Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1210/ Java: 64bit/jdk-9.0.1 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.search.facet.DistributedFacetSimpleRefinementLongTailTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.search.facet.DistributedFacetSimpleRefinementLongTailTest: 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.search.facet.DistributedFacetSimpleRefinementLongTailTest: 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) at __randomizedtesting.SeedInfo.seed([B52E943B300BC393]:0) FAILED: junit.framework.TestSuite.org.apache.solr.search.facet.DistributedFacetSimpleRefinementLongTailTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) at __randomizedtesting.SeedInfo.seed([B52E943B300BC393]:0) Build Log: [...truncated 12179 lines...] [junit4] Suite: org.apache.solr.search.facet.DistributedFacetSimpleRefinementLongTailTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/init-core-data-001 [junit4] 2> 427509 WARN (SUITE-DistributedFacetSimpleRefinementLongTailTest-seed#[B52E943B300BC393]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=15 numCloses=15 [junit4] 2> 427509 INFO (SUITE-DistributedFacetSimpleRefinementLongTailTest-seed#[B52E943B300BC393]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 427509 INFO (SUITE-DistributedFacetSimpleRefinementLongTailTest-seed#[B52E943B300BC393]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 427509 INFO (SUITE-DistributedFacetSimpleRefinementLongTailTest-seed#[B52E943B300BC393]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 427510 INFO (SUITE-DistributedFacetSimpleRefinementLongTailTest-seed#[B52E943B300BC393]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /cy_add/j [junit4] 2> 427635 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/control/cores/collection1 [junit4] 2> 427674 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 427681 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 427681 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 427681 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 427681 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e8230d{/cy_add/j,null,AVAILABLE} [junit4] 2> 427683 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5c603b21{SSL,[ssl, http/1.1]}{127.0.0.1:39877} [junit4] 2> 427683 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server Started @429323ms [junit4] 2> 427683 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/cy_add/j, hostPort=39877, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/control/cores} [junit4] 2> 427684 ERROR (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 427684 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 427684 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 427684 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 427684 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T22:26:57.437302Z [junit4] 2> 427684 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/control/solr.xml [junit4] 2> 427687 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 427687 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 427688 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 427714 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 427723 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 427723 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 427725 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/control/cores [junit4] 2> 427725 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 427726 INFO (coreLoadExecutor-1688-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 427726 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 427752 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 427773 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 427882 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 427887 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 427887 INFO (coreLoadExecutor-1688-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@23fdb93b [junit4] 2> 427887 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 427887 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 427888 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 427935 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 427935 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 427950 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 427950 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 427954 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1624550042, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 427955 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@496be25[collection1] main] [junit4] 2> 427955 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 428035 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 428133 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 428133 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 428133 INFO (coreLoadExecutor-1688-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590152100982030336 [junit4] 2> 428134 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 428134 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 428134 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 428134 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 428134 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 428134 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 428135 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 428135 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 428136 INFO (searcherExecutor-1689-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@496be25[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 428248 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 428250 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 428250 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 428250 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 428250 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 428250 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9026fe0{/cy_add/j,null,AVAILABLE} [junit4] 2> 428251 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@26c7208b{SSL,[ssl, http/1.1]}{127.0.0.1:46523} [junit4] 2> 428251 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server Started @429891ms [junit4] 2> 428251 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/cy_add/j, hostPort=46523, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard0/cores} [junit4] 2> 428251 ERROR (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 428251 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 428251 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 428251 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 428251 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T22:26:58.004855Z [junit4] 2> 428252 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard0/solr.xml [junit4] 2> 428254 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 428254 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 428254 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 428273 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 428282 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 428282 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 428283 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard0/cores [junit4] 2> 428283 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 428284 INFO (coreLoadExecutor-1699-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 428284 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 428304 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 428331 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 428407 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 428413 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 428413 INFO (coreLoadExecutor-1699-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@23fdb93b [junit4] 2> 428413 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 428413 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 428414 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 428462 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 428462 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 428462 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 428462 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 428463 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1624550042, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 428464 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f9076fb[collection1] main] [junit4] 2> 428464 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 428507 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 428605 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 428606 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 428606 INFO (coreLoadExecutor-1699-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590152101478006784 [junit4] 2> 428606 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 428606 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 428606 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 428607 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 428608 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 428608 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 428609 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 428609 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 428611 INFO (searcherExecutor-1700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7f9076fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 428764 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 428765 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 428766 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 428766 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 428766 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 428766 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1552c121{/cy_add/j,null,AVAILABLE} [junit4] 2> 428766 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@764fa6e2{SSL,[ssl, http/1.1]}{127.0.0.1:37025} [junit4] 2> 428766 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server Started @430406ms [junit4] 2> 428766 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/cy_add/j, hostPort=37025, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard1/cores} [junit4] 2> 428766 ERROR (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 428767 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 428767 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 428767 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 428767 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T22:26:58.520102Z [junit4] 2> 428767 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard1/solr.xml [junit4] 2> 428768 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 428769 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 428769 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 428786 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 428793 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 428793 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 428794 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard1/cores [junit4] 2> 428794 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 428794 INFO (coreLoadExecutor-1710-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 428795 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 428826 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 428844 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 428927 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 428944 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 428944 INFO (coreLoadExecutor-1710-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@23fdb93b [junit4] 2> 428944 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 428944 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard1/cores/collection1/data/] [junit4] 2> 428946 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 428988 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 428988 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 428988 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 428988 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 428989 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1624550042, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 428989 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@44a27101[collection1] main] [junit4] 2> 428990 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 429041 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 429157 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 429157 INFO (coreLoadExecutor-1710-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590152102055772160 [junit4] 2> 429158 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 429159 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 429159 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 429159 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 429178 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 429178 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 429179 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 429179 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 429182 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 429199 INFO (searcherExecutor-1711-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44a27101[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 429779 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard2/cores/collection1 [junit4] 2> 429781 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 429822 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 429822 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 429822 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 429823 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@225c3623{/cy_add/j,null,AVAILABLE} [junit4] 2> 429823 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@779dcf71{SSL,[ssl, http/1.1]}{127.0.0.1:34397} [junit4] 2> 429824 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.Server Started @431463ms [junit4] 2> 429824 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/cy_add/j, hostPort=34397, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard2/cores} [junit4] 2> 429824 ERROR (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 429824 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 429824 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 429824 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 429824 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T22:26:59.577615Z [junit4] 2> 429824 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard2/solr.xml [junit4] 2> 429827 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 429827 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 429829 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 429850 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 429860 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 429860 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@23fdb93b [junit4] 2> 429863 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard2/cores [junit4] 2> 429863 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 429863 INFO (coreLoadExecutor-1721-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 429864 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 429918 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 429939 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 430050 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 430059 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 430059 INFO (coreLoadExecutor-1721-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@23fdb93b [junit4] 2> 430059 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 430059 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.facet.DistributedFacetSimpleRefinementLongTailTest_B52E943B300BC393-001/tempDir-001/shard2/cores/collection1/data/] [junit4] 2> 430061 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 430121 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 430121 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 430122 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 430122 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 430123 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1624550042, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 430123 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@262078b0[collection1] main] [junit4] 2> 430124 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 430230 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 430347 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 430348 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 430348 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 430348 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 430348 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 430349 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 430349 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 430349 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 430350 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 430350 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 430351 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@262078b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 430351 INFO (coreLoadExecutor-1721-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590152103307771904 [junit4] 2> 430854 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 430869 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[1 (1590152103849885696)]} 0 1 [junit4] 2> 430884 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[2 (1590152103865614336)]} 0 1 [junit4] 2> 430897 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[3 (1590152103878197248)]} 0 2 [junit4] 2> 430898 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[4 (1590152103881342976)]} 0 0 [junit4] 2> 430899 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[5 (1590152103882391552)]} 0 0 [junit4] 2> 430900 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[6 (1590152103883440128)]} 0 0 [junit4] 2> 430901 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[7 (1590152103884488704)]} 0 0 [junit4] 2> 430903 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[8 (1590152103884488704)]} 0 1 [junit4] 2> 430904 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[9 (1590152103887634432)]} 0 0 [junit4] 2> 430904 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[10 (1590152103887634432)]} 0 0 [junit4] 2> 430905 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[11 (1590152103888683008)]} 0 0 [junit4] 2> 430906 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[12 (1590152103889731584)]} 0 0 [junit4] 2> 430906 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[13 (1590152103889731584)]} 0 0 [junit4] 2> 430907 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[14 (1590152103890780160)]} 0 0 [junit4] 2> 430908 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[15 (1590152103891828736)]} 0 0 [junit4] 2> 430909 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[16 (1590152103892877312)]} 0 0 [junit4] 2> 430910 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[17 (1590152103893925888)]} 0 0 [junit4] 2> 430911 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[18 (1590152103893925888)]} 0 0 [junit4] 2> 430911 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[19 (1590152103894974464)]} 0 0 [junit4] 2> 430928 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[20 (1590152103912800256)]} 0 0 [junit4] 2> 430929 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[21 (1590152103913848832)]} 0 0 [junit4] 2> 430930 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[22 (1590152103914897408)]} 0 0 [junit4] 2> 430931 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[23 (1590152103915945984)]} 0 0 [junit4] 2> 430931 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[24 (1590152103915945984)]} 0 0 [junit4] 2> 430932 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[25 (1590152103916994560)]} 0 0 [junit4] 2> 430933 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[26 (1590152103918043136)]} 0 0 [junit4] 2> 430934 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[27 (1590152103918043136)]} 0 0 [junit4] 2> 430934 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[28 (1590152103919091712)]} 0 0 [junit4] 2> 430935 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[29 (1590152103920140288)]} 0 0 [junit4] 2> 430936 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[30 (1590152103920140288)]} 0 0 [junit4] 2> 430936 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[31 (1590152103921188864)]} 0 0 [junit4] 2> 430937 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[32 (1590152103922237440)]} 0 0 [junit4] 2> 430938 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[33 (1590152103923286016)]} 0 0 [junit4] 2> 430939 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[34 (1590152103923286016)]} 0 0 [junit4] 2> 430939 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[35 (1590152103924334592)]} 0 0 [junit4] 2> 430940 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[36 (1590152103925383168)]} 0 0 [junit4] 2> 430941 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[37 (1590152103926431744)]} 0 0 [junit4] 2> 430942 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[38 (1590152103927480320)]} 0 0 [junit4] 2> 430943 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[39 (1590152103927480320)]} 0 0 [junit4] 2> 430943 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[40 (1590152103928528896)]} 0 0 [junit4] 2> 430944 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[41 (1590152103929577472)]} 0 0 [junit4] 2> 430945 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[42 (1590152103930626048)]} 0 0 [junit4] 2> 430946 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[43 (1590152103931674624)]} 0 0 [junit4] 2> 430947 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[44 (1590152103932723200)]} 0 0 [junit4] 2> 430948 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[45 (1590152103932723200)]} 0 0 [junit4] 2> 430948 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[46 (1590152103933771776)]} 0 0 [junit4] 2> 430949 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[47 (1590152103934820352)]} 0 0 [junit4] 2> 430950 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[48 (1590152103935868928)]} 0 0 [junit4] 2> 430950 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[49 (1590152103935868928)]} 0 0 [junit4] 2> 430951 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[50 (1590152103936917504)]} 0 0 [junit4] 2> 430952 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[51 (1590152103937966080)]} 0 0 [junit4] 2> 430953 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[52 (1590152103937966080)]} 0 0 [junit4] 2> 430953 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[53 (1590152103939014656)]} 0 0 [junit4] 2> 430954 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[54 (1590152103940063232)]} 0 0 [junit4] 2> 430955 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[55 (1590152103940063232)]} 0 0 [junit4] 2> 430955 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[56 (1590152103941111808)]} 0 0 [junit4] 2> 430956 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[57 (1590152103942160384)]} 0 0 [junit4] 2> 430957 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[58 (1590152103943208960)]} 0 0 [junit4] 2> 430958 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[59 (1590152103944257536)]} 0 0 [junit4] 2> 430958 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[60 (1590152103944257536)]} 0 0 [junit4] 2> 430959 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[61 (1590152103945306112)]} 0 0 [junit4] 2> 430960 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[62 (1590152103946354688)]} 0 0 [junit4] 2> 430960 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[63 (1590152103946354688)]} 0 0 [junit4] 2> 430961 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[64 (1590152103947403264)]} 0 0 [junit4] 2> 430962 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[65 (1590152103948451840)]} 0 0 [junit4] 2> 430962 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[66 (1590152103948451840)]} 0 0 [junit4] 2> 430963 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[67 (1590152103949500416)]} 0 0 [junit4] 2> 430964 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[68 (1590152103950548992)]} 0 0 [junit4] 2> 430964 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[69 (1590152103950548992)]} 0 0 [junit4] 2> 430965 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[70 (1590152103951597568)]} 0 0 [junit4] 2> 430966 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[71 (1590152103951597568)]} 0 0 [junit4] 2> 430966 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[72 (1590152103952646144)]} 0 0 [junit4] 2> 430967 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[73 (1590152103953694720)]} 0 0 [junit4] 2> 430968 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[74 (1590152103953694720)]} 0 0 [junit4] 2> 430968 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[75 (1590152103954743296)]} 0 0 [junit4] 2> 430969 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[76 (1590152103955791872)]} 0 0 [junit4] 2> 430970 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[77 (1590152103955791872)]} 0 0 [junit4] 2> 430970 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[78 (1590152103956840448)]} 0 0 [junit4] 2> 430971 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[79 (1590152103957889024)]} 0 0 [junit4] 2> 430971 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[80 (1590152103957889024)]} 0 0 [junit4] 2> 430972 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[81 (1590152103958937600)]} 0 0 [junit4] 2> 430973 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[82 (1590152103958937600)]} 0 0 [junit4] 2> 430973 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[83 (1590152103959986176)]} 0 0 [junit4] 2> 430974 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[84 (1590152103961034752)]} 0 0 [junit4] 2> 430975 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[85 (1590152103961034752)]} 0 0 [junit4] 2> 430975 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[86 (1590152103962083328)]} 0 0 [junit4] 2> 430977 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[87 (1590152103964180480)]} 0 0 [junit4] 2> 430996 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[88 (1590152103983054848)]} 0 0 [junit4] 2> 430996 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[89 (1590152103984103424)]} 0 0 [junit4] 2> 430997 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[90 (1590152103985152000)]} 0 0 [junit4] 2> 430997 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[91 (1590152103985152000)]} 0 0 [junit4] 2> 430998 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[92 (1590152103986200576)]} 0 0 [junit4] 2> 430999 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[93 (1590152103986200576)]} 0 0 [junit4] 2> 430999 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[94 (1590152103987249152)]} 0 0 [junit4] 2> 431000 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[95 (1590152103987249152)]} 0 0 [junit4] 2> 431000 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[96 (1590152103988297728)]} 0 0 [junit4] 2> 431000 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[97 (1590152103988297728)]} 0 0 [junit4] 2> 431001 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[98 (1590152103989346304)]} 0 0 [junit4] 2> 431001 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[99 (1590152103989346304)]} 0 0 [junit4] 2> 431003 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[100 (1590152103991443456)]} 0 0 [junit4] 2> 431005 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[101 (1590152103993540608)]} 0 0 [junit4] 2> 431006 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[102 (1590152103994589184)]} 0 0 [junit4] 2> 431008 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[103 (1590152103996686336)]} 0 0 [junit4] 2> 431009 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[104 (1590152103997734912)]} 0 0 [junit4] 2> 431010 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[105 (1590152103997734912)]} 0 0 [junit4] 2> 431010 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[106 (1590152103998783488)]} 0 0 [junit4] 2> 431011 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[107 (1590152103999832064)]} 0 0 [junit4] 2> 431012 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[108 (1590152104000880640)]} 0 0 [junit4] 2> 431013 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[109 (1590152104001929216)]} 0 0 [junit4] 2> 431014 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[110 (1590152104002977792)]} 0 0 [junit4] 2> 431015 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[111 (1590152104004026368)]} 0 0 [junit4] 2> 431015 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[112 (1590152104004026368)]} 0 0 [junit4] 2> 431016 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[113 (1590152104005074944)]} 0 0 [junit4] 2> 431017 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[114 (1590152104005074944)]} 0 0 [junit4] 2> 431017 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[115 (1590152104006123520)]} 0 0 [junit4] 2> 431018 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[116 (1590152104006123520)]} 0 0 [junit4] 2> 431018 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[117 (1590152104007172096)]} 0 0 [junit4] 2> 431019 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[118 (1590152104008220672)]} 0 0 [junit4] 2> 431019 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[119 (1590152104008220672)]} 0 0 [junit4] 2> 431020 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[120 (1590152104009269248)]} 0 0 [junit4] 2> 431021 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[121 (1590152104009269248)]} 0 0 [junit4] 2> 431021 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[122 (1590152104010317824)]} 0 0 [junit4] 2> 431022 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[123 (1590152104011366400)]} 0 0 [junit4] 2> 431022 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[124 (1590152104011366400)]} 0 0 [junit4] 2> 431023 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[125 (1590152104011366400)]} 0 0 [junit4] 2> 431023 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[126 (1590152104012414976)]} 0 0 [junit4] 2> 431023 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[127 (1590152104012414976)]} 0 0 [junit4] 2> 431024 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[128 (1590152104013463552)]} 0 0 [junit4] 2> 431024 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[129 (1590152104013463552)]} 0 0 [junit4] 2> 431025 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[130 (1590152104014512128)]} 0 0 [junit4] 2> 431025 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[131 (1590152104014512128)]} 0 0 [junit4] 2> 431026 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[132 (1590152104015560704)]} 0 0 [junit4] 2> 431026 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[133 (1590152104015560704)]} 0 0 [junit4] 2> 431027 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[134 (1590152104015560704)]} 0 0 [junit4] 2> 431027 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[135 (1590152104016609280)]} 0 0 [junit4] 2> 431027 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[136 (1590152104016609280)]} 0 0 [junit4] 2> 431028 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[137 (1590152104017657856)]} 0 0 [junit4] 2> 431028 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[138 (1590152104017657856)]} 0 0 [junit4] 2> 431029 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[139 (1590152104018706432)]} 0 0 [junit4] 2> 431029 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[140 (1590152104018706432)]} 0 0 [junit4] 2> 431030 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[141 (1590152104018706432)]} 0 0 [junit4] 2> 431030 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[142 (1590152104019755008)]} 0 0 [junit4] 2> 431030 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[143 (1590152104019755008)]} 0 0 [junit4] 2> 431031 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[144 (1590152104020803584)]} 0 0 [junit4] 2> 431031 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[145 (1590152104020803584)]} 0 0 [junit4] 2> 431032 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[146 (1590152104021852160)]} 0 0 [junit4] 2> 431033 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[147 (1590152104022900736)]} 0 0 [junit4] 2> 431033 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[148 (1590152104022900736)]} 0 0 [junit4] 2> 431034 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[149 (1590152104023949312)]} 0 0 [junit4] 2> 431035 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[150 (1590152104024997888)]} 0 0 [junit4] 2> 431036 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[151 (1590152104026046464)]} 0 0 [junit4] 2> 431037 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[152 (1590152104027095040)]} 0 0 [junit4] 2> 431038 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[153 (1590152104028143616)]} 0 0 [junit4] 2> 431038 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[154 (1590152104028143616)]} 0 0 [junit4] 2> 431039 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[155 (1590152104029192192)]} 0 0 [junit4] 2> 431040 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[156 (1590152104029192192)]} 0 0 [junit4] 2> 431040 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[157 (1590152104030240768)]} 0 0 [junit4] 2> 431041 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[158 (1590152104031289344)]} 0 0 [junit4] 2> 431042 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[159 (1590152104032337920)]} 0 0 [junit4] 2> 431043 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[160 (1590152104032337920)]} 0 0 [junit4] 2> 431043 INFO (qtp338841225-7029) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[161 (1590152104033386496)]} 0 0 [junit4] 2> 431044 INFO (qtp1527732343-7046) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[162 (1590152104034435072)]} 0 0 [junit4] 2> 431045 INFO (qtp796745768-7012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[163 (1590152104035483648)]} 0 0 [junit4] 2> 431045 INFO (qtp338841225-7031) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[164 (1590152104035483648)]} 0 0 [junit4] 2> 431046 INFO (qtp1527732343-7048) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[165 (1590152104036532224)]} 0 0 [junit4] 2> 431046 INFO (qtp796745768-7014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/cy_add/j path=/update params={wt=javabin&version=2}{add=[166 (1590152104036532224)]} 0 0 [junit4] 2> 431047 INFO (qtp338841225-70 [...truncated too long message...] unit4] 2> 462721 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 462721 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10f65887: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2017b6c3 [junit4] 2> 462722 INFO (coreCloseExecutor-1733-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@478d5f47 [junit4] 2> 462722 INFO (coreCloseExecutor-1733-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1200447303 [junit4] 2> 462722 INFO (coreCloseExecutor-1733-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@74868159: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1b4a3db5 [junit4] 2> 462737 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@225c3623{/cy_add/j,null,UNAVAILABLE} [junit4] 2> 462737 INFO (TEST-DistributedFacetSimpleRefinementLongTailTest.test-seed#[B52E943B300BC393]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 462739 INFO (SUITE-DistributedFacetSimpleRefinementLongTailTest-seed#[B52E943B300BC393]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Jan 20, 2018 10:27:32 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Jan 20, 2018 10:27:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.search.facet.DistributedFacetSimpleRefinementLongTailTest: [junit4] 2> 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] 2> Jan 20, 2018 10:27:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] [junit4] 2> Jan 20, 2018 10:27:55 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {bar_s=FST50, multiDefault=PostingsFormat(name=Memory), foo_s=PostingsFormat(name=Memory), id=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Direct), stat_i1=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Memory), intDvoDefault=DocValuesFormat(name=Lucene70), range_facet_l=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1710, maxMBSortInHeap=7.930893815639342, sim=RandomSimilarity(queryNorm=true): {}, locale=en-MU, timezone=Atlantic/Faeroe [junit4] 2> NOTE: Linux 4.13.0-26-generic amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=8,threads=2,free=166573272,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestConfigOverlay, TestRawResponseWriter, TestSlowCompositeReaderWrapper, CoreAdminRequestStatusTest, TestUniqueKeyFieldResource, DocValuesMissingTest, CheckHdfsIndexTest, TestLRUStatsCache, CoreSorterTest, TestPHPSerializedResponseWriter, HLLSerializationTest, SynonymTokenizerTest, JavabinLoaderTest, TestSolrConfigHandlerConcurrent, CloudExitableDirectoryReaderTest, TestWriterPerf, TestFieldCollectionResource, TestTrieFacet, TestNoOpRegenerator, CdcrBidirectionalTest, TestRemoteStreaming, OutputWriterTest, TestHdfsUpdateLog, TestDistributedSearch, DocExpirationUpdateProcessorFactoryTest, TestDistribIDF, TestSchemaSimilarityResource, TestLuceneIndexBackCompat, TestSimDistributedQueue, SystemLogListenerTest, HdfsWriteToMultipleCollectionsTest, ChaosMonkeyNothingIsSafeTest, TestCustomDocTransformer, TestManagedSynonymGraphFilterFactory, TestConfigReload, DistributedFacetSimpleRefinementLongTailTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedFacetSimpleRefinementLongTailTest -Dtests.seed=B52E943B300BC393 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-MU -Dtests.timezone=Atlantic/Faeroe -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | DistributedFacetSimpleRefinementLongTailTest (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.search.facet.DistributedFacetSimpleRefinementLongTailTest: [junit4] > 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] [junit4] > at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] > at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] > at __randomizedtesting.SeedInfo.seed([B52E943B300BC393]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=7009, name=qtp796745768-7009, state=TIMED_WAITING, group=TGRP-DistributedFacetSimpleRefinementLongTailTest] [junit4] > at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] > at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] > at __randomizedtesting.SeedInfo.seed([B52E943B300BC393]:0) [junit4] Completed [130/767 (1!)] on J0 in 58.51s, 1 test, 2 errors <<< FAILURES! [...truncated 42935 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
