Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1347/
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.update.TestHdfsUpdateLog
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([71DD60B0BD0D738A]:0)
Build Log:
[...truncated 12796 lines...]
[junit4] JVM J1: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J1-20170706_124322_0854238295808738570171.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/heapdumps/java_pid6160.hprof
...
[junit4] Heap dump file created [660046704 bytes in 14.766 secs]
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J1: stderr was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J1-20170706_124322_085772138592570646870.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] WARN: Unhandled exception in event serialization. ->
java.lang.OutOfMemoryError: Java heap space
[junit4] <<< JVM J1: EOF ----
[...truncated 835 lines...]
[junit4] Suite: org.apache.solr.update.TestHdfsUpdateLog
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestHdfsUpdateLog_71DD60B0BD0D738A-001/init-core-data-001
[junit4] 2> 956167 WARN
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 956167 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 956169 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 956562 WARN
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your
platform... using builtin-java classes where applicable
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 957225 WARN
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 957412 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log Logging
to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via
org.mortbay.log.Slf4jLog
[junit4] 2> 957430 WARN
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 957500 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 957546 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_59506_hdfs____f3fkw1/webapp
[junit4] 2> 958299 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:59506
[junit4] 2> 959098 WARN
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 959103 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 959118 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_51001_datanode____m83wxo/webapp
[junit4] 2> 959498 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51001
[junit4] 2> 960284 WARN
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 960286 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 960306 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_51152_datanode____hzyhl/webapp
[junit4] 2> 960862 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51152
[junit4] 2> 961384 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46 node
DatanodeRegistration(127.0.0.1:49950,
datanodeUuid=7c56d65d-5c5e-4c4e-b6ef-1172504a9545, infoPort=53005,
infoSecurePort=0, ipcPort=47687,
storageInfo=lv=-56;cid=testClusterID;nsid=1942765487;c=0), blocks: 0,
hasStaleStorage: true, processing time: 2 msecs
[junit4] 2> 961384 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-808389ac-305f-4349-bd5b-26eb5df476d5 node
DatanodeRegistration(127.0.0.1:49950,
datanodeUuid=7c56d65d-5c5e-4c4e-b6ef-1172504a9545, infoPort=53005,
infoSecurePort=0, ipcPort=47687,
storageInfo=lv=-56;cid=testClusterID;nsid=1942765487;c=0), blocks: 0,
hasStaleStorage: false, processing time: 1 msecs
[junit4] 2> 961386 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-d1c676bf-e31b-482f-bf21-cabf5e38a453 node
DatanodeRegistration(127.0.0.1:37760,
datanodeUuid=d0aa286b-d7cb-4288-a8a2-c667f5e9c420, infoPort=57053,
infoSecurePort=0, ipcPort=42785,
storageInfo=lv=-56;cid=testClusterID;nsid=1942765487;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 961386 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-b6f9d0de-9965-431f-806e-b36d32210db3 node
DatanodeRegistration(127.0.0.1:37760,
datanodeUuid=d0aa286b-d7cb-4288-a8a2-c667f5e9c420, infoPort=57053,
infoSecurePort=0, ipcPort=42785,
storageInfo=lv=-56;cid=testClusterID;nsid=1942765487;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 962371 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 962372 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 962408 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 962427 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 962514 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 962560 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42fb1a67
[junit4] 2> 962566 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42fb1a67
[junit4] 2> 962566 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42fb1a67
[junit4] 2> 962568 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 962598 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 962623 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 962718 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 962729 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 962730 INFO (coreLoadExecutor-2769-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@42fb1a67
[junit4] 2> 962752 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:48967/solr_hdfs_home
[junit4] 2> 962752 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication
disabled
[junit4] 2> 962752 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 962752 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1],
dataDir=[hdfs://localhost:48967/solr_hdfs_home/collection1//x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestHdfsUpdateLog_71DD60B0BD0D738A-001/init-core-data-001/]
[junit4] 2> 962754 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:48967/solr_hdfs_home/collection1//x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestHdfsUpdateLog_71DD60B0BD0D738A-001/init-core-data-001/snapshot_metadata
[junit4] 2> 962779 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1]
with direct memory allocation set to [true]
[junit4] 2> 962779 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage,
slab size of [8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 962779 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory Creating new global HDFS BlockCache
[junit4] 2> 963394 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 963402 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:48967/solr_hdfs_home/collection1//x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestHdfsUpdateLog_71DD60B0BD0D738A-001/init-core-data-001
[junit4] 2> 963435 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:48967/solr_hdfs_home/collection1//x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestHdfsUpdateLog_71DD60B0BD0D738A-001/init-core-data-001/index
[junit4] 2> 963444 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1]
with direct memory allocation set to [true]
[junit4] 2> 963444 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage,
slab size of [8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 963452 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 963453 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=15, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=87.62109375,
floorSegmentMB=1.1240234375, forceMergeDeletesPctAllowed=28.427669690135527,
segmentsPerTier=3.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 963697 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741825_1001{UCState=COMMITTED, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW]]}
size 72
[junit4] 2> 963697 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741825_1001 size 72
[junit4] 2> 964122 WARN (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 964182 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 964182 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=hdfs://localhost:48967/solr/shard1 defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 964182 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog:
tlogDfsReplication=2
[junit4] 2> 964201 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 964201 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 964204 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@4f6644cf
[junit4] 2> 964295 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@6ec3e484[collection1] main]
[junit4] 2> 964295 WARN (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 964299 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 964301 INFO
(searcherExecutor-2770-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6ec3e484[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 964302 INFO (coreLoadExecutor-2769-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1572178200288559104
[junit4] 2> 964305 INFO
(SUITE-TestHdfsUpdateLog-seed#[71DD60B0BD0D738A]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 964342 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testFSThreadSafety
[junit4] 2> 964343 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 964346 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@63c10078[collection1] realtime]
[junit4] 2> 964346 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
(-9223372036854775807)} 0 3
[junit4] 2> 964347 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1572178200335745024,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 964347 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@31b36a01
commitCommandVersion:1572178200335745024
[junit4] 2> 964385 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 964385 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 964396 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3213eeb0[collection1] main]
[junit4] 2> 964396 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 964399 INFO (searcherExecutor-2770-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3213eeb0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 964399 INFO
(TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[71DD60B0BD0D738A]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 51
[junit4] 2> 964457 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[0 (1572178200398659584)]} 0 50
[junit4] 2> 964472 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[1 (1572178200463671296)]} 0 3
[junit4] 2> 964485 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[2 (1572178200478351360)]} 0 2
[junit4] 2> 964497 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[3 (1572178200491982848)]} 0 1
[junit4] 2> 964511 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[4 (1572178200506662912)]} 0 1
[junit4] 2> 964523 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[5 (1572178200519245824)]} 0 1
[junit4] 2> 964535 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 964535 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@31b36a01 commitCommandVersion:0
[junit4] 2> 964535 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[6 (1572178200531828736)]} 0 1
[junit4] 2> 964607 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 964608 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 964655 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[7 (1572178200544411648)]} 0 108
[junit4] 2> 964675 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8 (1572178200675483648)]} 0 5
[junit4] 2> 964685 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 964686 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 964693 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[9 (1572178200691212288)]} 0 6
[junit4] 2> 964707 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[10 (1572178200709038080)]} 0 3
[junit4] 2> 964720 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[11 (1572178200724766720)]} 0 2
[junit4] 2> 964728 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 964730 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 964733 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[12 (1572178200738398208)]} 0 1
[junit4] 2> 964748 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[13 (1572178200750981120)]} 0 4
[junit4] 2> 964758 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 964760 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 964762 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[14 (1572178200767758336)]} 0 3
[junit4] 2> 964779 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[15 (1572178200782438400)]} 0 6
[junit4] 2> 964785 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741825_1001 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 964794 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[16 (1572178200800264192)]} 0 4
[junit4] 2> 964810 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[17 (1572178200815992832)]} 0 4
[junit4] 2> 964822 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[18 (1572178200831721472)]} 0 2
[junit4] 2> 964830 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@386d5a6c[collection1] realtime]
[junit4] 2> 964838 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW]]}
size 75
[junit4] 2> 964839 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW]]}
size 75
[junit4] 2> 964844 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 964845 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 964845 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@31b36a01 commitCommandVersion:0
[junit4] 2> 964846 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[19 (1572178200845352960)]} 0 12
[junit4] 2> 964906 INFO
(org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@7dfc4197)
[ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:37760 to delete
[blk_1073741825_1001]
[junit4] 2> 964916 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 964916 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[20 (1572178200869470208)]} 0 60
[junit4] 2> 964917 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 964929 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[21 (1572178200943919104)]} 0 2
[junit4] 2> 964941 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[22 (1572178200957550592)]} 0 1
[junit4] 2> 964954 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[23 (1572178200970133504)]} 0 2
[junit4] 2> 964965 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 964966 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[24 (1572178200983764992)]} 0 1
[junit4] 2> 964968 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741835_1011 size 882
[junit4] 2> 964979 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[25 (1572178200996347904)]} 0 1
[junit4] 2> 964992 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[26 (1572178201009979392)]} 0 1
[junit4] 2> 965015 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[27 (1572178201022562304)]} 0 12
[junit4] 2> 965022 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965023 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965029 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[28 (1572178201046679552)]} 0 3
[junit4] 2> 965041 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[29 (1572178201062408192)]} 0 1
[junit4] 2> 965053 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[30 (1572178201074991104)]} 0 1
[junit4] 2> 965067 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965068 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965069 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[31 (1572178201087574016)]} 0 4
[junit4] 2> 965080 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[32 (1572178201103302656)]} 0 1
[junit4] 2> 965092 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[33 (1572178201115885568)]} 0 1
[junit4] 2> 965104 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[34 (1572178201128468480)]} 0 1
[junit4] 2> 965116 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[35 (1572178201141051392)]} 0 1
[junit4] 2> 965130 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[36 (1572178201155731456)]} 0 1
[junit4] 2> 965142 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741838_1014{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965144 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[37 (1572178201168314368)]} 0 2
[junit4] 2> 965144 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741838_1014 size 384
[junit4] 2> 965156 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[38 (1572178201181945856)]} 0 1
[junit4] 2> 965168 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[39 (1572178201194528768)]} 0 1
[junit4] 2> 965180 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[40 (1572178201207111680)]} 0 1
[junit4] 2> 965191 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[41 (1572178201219694592)]} 0 1
[junit4] 2> 965203 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[42 (1572178201232277504)]} 0 1
[junit4] 2> 965215 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[43 (1572178201244860416)]} 0 1
[junit4] 2> 965227 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[44 (1572178201257443328)]} 0 1
[junit4] 2> 965229 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741839_1015{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965230 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741839_1015{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965239 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[45 (1572178201270026240)]} 0 1
[junit4] 2> 965252 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[46 (1572178201281560576)]} 0 2
[junit4] 2> 965264 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[47 (1572178201296240640)]} 0 1
[junit4] 2> 965275 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[48 (1572178201307774976)]} 0 1
[junit4] 2> 965299 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[49 (1572178201320357888)]} 0 13
[junit4] 2> 965310 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741840_1016{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 0
[junit4] 2> 965312 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741840_1016 size 795
[junit4] 2> 965321 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[50 (1572178201352863744)]} 0 3
[junit4] 2> 965346 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[51 (1572178201368592384)]} 0 14
[junit4] 2> 965359 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[52 (1572178201395855360)]} 0 1
[junit4] 2> 965371 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[53 (1572178201408438272)]} 0 1
[junit4] 2> 965372 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@21ebc70c[collection1] realtime]
[junit4] 2> 965383 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW]]}
size 75
[junit4] 2> 965384 INFO (IPC Server handler 0 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW]]}
size 75
[junit4] 2> 965385 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 965385 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 965385 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@31b36a01 commitCommandVersion:0
[junit4] 2> 965386 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[54 (1572178201421021184)]} 0 4
[junit4] 2> 965456 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741841_1017{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965457 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741841_1017{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965485 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[55 (1572178201436749824)]} 0 88
[junit4] 2> 965497 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[56 (1572178201540558848)]} 0 0
[junit4] 2> 965499 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741843_1019{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965501 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741843_1019{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965509 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[57 (1572178201553141760)]} 0 1
[junit4] 2> 965523 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[58 (1572178201567821824)]} 0 1
[junit4] 2> 965524 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741844_1020{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965530 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741844_1020{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965536 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[59 (1572178201580404736)]} 0 1
[junit4] 2> 965547 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[60 (1572178201592987648)]} 0 1
[junit4] 2> 965560 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[61 (1572178201605570560)]} 0 2
[junit4] 2> 965566 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741845_1021{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965567 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741845_1021 size 221
[junit4] 2> 965574 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[62 (1572178201618153472)]} 0 3
[junit4] 2> 965586 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[63 (1572178201633882112)]} 0 1
[junit4] 2> 965601 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[64 (1572178201649610752)]} 0 1
[junit4] 2> 965604 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741846_1022{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965605 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741846_1022{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965613 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[65 (1572178201662193664)]} 0 1
[junit4] 2> 965630 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741847_1023{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965630 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[66 (1572178201677922304)]} 0 3
[junit4] 2> 965631 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741847_1023{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965643 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[67 (1572178201692602368)]} 0 2
[junit4] 2> 965656 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[68 (1572178201707282432)]} 0 1
[junit4] 2> 965661 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741848_1024{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965661 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741848_1024{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965668 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[69 (1572178201719865344)]} 0 1
[junit4] 2> 965669 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741828_1004 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 965684 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[70 (1572178201731399680)]} 0 6
[junit4] 2> 965696 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[71 (1572178201749225472)]} 0 1
[junit4] 2> 965698 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2fdbc252[collection1] realtime]
[junit4] 2> 965710 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 76
[junit4] 2> 965710 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 76
[junit4] 2> 965711 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 965711 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 965711 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@31b36a01 commitCommandVersion:0
[junit4] 2> 965712 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[72 (1572178201761808384)]} 0 5
[junit4] 2> 965747 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741849_1025{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965747 INFO (IPC Server handler 0 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741849_1025{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965774 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[73 (1572178201778585600)]} 0 51
[junit4] 2> 965778 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741851_1027{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965783 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741851_1027 size 221
[junit4] 2> 965786 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[74 (1572178201843597312)]} 0 1
[junit4] 2> 965802 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[75 (1572178201856180224)]} 0 5
[junit4] 2> 965814 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[76 (1572178201871908864)]} 0 1
[junit4] 2> 965825 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[77 (1572178201884491776)]} 0 1
[junit4] 2> 965830 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741852_1028{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 0
[junit4] 2> 965830 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741852_1028{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965837 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[78 (1572178201897074688)]} 0 1
[junit4] 2> 965849 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[79 (1572178201909657600)]} 0 1
[junit4] 2> 965861 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741853_1029{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965862 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[80 (1572178201922240512)]} 0 1
[junit4] 2> 965862 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741853_1029{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965875 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741854_1030{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965875 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[81 (1572178201936920576)]} 0 1
[junit4] 2> 965876 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741854_1030{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 965887 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[82 (1572178201949503488)]} 0 1
[junit4] 2> 965903 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[83 (1572178201962086400)]} 0 4
[junit4] 2> 965915 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[84 (1572178201977815040)]} 0 1
[junit4] 2> 965918 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741855_1031{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965919 INFO (IPC Server handler 0 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741855_1031{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 965934 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[85 (1572178201991446528)]} 0 7
[junit4] 2> 965945 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[86 (1572178202010320896)]} 0 1
[junit4] 2> 965957 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[87 (1572178202022903808)]} 0 1
[junit4] 2> 965971 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[88 (1572178202035486720)]} 0 3
[junit4] 2> 965974 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741856_1032{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965975 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741856_1032{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 965986 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[89 (1572178202051215360)]} 0 3
[junit4] 2> 966000 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[90 (1572178202066944000)]} 0 2
[junit4] 2> 966001 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741857_1033{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966006 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741857_1033 size 384
[junit4] 2> 966012 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[91 (1572178202080575488)]} 0 1
[junit4] 2> 966028 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[92 (1572178202096304128)]} 0 1
[junit4] 2> 966033 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741858_1034{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966034 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741858_1034 size 421
[junit4] 2> 966039 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[93 (1572178202108887040)]} 0 0
[junit4] 2> 966051 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[94 (1572178202121469952)]} 0 1
[junit4] 2> 966062 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[95 (1572178202133004288)]} 0 1
[junit4] 2> 966074 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[96 (1572178202145587200)]} 0 0
[junit4] 2> 966079 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741859_1035{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966081 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741859_1035{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966086 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[97 (1572178202158170112)]} 0 1
[junit4] 2> 966086 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741833_1009 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 966101 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[98 (1572178202169704448)]} 0 4
[junit4] 2> 966110 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2f24f5ef[collection1] realtime]
[junit4] 2> 966131 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741842_1018{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 76
[junit4] 2> 966131 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741842_1018{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 76
[junit4] 2> 966132 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 966132 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 966132 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@31b36a01 commitCommandVersion:0
[junit4] 2> 966178 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741861_1037{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966180 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741861_1037{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966180 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[99 (1572178202192773120)]} 0 62
[junit4] 2> 966192 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[100 (1572178202269319168)]} 0 1
[junit4] 2> 966204 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[101 (1572178202281902080)]} 0 0
[junit4] 2> 966227 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[102 (1572178202306019328)]} 0 1
[junit4] 2> 966231 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741862_1038{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966235 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741862_1038{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966241 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[103 (1572178202318602240)]} 0 3
[junit4] 2> 966255 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[104 (1572178202335379456)]} 0 1
[junit4] 2> 966262 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741863_1039{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966264 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741863_1039{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966266 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[105 (1572178202346913792)]} 0 1
[junit4] 2> 966278 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[106 (1572178202359496704)]} 0 1
[junit4] 2> 966290 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[107 (1572178202372079616)]} 0 1
[junit4] 2> 966293 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741864_1040{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966293 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741864_1040{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966302 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[108 (1572178202384662528)]} 0 1
[junit4] 2> 966313 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[109 (1572178202396196864)]} 0 1
[junit4] 2> 966314 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741865_1041{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW]]}
size 0
[junit4] 2> 966314 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741865_1041{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966325 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[110 (1572178202408779776)]} 0 1
[junit4] 2> 966337 INFO (IPC Server handler 0 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741866_1042{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966337 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[111 (1572178202421362688)]} 0 1
[junit4] 2> 966339 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741866_1042{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966349 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[112 (1572178202433945600)]} 0 1
[junit4] 2> 966356 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741867_1043{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966356 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741867_1043{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966361 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[113 (1572178202446528512)]} 0 1
[junit4] 2> 966362 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741841_1017 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 966364 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741831_1007 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 966366 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741843_1019 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 966368 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741836_1012 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 966372 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[114 (1572178202458062848)]} 0 1
[junit4] 2> 966379 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741837_1013 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966381 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741838_1014 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966385 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[115 (1572178202470645760)]} 0 2
[junit4] 2> 966385 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741832_1008 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966389 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741840_1016 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 966391 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741845_1021 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966393 INFO (IPC Server handler 7 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741835_1011 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966395 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741830_1006 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966396 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[116 (1572178202483228672)]} 0 0
[junit4] 2> 966397 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741839_1015 127.0.0.1:37760
127.0.0.1:49950
[junit4] 2> 966405 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741846_1022 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966408 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[117 (1572178202495811584)]} 0 1
[junit4] 2> 966442 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[118 (1572178202507345920)]} 0 23
[junit4] 2> 966443 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2f05239a[collection1] realtime]
[junit4] 2> 966448 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741850_1026{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 76
[junit4] 2> 966449 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741850_1026{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 76
[junit4] 2> 966452 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741827_1003 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966453 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addToInvalidates: blk_1073741829_1005 127.0.0.1:49950
127.0.0.1:37760
[junit4] 2> 966453 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 966453 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 966453 INFO (commitScheduler-2773-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@31b36a01 commitCommandVersion:0
[junit4] 2> 966471 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[119 (1572178202542997504)]} 0 18
[junit4] 2> 966474 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741869_1045{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966474 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741869_1045{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966482 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[120 (1572178202573406208)]} 0 0
[junit4] 2> 966485 INFO (IPC Server handler 0 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741870_1046{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966486 INFO (IPC Server handler 1 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741870_1046{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966494 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[121 (1572178202585989120)]} 0 1
[junit4] 2> 966505 INFO (IPC Server handler 3 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741871_1047{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966506 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[122 (1572178202598572032)]} 0 1
[junit4] 2> 966511 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741871_1047 size 1008
[junit4] 2> 966517 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[123 (1572178202610106368)]} 0 1
[junit4] 2> 966525 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741872_1048{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED]]}
size 0
[junit4] 2> 966525 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741872_1048{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|FINALIZED],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966534 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[124 (1572178202623737856)]} 0 4
[junit4] 2> 966545 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[125 (1572178202639466496)]} 0 1
[junit4] 2> 966548 INFO (IPC Server handler 6 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741873_1049{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966554 INFO (IPC Server handler 0 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741873_1049 size 421
[junit4] 2> 966557 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[126 (1572178202652049408)]} 0 0
[junit4] 2> 966567 INFO (IPC Server handler 8 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741874_1050{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966569 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[127 (1572178202663583744)]} 0 1
[junit4] 2> 966569 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741874_1050{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966580 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[128 (1572178202676166656)]} 0 1
[junit4] 2> 966586 INFO (IPC Server handler 0 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741875_1051{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4] 2> 966587 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741875_1051 size 221
[junit4] 2> 966591 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[129 (1572178202688749568)]} 0 0
[junit4] 2> 966603 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[130 (1572178202700283904)]} 0 1
[junit4] 2> 966614 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[131 (1572178202712866816)]} 0 0
[junit4] 2> 966615 INFO (IPC Server handler 5 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741876_1052{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 0
[junit4] 2> 966616 INFO (IPC Server handler 4 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741876_1052{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW],
ReplicaUC[[DISK]DS-d1c676bf-e31b-482f-bf21-cabf5e38a453:NORMAL:127.0.0.1:37760|RBW]]}
size 0
[junit4] 2> 966626 INFO (Thread-49591) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[132 (1572178202724401152)]} 0 1
[junit4] 2> 966630 INFO (IPC Server handler 2 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37760 is
added to blk_1073741877_1053{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-808389ac-305f-4349-bd5b-26eb5df476d5:NORMAL:127.0.0.1:49950|RBW]]}
size 0
[junit4] 2> 966630 INFO (IPC Server handler 9 on 48967) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49950 is
added to blk_1073741877_1053{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b6f9d0de-9965-431f-806e-b36d32210db3:NORMAL:127.0.0.1:37760|RBW],
ReplicaUC[[DISK]DS-bb622c97-8f7c-4beb-9ddf-dafbf719dc46:NORMAL:127.0.0.1:49950|FINALIZED]]}
size 0
[junit4
[...truncated too long message...]
-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/byte-buddy-1.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/hadoop-common-2.7.2-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/hadoop-hdfs-2.7.2-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/hadoop-minikdc-2.7.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/mockito-core-2.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/netty-all-4.0.36.Final.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/test-lib/objenesis-2.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/analysis/icu/lucene-analyzers-icu-8.0.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/analysis-extras/lib/icu4j-59.1.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_131/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.5.2.jar
com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J1-20170706_124322_0851540403983220184781.events
@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J1-20170706_124322_0854203761868672364522.suites
-stdin
[junit4] ERROR: JVM J1 ended with an exception: Quit event not received from
the forked process? This may indicate JVM crash or runner bugs.
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1540)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] at java.lang.Thread.run(Thread.java:748)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:818:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:754:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build.xml:267:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/common-build.xml:549:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/common-build.xml:1455:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/common-build.xml:985:
At least one slave process threw an exception, first: Quit event not received
from the forked process? This may indicate JVM crash or runner bugs.
Total time: 327 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No artifacts from Lucene-Solr-NightlyTests-master #1346 to compare, so
performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
