See <https://builds.apache.org/job/Phoenix-master/1467/changes>
Changes:
[elserj] PHOENIX-3426 Fix the broken QueryServerBasicsIT
------------------------------------------
[...truncated 473279 lines...]
2016-11-01 00:01:11,930 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver with path
null and priority 805306366
2016-11-01 00:01:11,931 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver
from HTD of T000368 successfully.
2016-11-01 00:01:11,931 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.ScanRegionObserver with path null and
priority 805306366
2016-11-01 00:01:11,931 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.ScanRegionObserver from HTD of
T000368 successfully.
2016-11-01 00:01:11,932 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.regionserver.MetricsRegionSourceImpl(70): Creating new
MetricsRegionSourceImpl for table T000368 d3a6da933d7a9fabfb44dc4261002efe
2016-11-01 00:01:11,932 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.regionserver.HRegion(735): Instantiated
T000368,,1477958467600.d3a6da933d7a9fabfb44dc4261002efe.
2016-11-01 00:01:11,940 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:46798-0]
org.apache.hadoop.hbase.regionserver.HRegionFileSystem(381): Committing store
file
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/I_T000056/d97fa07371ab67701787d5a87a056c03/.tmp/b9b6c5df9f724c65aa4cd27d4210fd7b
as
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/I_T000056/d97fa07371ab67701787d5a87a056c03/0/b9b6c5df9f724c65aa4cd27d4210fd7b
2016-11-01 00:01:11,942 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.hadoop.hbase.wal.WALSplitter(729): Wrote region
seqId=hdfs://localhost:46171/user/jenkins/test-data/4a3d2459-391d-4788-8a7e-bb4d1c8efb65/data/default/S_T000429.T000431/ebad129d27f7190584bd43588ed17c00/recovered.edits/2.seqid
to file, newSeqId=2, maxSeqId=0
2016-11-01 00:01:11,941 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.zookeeper.ZKAssign(880):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Transitioned node f640c428847a963593b971cd6dfe1642 from M_ZK_REGION_OFFLINE to
RS_ZK_REGION_OPENING
2016-11-01 00:01:11,943 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.hadoop.hbase.regionserver.HRegion(870): Onlined
ebad129d27f7190584bd43588ed17c00; next sequenceid=2
2016-11-01 00:01:11,941 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.HRegion(6402): Opening region: {ENCODED =>
f640c428847a963593b971cd6dfe1642, NAME =>
'T000372,,1477958467603.f640c428847a963593b971cd6dfe1642.', STARTKEY => '',
ENDKEY => ''}
2016-11-01 00:01:11,943 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.phoenix.hbase.index.Indexer(520): Found some outstanding index
updates that didn't succeed during WAL replay - attempting to replay now.
2016-11-01 00:01:11,942 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:39781-0x1581d2dca480000, quorum=localhost:51252, baseZNode=/hbase
Received ZooKeeper Event, type=NodeDataChanged, state=SyncConnected,
path=/hbase/region-in-transition/f640c428847a963593b971cd6dfe1642
2016-11-01 00:01:11,942 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:39781-0x1581d2dca480000, quorum=localhost:51252, baseZNode=/hbase
Received ZooKeeper Event, type=NodeDataChanged, state=SyncConnected,
path=/hbase/region-in-transition/5895aa952871281f7c8b7df9bb6ca524
2016-11-01 00:01:11,942 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.hbase.index.Indexer with path null and priority
805306366
2016-11-01 00:01:11,943 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.zookeeper.ZKAssign(880):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Transitioned node 5895aa952871281f7c8b7df9bb6ca524 from M_ZK_REGION_OFFLINE to
RS_ZK_REGION_OPENING
2016-11-01 00:01:11,943 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.HRegion(6402): Opening region: {ENCODED =>
5895aa952871281f7c8b7df9bb6ca524, NAME =>
'T000376,,1477958467588.5895aa952871281f7c8b7df9bb6ca524.', STARTKEY => '',
ENDKEY => ''}
2016-11-01 00:01:11,944 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.hbase.index.Indexer with path null and priority
805306366
2016-11-01 00:01:11,945 DEBUG [AM.ZK.Worker-pool302-t5]
org.apache.hadoop.hbase.master.AssignmentManager(959): Handling
RS_ZK_REGION_OPENING, server=jenkins-ubuntu2.apache.org,44416,1477958291254,
region=5895aa952871281f7c8b7df9bb6ca524,
current_state={5895aa952871281f7c8b7df9bb6ca524 state=PENDING_OPEN,
ts=1477958471730, server=jenkins-ubuntu2.apache.org,44416,1477958291254}
2016-11-01 00:01:11,945 DEBUG [AM.ZK.Worker-pool302-t2]
org.apache.hadoop.hbase.master.AssignmentManager(959): Handling
RS_ZK_REGION_OPENING, server=jenkins-ubuntu2.apache.org,44416,1477958291254,
region=f640c428847a963593b971cd6dfe1642,
current_state={f640c428847a963593b971cd6dfe1642 state=PENDING_OPEN,
ts=1477958471284, server=jenkins-ubuntu2.apache.org,44416,1477958291254}
2016-11-01 00:01:11,946 INFO [AM.ZK.Worker-pool302-t5]
org.apache.hadoop.hbase.master.RegionStates(1107): Transition
{5895aa952871281f7c8b7df9bb6ca524 state=PENDING_OPEN, ts=1477958471730,
server=jenkins-ubuntu2.apache.org,44416,1477958291254} to
{5895aa952871281f7c8b7df9bb6ca524 state=OPENING, ts=1477958471945,
server=jenkins-ubuntu2.apache.org,44416,1477958291254}
2016-11-01 00:01:11,946 INFO [AM.ZK.Worker-pool302-t2]
org.apache.hadoop.hbase.master.RegionStates(1107): Transition
{f640c428847a963593b971cd6dfe1642 state=PENDING_OPEN, ts=1477958471284,
server=jenkins-ubuntu2.apache.org,44416,1477958291254} to
{f640c428847a963593b971cd6dfe1642 state=OPENING, ts=1477958471946,
server=jenkins-ubuntu2.apache.org,44416,1477958291254}
2016-11-01 00:01:11,955 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:50873-0x1581d2e09400000, quorum=localhost:57108, baseZNode=/hbase
Received ZooKeeper Event, type=NodeDataChanged, state=SyncConnected,
path=/hbase/region-in-transition/072599aba0d2e3ddb3b39132d2b8bfe2
2016-11-01 00:01:11,956 DEBUG [AM.ZK.Worker-pool384-t2]
org.apache.hadoop.hbase.master.AssignmentManager(959): Handling
RS_ZK_REGION_OPENING, server=jenkins-ubuntu2.apache.org,52154,1477958307217,
region=072599aba0d2e3ddb3b39132d2b8bfe2,
current_state={072599aba0d2e3ddb3b39132d2b8bfe2 state=PENDING_OPEN,
ts=1477958471765, server=jenkins-ubuntu2.apache.org,52154,1477958307217}
2016-11-01 00:01:11,956 INFO [AM.ZK.Worker-pool384-t2]
org.apache.hadoop.hbase.master.RegionStates(1107): Transition
{072599aba0d2e3ddb3b39132d2b8bfe2 state=PENDING_OPEN, ts=1477958471765,
server=jenkins-ubuntu2.apache.org,52154,1477958307217} to
{072599aba0d2e3ddb3b39132d2b8bfe2 state=OPENING, ts=1477958471956,
server=jenkins-ubuntu2.apache.org,52154,1477958307217}
2016-11-01 00:01:11,954 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.phoenix.hbase.index.Indexer(170): Setting up recovery writter with
failure policy: class
org.apache.phoenix.hbase.index.write.recovery.StoreFailuresInCachePolicy
2016-11-01 00:01:11,955 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.hbase.index.Indexer from HTD of T000372
successfully.
2016-11-01 00:01:11,955 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.ServerCachingEndpointImpl with path null
and priority 805306366
2016-11-01 00:01:11,955 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.HRegion(7724): Registered coprocessor
service: region=T000372,,1477958467603.f640c428847a963593b971cd6dfe1642.
service=ServerCachingService
2016-11-01 00:01:11,955 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.ServerCachingEndpointImpl from HTD
of T000372 successfully.
2016-11-01 00:01:11,955 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver with path
null and priority 805306366
2016-11-01 00:01:11,955 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver from
HTD of T000372 successfully.
2016-11-01 00:01:11,955 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver with path
null and priority 805306366
2016-11-01 00:01:11,955 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver
from HTD of T000372 successfully.
2016-11-01 00:01:11,956 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.ScanRegionObserver with path null and
priority 805306366
2016-11-01 00:01:11,956 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.ScanRegionObserver from HTD of
T000372 successfully.
2016-11-01 00:01:11,956 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.MetricsRegionSourceImpl(70): Creating new
MetricsRegionSourceImpl for table T000372 f640c428847a963593b971cd6dfe1642
2016-11-01 00:01:11,956 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.HRegion(735): Instantiated
T000372,,1477958467603.f640c428847a963593b971cd6dfe1642.
2016-11-01 00:01:11,956 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.phoenix.hbase.index.Indexer(170): Setting up recovery writter with
failure policy: class
org.apache.phoenix.hbase.index.write.recovery.StoreFailuresInCachePolicy
2016-11-01 00:01:11,957 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.hbase.index.Indexer from HTD of T000376
successfully.
2016-11-01 00:01:11,957 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.ServerCachingEndpointImpl with path null
and priority 805306366
2016-11-01 00:01:11,957 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.HRegion(7724): Registered coprocessor
service: region=T000376,,1477958467588.5895aa952871281f7c8b7df9bb6ca524.
service=ServerCachingService
2016-11-01 00:01:11,957 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.ServerCachingEndpointImpl from HTD
of T000376 successfully.
2016-11-01 00:01:11,957 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver with path
null and priority 805306366
2016-11-01 00:01:11,957 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver from
HTD of T000376 successfully.
2016-11-01 00:01:11,957 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver with path
null and priority 805306366
2016-11-01 00:01:11,957 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver
from HTD of T000376 successfully.
2016-11-01 00:01:11,957 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.ScanRegionObserver with path null and
priority 805306366
2016-11-01 00:01:11,957 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.ScanRegionObserver from HTD of
T000376 successfully.
2016-11-01 00:01:11,958 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.MetricsRegionSourceImpl(70): Creating new
MetricsRegionSourceImpl for table T000376 5895aa952871281f7c8b7df9bb6ca524
2016-11-01 00:01:11,958 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.HRegion(735): Instantiated
T000376,,1477958467588.5895aa952871281f7c8b7df9bb6ca524.
2016-11-01 00:01:11,964 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.zookeeper.ZKAssign(880):
regionserver:52154-0x1581d2e09400001, quorum=localhost:57108, baseZNode=/hbase
Transitioned node 072599aba0d2e3ddb3b39132d2b8bfe2 from M_ZK_REGION_OFFLINE to
RS_ZK_REGION_OPENING
2016-11-01 00:01:11,965 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.HRegion(6402): Opening region: {ENCODED =>
072599aba0d2e3ddb3b39132d2b8bfe2, NAME =>
'S_T000421.T000427,,1477958465706.072599aba0d2e3ddb3b39132d2b8bfe2.', STARTKEY
=> '', ENDKEY => ''}
2016-11-01 00:01:11,967 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.hadoop.hbase.wal.WALSplitter(729): Wrote region
seqId=hdfs://localhost:36144/user/jenkins/test-data/04ac99d0-15f9-48d0-99ae-9f044c918297/data/default/T_NULL_DECIMAL_DESC_T000313/f2a9905d214f6a079739bb3498f8b070/recovered.edits/2.seqid
to file, newSeqId=2, maxSeqId=0
2016-11-01 00:01:11,967 INFO [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.hadoop.hbase.regionserver.HRegion(870): Onlined
f2a9905d214f6a079739bb3498f8b070; next sequenceid=2
2016-11-01 00:01:11,967 INFO [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.phoenix.hbase.index.Indexer(520): Found some outstanding index
updates that didn't succeed during WAL replay - attempting to replay now.
2016-11-01 00:01:11,970 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.hbase.index.Indexer with path null and priority
805306366
2016-11-01 00:01:11,970 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.phoenix.hbase.index.Indexer(170): Setting up recovery writter with
failure policy: class
org.apache.phoenix.hbase.index.write.recovery.StoreFailuresInCachePolicy
2016-11-01 00:01:11,971 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.hbase.index.Indexer from HTD of
S_T000421.T000427 successfully.
2016-11-01 00:01:11,971 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.ServerCachingEndpointImpl with path null
and priority 805306366
2016-11-01 00:01:11,971 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.HRegion(7724): Registered coprocessor
service:
region=S_T000421.T000427,,1477958465706.072599aba0d2e3ddb3b39132d2b8bfe2.
service=ServerCachingService
2016-11-01 00:01:11,971 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=50873]
org.apache.hadoop.hbase.procedure2.ProcedureExecutor(669): Procedure
CreateTableProcedure (table=S_T000417.idx_item) id=241 owner=jenkins
state=RUNNABLE:CREATE_TABLE_PRE_OPERATION added to the store.
2016-11-01 00:01:11,972 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.ServerCachingEndpointImpl from HTD
of S_T000421.T000427 successfully.
2016-11-01 00:01:11,972 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.hadoop.hbase.zookeeper.ZKAssign(643):
regionserver:52154-0x1581d2e09400001, quorum=localhost:57108, baseZNode=/hbase
Attempting to retransition opening state of node
ebad129d27f7190584bd43588ed17c00
2016-11-01 00:01:11,974 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver with path
null and priority 805306366
2016-11-01 00:01:11,974 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver from
HTD of S_T000421.T000427 successfully.
2016-11-01 00:01:11,975 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver with path
null and priority 805306366
2016-11-01 00:01:11,975 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver
from HTD of S_T000421.T000427 successfully.
2016-11-01 00:01:11,975 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(182): Loading coprocessor
class org.apache.phoenix.coprocessor.ScanRegionObserver with path null and
priority 805306366
2016-11-01 00:01:11,975 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost(367): Loaded
coprocessor org.apache.phoenix.coprocessor.ScanRegionObserver from HTD of
S_T000421.T000427 successfully.
2016-11-01 00:01:11,975 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.MetricsRegionSourceImpl(70): Creating new
MetricsRegionSourceImpl for table S_T000421.T000427
072599aba0d2e3ddb3b39132d2b8bfe2
2016-11-01 00:01:11,975 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.HRegion(735): Instantiated
S_T000421.T000427,,1477958465706.072599aba0d2e3ddb3b39132d2b8bfe2.
2016-11-01 00:01:11,975 INFO [StoreOpener-d3a6da933d7a9fabfb44dc4261002efe-1]
org.apache.hadoop.hbase.io.hfile.CacheConfig(292):
blockCache=LruBlockCache{blockCount=0, currentSize=844240, freeSize=821239344,
maxSize=822083584, heapSize=844240, minSize=780979392, minFactor=0.95,
multiSize=390489696, multiFactor=0.5, singleSize=195244848, singleFactor=0.25},
cacheDataOnRead=true, cacheDataOnWrite=false, cacheIndexesOnWrite=false,
cacheBloomsOnWrite=false, cacheEvictOnClose=false, cacheDataCompressed=false,
prefetchOnOpen=false
2016-11-01 00:01:11,975 INFO [StoreOpener-d3a6da933d7a9fabfb44dc4261002efe-1]
org.apache.hadoop.hbase.regionserver.compactions.CompactionConfiguration(104):
size [134217728, 9223372036854775807, 9223372036854775807); files [3, 10);
ratio 1.200000; off-peak ratio 5.000000; throttle point 2684354560; major
period 604800000, major jitter 0.500000, min locality to compact 0.000000
2016-11-01 00:01:11,976 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.wal.WALSplitter(729): Wrote region
seqId=hdfs://localhost:41977/user/jenkins/test-data/5c3a4a72-b86b-4376-acda-4544e5207fce/data/default/T000030/6dc146d0df916c07ce72a841564219ee/recovered.edits/7.seqid
to file, newSeqId=7, maxSeqId=5
2016-11-01 00:01:11,980 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(272): Stop coprocessor
org.apache.phoenix.hbase.index.Indexer
2016-11-01 00:01:11,980 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.builder.BaseIndexBuilder(124): Stopping because:
Indexer is being stopped
2016-11-01 00:01:11,980 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.parallel.BaseTaskRunner(121): Shutting down task
runner because Indexer is being stopped
2016-11-01 00:01:11,980 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.write.IndexWriter(220): Stopping because Indexer
is being stopped
2016-11-01 00:01:11,980 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter(229):
Shutting down ParallelWriterIndexCommitter because Indexer is being stopped
2016-11-01 00:01:11,980 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.parallel.BaseTaskRunner(121): Shutting down task
runner because Indexer is being stopped
2016-11-01 00:01:11,980 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.write.IndexWriter(220): Stopping because Indexer
is being stopped
2016-11-01 00:01:11,980 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.write.recovery.TrackingParallelWriterIndexCommitter(232):
Shutting down TrackingParallelWriterIndexCommitter
2016-11-01 00:01:11,980 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.phoenix.hbase.index.parallel.BaseTaskRunner(121): Shutting down task
runner because Indexer is being stopped
2016-11-01 00:01:11,980 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(272): Stop coprocessor
org.apache.phoenix.coprocessor.ServerCachingEndpointImpl
2016-11-01 00:01:11,980 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(272): Stop coprocessor
org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver
2016-11-01 00:01:11,980 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(272): Stop coprocessor
org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver
2016-11-01 00:01:11,981 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.coprocessor.CoprocessorHost(272): Stop coprocessor
org.apache.phoenix.coprocessor.ScanRegionObserver
2016-11-01 00:01:11,981 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.regionserver.HRegion(1523): Closed
T000030,j\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00,1477958285736.6dc146d0df916c07ce72a841564219ee.
2016-11-01 00:01:11,981 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.regionserver.handler.CloseRegionHandler(164): Closed
T000030,j\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00,1477958285736.6dc146d0df916c07ce72a841564219ee.
2016-11-01 00:01:11,981 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.regionserver.handler.CloseRegionHandler(119):
Processing close of T000136,,1477958445089.8f181263a62ff53fd9d28ff885e84e5e.
2016-11-01 00:01:11,981 INFO
[PostOpenDeployTasks:ebad129d27f7190584bd43588ed17c00]
org.apache.hadoop.hbase.regionserver.HRegionServer(1912): Post open deploy
tasks for S_T000429.T000431,,1477958465722.ebad129d27f7190584bd43588ed17c00.
2016-11-01 00:01:11,982 DEBUG [StoreOpener-d3a6da933d7a9fabfb44dc4261002efe-1]
org.apache.hadoop.hbase.regionserver.HRegionFileSystem(201): No StoreFiles for:
hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000368/d3a6da933d7a9fabfb44dc4261002efe/0
2016-11-01 00:01:11,983 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.regionserver.HRegion(3878): Found 0 recovered edits
file(s) under
hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000368/d3a6da933d7a9fabfb44dc4261002efe
2016-11-01 00:01:11,985 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.hadoop.hbase.zookeeper.ZKAssign(643):
regionserver:40197-0x1581d2de0690001, quorum=localhost:59305, baseZNode=/hbase
Attempting to retransition opening state of node
f2a9905d214f6a079739bb3498f8b070
2016-11-01 00:01:11,987 INFO
[PostOpenDeployTasks:f2a9905d214f6a079739bb3498f8b070]
org.apache.hadoop.hbase.regionserver.HRegionServer(1912): Post open deploy
tasks for
T_NULL_DECIMAL_DESC_T000313,,1477958467712.f2a9905d214f6a079739bb3498f8b070.
2016-11-01 00:01:11,996 INFO [RS:0;jenkins-ubuntu2:51965]
org.apache.hadoop.hbase.regionserver.HRegionServer(1261): Waiting on 183
regions to close
2016-11-01 00:01:12,003 DEBUG
[org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@64cfc878]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(1500): BLOCK*
neededReplications = 0 pendingReplications = 0
2016-11-01 00:01:12,073 INFO [ProcedureExecutor-3]
org.apache.hadoop.hbase.MetaTableAccessor(1199): Added 1
2016-11-01 00:01:12,072 INFO
[PostOpenDeployTasks:f2a9905d214f6a079739bb3498f8b070]
org.apache.hadoop.hbase.MetaTableAccessor(1394): Updated row
T_NULL_DECIMAL_DESC_T000313,,1477958467712.f2a9905d214f6a079739bb3498f8b070.
with server=jenkins-ubuntu2.apache.org,40197,1477958296192
2016-11-01 00:01:12,075 INFO
[PostOpenDeployTasks:ebad129d27f7190584bd43588ed17c00]
org.apache.hadoop.hbase.MetaTableAccessor(1394): Updated row
S_T000429.T000431,,1477958465722.ebad129d27f7190584bd43588ed17c00. with
server=jenkins-ubuntu2.apache.org,52154,1477958307217
2016-11-01 00:01:12,105 DEBUG
[PostOpenDeployTasks:ebad129d27f7190584bd43588ed17c00]
org.apache.hadoop.hbase.regionserver.HRegionServer(1946): Finished post open
deploy task for
S_T000429.T000431,,1477958465722.ebad129d27f7190584bd43588ed17c00.
2016-11-01 00:01:12,101 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=46226]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=136
2016-11-01 00:01:12,106 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.hadoop.hbase.zookeeper.ZKAssign(805):
regionserver:52154-0x1581d2e09400001, quorum=localhost:57108, baseZNode=/hbase
Transitioning ebad129d27f7190584bd43588ed17c00 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2016-11-01 00:01:12,091 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.regionserver.HRegion(1393): Closing
T000136,,1477958445089.8f181263a62ff53fd9d28ff885e84e5e.: disabling compactions
& flushes
2016-11-01 00:01:12,105 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.regionserver.HRegion(1420): Updates disabled for region
T000136,,1477958445089.8f181263a62ff53fd9d28ff885e84e5e.
2016-11-01 00:01:12,105 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-1]
org.apache.hadoop.hbase.regionserver.HRegion(2163): Flushing 2/2 column
families, memstore=3.14 KB
2016-11-01 00:01:12,105 DEBUG
[PostOpenDeployTasks:f2a9905d214f6a079739bb3498f8b070]
org.apache.hadoop.hbase.regionserver.HRegionServer(1946): Finished post open
deploy task for
T_NULL_DECIMAL_DESC_T000313,,1477958467712.f2a9905d214f6a079739bb3498f8b070.
2016-11-01 00:01:12,107 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.hadoop.hbase.zookeeper.ZKAssign(805):
regionserver:40197-0x1581d2de0690001, quorum=localhost:59305, baseZNode=/hbase
Transitioning f2a9905d214f6a079739bb3498f8b070 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2016-11-01 00:01:12,111 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=50873]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=241
2016-11-01 00:01:12,111 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.regionserver.FlushLargeStoresPolicy(56):
hbase.hregion.percolumnfamilyflush.size.lower.bound is not specified, use
global config(16777216) instead
2016-11-01 00:01:12,112 INFO [StoreOpener-5895aa952871281f7c8b7df9bb6ca524-1]
org.apache.hadoop.hbase.io.hfile.CacheConfig(292):
blockCache=LruBlockCache{blockCount=0, currentSize=844240, freeSize=821239344,
maxSize=822083584, heapSize=844240, minSize=780979392, minFactor=0.95,
multiSize=390489696, multiFactor=0.5, singleSize=195244848, singleFactor=0.25},
cacheDataOnRead=true, cacheDataOnWrite=false, cacheIndexesOnWrite=false,
cacheBloomsOnWrite=false, cacheEvictOnClose=false, cacheDataCompressed=false,
prefetchOnOpen=false
2016-11-01 00:01:12,113 INFO [StoreOpener-5895aa952871281f7c8b7df9bb6ca524-1]
org.apache.hadoop.hbase.regionserver.compactions.CompactionConfiguration(104):
size [134217728, 9223372036854775807, 9223372036854775807); files [3, 10);
ratio 1.200000; off-peak ratio 5.000000; throttle point 2684354560; major
period 604800000, major jitter 0.500000, min locality to compact 0.000000
2016-11-01 00:01:12,112 INFO [StoreOpener-f640c428847a963593b971cd6dfe1642-1]
org.apache.hadoop.hbase.io.hfile.CacheConfig(292):
blockCache=LruBlockCache{blockCount=0, currentSize=844240, freeSize=821239344,
maxSize=822083584, heapSize=844240, minSize=780979392, minFactor=0.95,
multiSize=390489696, multiFactor=0.5, singleSize=195244848, singleFactor=0.25},
cacheDataOnRead=true, cacheDataOnWrite=false, cacheIndexesOnWrite=false,
cacheBloomsOnWrite=false, cacheEvictOnClose=false, cacheDataCompressed=false,
prefetchOnOpen=false
2016-11-01 00:01:12,113 INFO [StoreOpener-f640c428847a963593b971cd6dfe1642-1]
org.apache.hadoop.hbase.regionserver.compactions.CompactionConfiguration(104):
size [134217728, 9223372036854775807, 9223372036854775807); files [3, 10);
ratio 1.200000; off-peak ratio 5.000000; throttle point 2684354560; major
period 604800000, major jitter 0.500000, min locality to compact 0.000000
2016-11-01 00:01:12,115 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:46798-2]
org.apache.hadoop.hbase.regionserver.DefaultStoreFlusher(90): Flushed,
sequenceid=6, memsize=7.5 K, hasBloomFilter=true, into tmp file
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/S_T000431.idx_item/ef984742de7714050ec7ca9d715ed35a/.tmp/b2c8b470c56147afa9747c50bba3c549
2016-11-01 00:01:12,116 INFO [StoreOpener-072599aba0d2e3ddb3b39132d2b8bfe2-1]
org.apache.hadoop.hbase.io.hfile.CacheConfig(292):
blockCache=LruBlockCache{blockCount=4, currentSize=848304, freeSize=821235280,
maxSize=822083584, heapSize=848304, minSize=780979392, minFactor=0.95,
multiSize=390489696, multiFactor=0.5, singleSize=195244848, singleFactor=0.25},
cacheDataOnRead=true, cacheDataOnWrite=false, cacheIndexesOnWrite=false,
cacheBloomsOnWrite=false, cacheEvictOnClose=false, cacheDataCompressed=false,
prefetchOnOpen=false
2016-11-01 00:01:12,116 INFO [StoreOpener-072599aba0d2e3ddb3b39132d2b8bfe2-1]
org.apache.hadoop.hbase.regionserver.compactions.CompactionConfiguration(104):
size [134217728, 9223372036854775807, 9223372036854775807); files [3, 10);
ratio 1.200000; off-peak ratio 5.000000; throttle point 2684354560; major
period 604800000, major jitter 0.500000, min locality to compact 0.000000
2016-11-01 00:01:12,117 DEBUG [StoreOpener-f640c428847a963593b971cd6dfe1642-1]
org.apache.hadoop.hbase.regionserver.HRegionFileSystem(201): No StoreFiles for:
hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000372/f640c428847a963593b971cd6dfe1642/0
2016-11-01 00:01:12,118 DEBUG [StoreOpener-072599aba0d2e3ddb3b39132d2b8bfe2-1]
org.apache.hadoop.hbase.regionserver.HRegionFileSystem(201): No StoreFiles for:
hdfs://localhost:46171/user/jenkins/test-data/4a3d2459-391d-4788-8a7e-bb4d1c8efb65/data/default/S_T000421.T000427/072599aba0d2e3ddb3b39132d2b8bfe2/0
2016-11-01 00:01:12,118 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.HRegion(3878): Found 0 recovered edits
file(s) under
hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000372/f640c428847a963593b971cd6dfe1642
2016-11-01 00:01:12,119 DEBUG [StoreOpener-5895aa952871281f7c8b7df9bb6ca524-1]
org.apache.hadoop.hbase.regionserver.HRegionFileSystem(201): No StoreFiles for:
hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000376/5895aa952871281f7c8b7df9bb6ca524/0
2016-11-01 00:01:12,120 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.HRegion(3878): Found 0 recovered edits
file(s) under
hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000376/5895aa952871281f7c8b7df9bb6ca524
2016-11-01 00:01:12,120 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.FlushLargeStoresPolicy(56):
hbase.hregion.percolumnfamilyflush.size.lower.bound is not specified, use
global config(16777216) instead
2016-11-01 00:01:12,121 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=50873]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=240
2016-11-01 00:01:12,123 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.FlushLargeStoresPolicy(56):
hbase.hregion.percolumnfamilyflush.size.lower.bound is not specified, use
global config(16777216) instead
2016-11-01 00:01:12,132 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:46798-1]
org.apache.hadoop.hbase.regionserver.DefaultStoreFlusher(90): Flushed,
sequenceid=14, memsize=1.0 K, hasBloomFilter=true, into tmp file
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/S_T000513.T000632/e852efcadaede1eae143ed3c2f5273fb/.tmp/162f320531b84aa8b34f3fd15092778c
2016-11-01 00:01:12,135 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.HRegion(3878): Found 0 recovered edits
file(s) under
hdfs://localhost:46171/user/jenkins/test-data/4a3d2459-391d-4788-8a7e-bb4d1c8efb65/data/default/S_T000421.T000427/072599aba0d2e3ddb3b39132d2b8bfe2
2016-11-01 00:01:12,137 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.FlushLargeStoresPolicy(56):
hbase.hregion.percolumnfamilyflush.size.lower.bound is not specified, use
global config(16777216) instead
2016-11-01 00:01:12,142 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:46226-0x1581d2de0690000, quorum=localhost:59305, baseZNode=/hbase
Received ZooKeeper Event, type=NodeDataChanged, state=SyncConnected,
path=/hbase/region-in-transition/f2a9905d214f6a079739bb3498f8b070
2016-11-01 00:01:12,143 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.hadoop.hbase.zookeeper.ZKAssign(880):
regionserver:40197-0x1581d2de0690001, quorum=localhost:59305, baseZNode=/hbase
Transitioned node f2a9905d214f6a079739bb3498f8b070 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2016-11-01 00:01:12,143 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.hadoop.hbase.coordination.ZkOpenRegionCoordination(91): Transitioned
f2a9905d214f6a079739bb3498f8b070 to OPENED in zk on
jenkins-ubuntu2.apache.org,40197,1477958296192
2016-11-01 00:01:12,143 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:40197-1]
org.apache.hadoop.hbase.regionserver.handler.OpenRegionHandler(170): Opened
T_NULL_DECIMAL_DESC_T000313,,1477958467712.f2a9905d214f6a079739bb3498f8b070. on
jenkins-ubuntu2.apache.org,40197,1477958296192
2016-11-01 00:01:12,144 DEBUG [AM.ZK.Worker-pool313-t1]
org.apache.hadoop.hbase.master.AssignmentManager(959): Handling
RS_ZK_REGION_OPENED, server=jenkins-ubuntu2.apache.org,40197,1477958296192,
region=f2a9905d214f6a079739bb3498f8b070,
current_state={f2a9905d214f6a079739bb3498f8b070 state=OPENING,
ts=1477958471835, server=jenkins-ubuntu2.apache.org,40197,1477958296192}
2016-11-01 00:01:12,144 INFO [AM.ZK.Worker-pool313-t1]
org.apache.hadoop.hbase.master.RegionStates(1107): Transition
{f2a9905d214f6a079739bb3498f8b070 state=OPENING, ts=1477958471835,
server=jenkins-ubuntu2.apache.org,40197,1477958296192} to
{f2a9905d214f6a079739bb3498f8b070 state=OPEN, ts=1477958472144,
server=jenkins-ubuntu2.apache.org,40197,1477958296192}
2016-11-01 00:01:12,144 DEBUG [AM.ZK.Worker-pool313-t1]
org.apache.hadoop.hbase.coordination.ZkOpenRegionCoordination(350): Handling
OPENED of f2a9905d214f6a079739bb3498f8b070 from
jenkins-ubuntu2.apache.org,46226,1477958295969; deleting unassigned node
2016-11-01 00:01:12,146 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:50873-0x1581d2e09400000, quorum=localhost:57108, baseZNode=/hbase
Received ZooKeeper Event, type=NodeDataChanged, state=SyncConnected,
path=/hbase/region-in-transition/ebad129d27f7190584bd43588ed17c00
2016-11-01 00:01:12,146 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.hadoop.hbase.zookeeper.ZKAssign(880):
regionserver:52154-0x1581d2e09400001, quorum=localhost:57108, baseZNode=/hbase
Transitioned node ebad129d27f7190584bd43588ed17c00 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2016-11-01 00:01:12,147 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.hadoop.hbase.coordination.ZkOpenRegionCoordination(91): Transitioned
ebad129d27f7190584bd43588ed17c00 to OPENED in zk on
jenkins-ubuntu2.apache.org,52154,1477958307217
2016-11-01 00:01:12,147 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-0]
org.apache.hadoop.hbase.regionserver.handler.OpenRegionHandler(170): Opened
S_T000429.T000431,,1477958465722.ebad129d27f7190584bd43588ed17c00. on
jenkins-ubuntu2.apache.org,52154,1477958307217
2016-11-01 00:01:12,147 DEBUG [AM.ZK.Worker-pool384-t4]
org.apache.hadoop.hbase.master.AssignmentManager(959): Handling
RS_ZK_REGION_OPENED, server=jenkins-ubuntu2.apache.org,52154,1477958307217,
region=ebad129d27f7190584bd43588ed17c00,
current_state={ebad129d27f7190584bd43588ed17c00 state=OPENING,
ts=1477958471781, server=jenkins-ubuntu2.apache.org,52154,1477958307217}
2016-11-01 00:01:12,148 INFO [AM.ZK.Worker-pool384-t4]
org.apache.hadoop.hbase.master.RegionStates(1107): Transition
{ebad129d27f7190584bd43588ed17c00 state=OPENING, ts=1477958471781,
server=jenkins-ubuntu2.apache.org,52154,1477958307217} to
{ebad129d27f7190584bd43588ed17c00 state=OPEN, ts=1477958472148,
server=jenkins-ubuntu2.apache.org,52154,1477958307217}
2016-11-01 00:01:12,148 DEBUG [AM.ZK.Worker-pool384-t4]
org.apache.hadoop.hbase.coordination.ZkOpenRegionCoordination(350): Handling
OPENED of ebad129d27f7190584bd43588ed17c00 from
jenkins-ubuntu2.apache.org,50873,1477958306364; deleting unassigned node
2016-11-01 00:01:12,850 INFO [M:0;jenkins-ubuntu2:44406]
org.apache.hadoop.hbase.master.ServerManager(557): Waiting on regionserver(s)
to go down jenkins-ubuntu2.apache.org,51965,1477958147783
2016-11-01 00:01:12,733 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:46226-0x1581d2de0690000, quorum=localhost:59305, baseZNode=/hbase
Received ZooKeeper Event, type=NodeDeleted, state=SyncConnected,
path=/hbase/region-in-transition/f2a9905d214f6a079739bb3498f8b070
2016-11-01 00:01:12,882 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=39781]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=303
2016-11-01 00:01:12,940 INFO [M:0;jenkins-ubuntu2:57887]
org.apache.hadoop.hbase.master.ServerManager(557): Waiting on regionserver(s)
to go down jenkins-ubuntu2.apache.org,46798,1477958147823
2016-11-01 00:01:12,893 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:50873-0x1581d2e09400000, quorum=localhost:57108, baseZNode=/hbase
Received ZooKeeper Event, type=NodeDeleted, state=SyncConnected,
path=/hbase/region-in-transition/ebad129d27f7190584bd43588ed17c00
2016-11-01 00:01:13,117 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=46226]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=135
2016-11-01 00:01:12,858 DEBUG [AM.ZK.Worker-pool313-t1]
org.apache.hadoop.hbase.zookeeper.ZKAssign(479):
master:46226-0x1581d2de0690000, quorum=localhost:59305, baseZNode=/hbase
Deleted unassigned node f2a9905d214f6a079739bb3498f8b070 in expected state
RS_ZK_REGION_OPENED
2016-11-01 00:01:13,117 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=50873]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=241
2016-11-01 00:01:13,044 WARN
[jenkins-ubuntu2.apache.org,50873,1477958306364_ChoreService_1]
org.apache.hadoop.hbase.master.CatalogJanitor(108): CatalogJanitor disabled!
Not running scan.
2016-11-01 00:01:12,981 DEBUG [AM.ZK.Worker-pool384-t4]
org.apache.hadoop.hbase.zookeeper.ZKAssign(479):
master:50873-0x1581d2e09400000, quorum=localhost:57108, baseZNode=/hbase
Deleted unassigned node ebad129d27f7190584bd43588ed17c00 in expected state
RS_ZK_REGION_OPENED
2016-11-01 00:01:13,170 DEBUG [AM.ZK.Worker-pool384-t4]
org.apache.hadoop.hbase.master.AssignmentManager$4(1350): Znode
S_T000429.T000431,,1477958465722.ebad129d27f7190584bd43588ed17c00. deleted,
state: {ebad129d27f7190584bd43588ed17c00 state=OPEN, ts=1477958472148,
server=jenkins-ubuntu2.apache.org,52154,1477958307217}
2016-11-01 00:01:13,171 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:46226-0x1581d2de0690000, quorum=localhost:59305, baseZNode=/hbase
Received ZooKeeper Event, type=NodeChildrenChanged, state=SyncConnected,
path=/hbase/region-in-transition
2016-11-01 00:01:13,171 DEBUG [AM.ZK.Worker-pool384-t4]
org.apache.hadoop.hbase.master.RegionStates(456): Onlined
ebad129d27f7190584bd43588ed17c00 on
jenkins-ubuntu2.apache.org,52154,1477958307217
2016-11-01 00:01:13,171 DEBUG [ProcedureExecutor-2]
org.apache.hadoop.hbase.master.AssignmentManager(1813): Bulk assigning done for
jenkins-ubuntu2.apache.org,52154,1477958307217
2016-11-01 00:01:13,171 INFO [ProcedureExecutor-2]
org.apache.hadoop.hbase.zookeeper.ZKTableStateManager(100): Moving table
S_T000429.T000431 state from ENABLING to ENABLED
2016-11-01 00:01:13,169 DEBUG [main-EventThread]
org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602):
master:50873-0x1581d2e09400000, quorum=localhost:57108, baseZNode=/hbase
Received ZooKeeper Event, type=NodeChildrenChanged, state=SyncConnected,
path=/hbase/region-in-transition
2016-11-01 00:01:13,168 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=50873]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=234
2016-11-01 00:01:13,171 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:46798-0]
org.apache.hadoop.hbase.regionserver.StoreFile$Reader(1454): Loaded Delete
Family Bloom (CompoundBloomFilter) metadata for b9b6c5df9f724c65aa4cd27d4210fd7b
2016-11-01 00:01:13,171 DEBUG [AM.ZK.Worker-pool313-t5]
org.apache.hadoop.hbase.master.AssignmentManager$4(1350): Znode
T_NULL_DECIMAL_DESC_T000313,,1477958467712.f2a9905d214f6a079739bb3498f8b070.
deleted, state: {f2a9905d214f6a079739bb3498f8b070 state=OPEN, ts=1477958472144,
server=jenkins-ubuntu2.apache.org,40197,1477958296192}
2016-11-01 00:01:13,172 DEBUG [AM.ZK.Worker-pool313-t5]
org.apache.hadoop.hbase.master.RegionStates(456): Onlined
f2a9905d214f6a079739bb3498f8b070 on
jenkins-ubuntu2.apache.org,40197,1477958296192
2016-11-01 00:01:13,172 DEBUG [ProcedureExecutor-2]
org.apache.hadoop.hbase.master.AssignmentManager(1813): Bulk assigning done for
jenkins-ubuntu2.apache.org,40197,1477958296192
2016-11-01 00:01:13,172 INFO [ProcedureExecutor-2]
org.apache.hadoop.hbase.zookeeper.ZKTableStateManager(100): Moving table
T_NULL_DECIMAL_DESC_T000313 state from ENABLING to ENABLED
2016-11-01 00:01:13,172 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer(1350): *BLOCK*
NameNode.blockReceivedAndDeleted: from DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0) 2 blocks.
2016-11-01 00:01:13,173 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3235): BLOCK* block
RECEIVING_BLOCK: blk_1073742388_1564 is received from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0)
2016-11-01 00:01:13,173 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3238): *BLOCK*
NameNode.processIncrementalBlockReport: from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0) receiving: 1,
received: 0, deleted: 0
2016-11-01 00:01:13,173 INFO [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(2624): BLOCK*
addStoredBlock: blockMap updated: 127.0.0.1:51641 is added to
blk_1073742387_1563{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-12463beb-342c-449a-8f93-acae73c42b65:NORMAL:127.0.0.1:51641|RBW]]}
size 0
2016-11-01 00:01:13,173 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3235): BLOCK* block
RECEIVED_BLOCK: blk_1073742387_1563 is received from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0)
2016-11-01 00:01:13,173 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3238): *BLOCK*
NameNode.processIncrementalBlockReport: from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0) receiving: 0,
received: 1, deleted: 0
2016-11-01 00:01:13,185 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:46798-0]
org.apache.hadoop.hbase.regionserver.HStore(968): Added
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/I_T000056/d97fa07371ab67701787d5a87a056c03/0/b9b6c5df9f724c65aa4cd27d4210fd7b,
entries=6, sequenceid=7, filesize=5.1 K
2016-11-01 00:01:13,186 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=50873]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=240
2016-11-01 00:01:13,186 DEBUG
[org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@8f6cb37]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(1500): BLOCK*
neededReplications = 0 pendingReplications = 0
2016-11-01 00:01:13,187 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=50873]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=235
2016-11-01 00:01:13,188 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=50873]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=239
2016-11-01 00:01:13,190 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=39781]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=302
2016-11-01 00:01:13,191 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=39781]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=305
2016-11-01 00:01:13,199 DEBUG
[RegionOpenAndInitThread-T_NULL_DOUBLE_DESC_T000280-1]
org.apache.hadoop.hbase.regionserver.HRegion(735): Instantiated
T_NULL_DOUBLE_DESC_T000280,,1477958468741.161e37a85ba0db3600847b497b84c3cb.
2016-11-01 00:01:13,199 DEBUG
[RegionOpenAndInitThread-T_NULL_DOUBLE_DESC_T000280-1]
org.apache.hadoop.hbase.regionserver.HRegion(1393): Closing
T_NULL_DOUBLE_DESC_T000280,,1477958468741.161e37a85ba0db3600847b497b84c3cb.:
disabling compactions & flushes
2016-11-01 00:01:13,200 DEBUG
[RegionOpenAndInitThread-T_NULL_DOUBLE_DESC_T000280-1]
org.apache.hadoop.hbase.regionserver.HRegion(1420): Updates disabled for region
T_NULL_DOUBLE_DESC_T000280,,1477958468741.161e37a85ba0db3600847b497b84c3cb.
2016-11-01 00:01:13,200 INFO
[RegionOpenAndInitThread-T_NULL_DOUBLE_DESC_T000280-1]
org.apache.hadoop.hbase.regionserver.HRegion(1523): Closed
T_NULL_DOUBLE_DESC_T000280,,1477958468741.161e37a85ba0db3600847b497b84c3cb.
2016-11-01 00:01:13,200 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=39781]
org.apache.hadoop.hbase.master.MasterRpcServices(1027): Checking to see if
procedure is done procId=304
2016-11-01 00:01:13,204 DEBUG [ProcedureExecutor-0]
org.apache.hadoop.hbase.util.FSTableDescriptors(656): Wrote descriptor into:
hdfs://localhost:46171/user/jenkins/test-data/4a3d2459-391d-4788-8a7e-bb4d1c8efb65/.tmp/data/default/S_T000433.T000435/.tabledesc/.tableinfo.0000000001
2016-11-01 00:01:13,211 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:46798-2]
org.apache.hadoop.hbase.regionserver.HRegionFileSystem(381): Committing store
file
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/S_T000431.idx_item/ef984742de7714050ec7ca9d715ed35a/.tmp/b2c8b470c56147afa9747c50bba3c549
as
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/S_T000431.idx_item/ef984742de7714050ec7ca9d715ed35a/0/b2c8b470c56147afa9747c50bba3c549
2016-11-01 00:01:13,214 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.wal.WALSplitter(729): Wrote region
seqId=hdfs://localhost:46171/user/jenkins/test-data/4a3d2459-391d-4788-8a7e-bb4d1c8efb65/data/default/S_T000421.T000427/072599aba0d2e3ddb3b39132d2b8bfe2/recovered.edits/2.seqid
to file, newSeqId=2, maxSeqId=0
2016-11-01 00:01:13,214 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.hadoop.hbase.regionserver.HRegion(870): Onlined
072599aba0d2e3ddb3b39132d2b8bfe2; next sequenceid=2
2016-11-01 00:01:13,214 INFO [RS_OPEN_REGION-jenkins-ubuntu2:52154-2]
org.apache.phoenix.hbase.index.Indexer(520): Found some outstanding index
updates that didn't succeed during WAL replay - attempting to replay now.
2016-11-01 00:01:13,214 INFO [RegionOpenAndInitThread-S_T000433.T000435-1]
org.apache.hadoop.hbase.regionserver.HRegion(6213): creating HRegion
S_T000433.T000435 HTD == 'S_T000433.T000435', {TABLE_ATTRIBUTES =>
{coprocessor$1 =>
'|org.apache.phoenix.coprocessor.ScanRegionObserver|805306366|', coprocessor$2
=>
'|org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver|805306366|',
coprocessor$3 =>
'|org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver|805306366|',
coprocessor$4 =>
'|org.apache.phoenix.coprocessor.ServerCachingEndpointImpl|805306366|',
coprocessor$5 =>
'|org.apache.phoenix.hbase.index.Indexer|805306366|index.builder=org.apache.phoenix.index.PhoenixIndexBuilder,org.apache.hadoop.hbase.index.codec.class=org.apache.phoenix.index.PhoenixIndexCodec'},
{NAME => '0', DATA_BLOCK_ENCODING => 'FAST_DIFF', BLOOMFILTER => 'ROW',
REPLICATION_SCOPE => '0', VERSIONS => '1', COMPRESSION => 'NONE', MIN_VERSIONS
=> '0', TTL => 'FOREVER', KEEP_DELETED_CELLS => 'FALSE', BLOCKSIZE => '65536',
IN_MEMORY => 'false', BLOCKCACHE => 'true'} RootDir =
hdfs://localhost:46171/user/jenkins/test-data/4a3d2459-391d-4788-8a7e-bb4d1c8efb65/.tmp
Table name == S_T000433.T000435
2016-11-01 00:01:13,216 DEBUG [RS_CLOSE_REGION-jenkins-ubuntu2:46798-1]
org.apache.hadoop.hbase.regionserver.HRegionFileSystem(381): Committing store
file
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/S_T000513.T000632/e852efcadaede1eae143ed3c2f5273fb/.tmp/162f320531b84aa8b34f3fd15092778c
as
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/S_T000513.T000632/e852efcadaede1eae143ed3c2f5273fb/L#0/162f320531b84aa8b34f3fd15092778c
2016-11-01 00:01:13,228 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-2]
org.apache.hadoop.hbase.regionserver.DefaultStoreFlusher(90): Flushed,
sequenceid=24, memsize=2.0 K, hasBloomFilter=true, into tmp file
hdfs://localhost:41977/user/jenkins/test-data/5c3a4a72-b86b-4376-acda-4544e5207fce/data/default/T000130/a2bfd473847ce87590a50eec1860d723/.tmp/24d598c1953b445b91d98d963bdcf97f
2016-11-01 00:01:13,365 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.wal.WALSplitter(729): Wrote region
seqId=hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000368/d3a6da933d7a9fabfb44dc4261002efe/recovered.edits/2.seqid
to file, newSeqId=2, maxSeqId=0
2016-11-01 00:01:13,407 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.regionserver.HRegion(870): Onlined
d3a6da933d7a9fabfb44dc4261002efe; next sequenceid=2
2016-11-01 00:01:13,407 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.phoenix.hbase.index.Indexer(520): Found some outstanding index
updates that didn't succeed during WAL replay - attempting to replay now.
2016-11-01 00:01:13,366 INFO [sync.1]
org.apache.hadoop.hbase.regionserver.wal.FSHLog(1393): Slow sync cost: 1253 ms,
current pipeline:
[DatanodeInfoWithStorage[127.0.0.1:51641,DS-12463beb-342c-449a-8f93-acae73c42b65,DISK]]
2016-11-01 00:01:13,397 DEBUG
[org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@7c5f9089]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(1500): BLOCK*
neededReplications = 0 pendingReplications = 0
2016-11-01 00:01:13,406 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.wal.WALSplitter(729): Wrote region
seqId=hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000372/f640c428847a963593b971cd6dfe1642/recovered.edits/2.seqid
to file, newSeqId=2, maxSeqId=0
2016-11-01 00:01:13,408 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.regionserver.HRegion(870): Onlined
f640c428847a963593b971cd6dfe1642; next sequenceid=2
2016-11-01 00:01:13,408 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.phoenix.hbase.index.Indexer(520): Found some outstanding index
updates that didn't succeed during WAL replay - attempting to replay now.
2016-11-01 00:01:13,418 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.wal.WALSplitter(729): Wrote region
seqId=hdfs://localhost:52669/user/jenkins/test-data/54f1c283-0537-495e-a371-931a24e98ffd/data/default/T000376/5895aa952871281f7c8b7df9bb6ca524/recovered.edits/2.seqid
to file, newSeqId=2, maxSeqId=0
2016-11-01 00:01:13,418 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.regionserver.HRegion(870): Onlined
5895aa952871281f7c8b7df9bb6ca524; next sequenceid=2
2016-11-01 00:01:13,418 INFO [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.phoenix.hbase.index.Indexer(520): Found some outstanding index
updates that didn't succeed during WAL replay - attempting to replay now.
2016-11-01 00:01:13,545 DEBUG
[org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@2d886769]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(1500): BLOCK*
neededReplications = 0 pendingReplications = 0
2016-11-01 00:01:13,565 INFO [sync.0]
org.apache.hadoop.hbase.regionserver.wal.FSHLog(1393): Slow sync cost: 156 ms,
current pipeline:
[DatanodeInfoWithStorage[127.0.0.1:55074,DS-312c4958-c960-4dfe-9b76-9d56409cc7ad,DISK]]
2016-11-01 00:01:13,566 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.zookeeper.ZKAssign(643):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Attempting to retransition opening state of node
f640c428847a963593b971cd6dfe1642
2016-11-01 00:01:13,566 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.zookeeper.ZKAssign(643):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Attempting to retransition opening state of node
d3a6da933d7a9fabfb44dc4261002efe
2016-11-01 00:01:13,545 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:46798-0]
org.apache.hadoop.hbase.regionserver.HRegion(2462): Finished memstore flush of
~968 B/968, currentsize=0 B/0 for region
I_T000056,,1477958222883.d97fa07371ab67701787d5a87a056c03. in 3991ms,
sequenceid=7, compaction requested=false
2016-11-01 00:01:13,566 INFO [sync.1]
org.apache.hadoop.hbase.regionserver.wal.FSHLog(1393): Slow sync cost: 157 ms,
current pipeline:
[DatanodeInfoWithStorage[127.0.0.1:55074,DS-312c4958-c960-4dfe-9b76-9d56409cc7ad,DISK]]
2016-11-01 00:01:13,551 INFO [sync.1]
org.apache.hadoop.hbase.regionserver.wal.FSHLog(1393): Slow sync cost: 359 ms,
current pipeline:
[DatanodeInfoWithStorage[127.0.0.1:42816,DS-dd1b49e2-f407-4013-896c-abfc4ee5e300,DISK]]
2016-11-01 00:01:13,569 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.zookeeper.ZKAssign(643):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Attempting to retransition opening state of node
5895aa952871281f7c8b7df9bb6ca524
2016-11-01 00:01:13,574 INFO
[regionserver/jenkins-ubuntu2.apache.org/10.20.2.231:0.logRoller]
org.apache.hadoop.hbase.regionserver.LogRoller(176): LogRoller exiting.
2016-11-01 00:01:13,851 INFO [ProcedureExecutor-1]
org.apache.hadoop.hbase.MetaTableAccessor(1199): Added 1
2016-11-01 00:01:13,855 INFO
[StoreCloserThread-I_T000056,,1477958222883.d97fa07371ab67701787d5a87a056c03.-1]
org.apache.hadoop.hbase.regionserver.HStore(875): Closed 0
2016-11-01 00:01:13,863 INFO
[regionserver/jenkins-ubuntu2.apache.org/10.20.2.231:0.leaseChecker]
org.apache.hadoop.hbase.regionserver.Leases(146):
regionserver/jenkins-ubuntu2.apache.org/10.20.2.231:0.leaseChecker closing
leases
2016-11-01 00:01:13,863 INFO
[regionserver/jenkins-ubuntu2.apache.org/10.20.2.231:0.leaseChecker]
org.apache.hadoop.hbase.regionserver.Leases(149):
regionserver/jenkins-ubuntu2.apache.org/10.20.2.231:0.leaseChecker closed leases
2016-11-01 00:01:13,882 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer(1350): *BLOCK*
NameNode.blockReceivedAndDeleted: from DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0) 1 blocks.
2016-11-01 00:01:13,882 INFO [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(2624): BLOCK*
addStoredBlock: blockMap updated: 127.0.0.1:51641 is added to
blk_1073742388_1564{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b5f00aa4-29bf-45e8-aa00-b81265ff47ef:NORMAL:127.0.0.1:51641|RBW]]}
size 0
2016-11-01 00:01:13,882 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3235): BLOCK* block
RECEIVED_BLOCK: blk_1073742388_1564 is received from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0)
2016-11-01 00:01:13,882 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3238): *BLOCK*
NameNode.processIncrementalBlockReport: from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0) receiving: 0,
received: 1, deleted: 0
2016-11-01 00:01:13,903 DEBUG
[org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@2cf1ee7f]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(1500): BLOCK*
neededReplications = 0 pendingReplications = 0
2016-11-01 00:01:13,912 INFO
[PostOpenDeployTasks:f640c428847a963593b971cd6dfe1642]
org.apache.hadoop.hbase.regionserver.HRegionServer(1912): Post open deploy
tasks for T000372,,1477958467603.f640c428847a963593b971cd6dfe1642.
2016-11-01 00:01:13,935 INFO
[PostOpenDeployTasks:5895aa952871281f7c8b7df9bb6ca524]
org.apache.hadoop.hbase.regionserver.HRegionServer(1912): Post open deploy
tasks for T000376,,1477958467588.5895aa952871281f7c8b7df9bb6ca524.
2016-11-01 00:01:13,935 INFO
[PostOpenDeployTasks:d3a6da933d7a9fabfb44dc4261002efe]
org.apache.hadoop.hbase.regionserver.HRegionServer(1912): Post open deploy
tasks for T000368,,1477958467600.d3a6da933d7a9fabfb44dc4261002efe.
2016-11-01 00:01:13,944 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:46798-2]
org.apache.hadoop.hbase.regionserver.HStore(968): Added
hdfs://localhost:58972/user/jenkins/test-data/6edd781e-8402-4cec-b773-bc5efc54fd49/data/default/S_T000431.idx_item/ef984742de7714050ec7ca9d715ed35a/0/b2c8b470c56147afa9747c50bba3c549,
entries=42, sequenceid=6, filesize=5.7 K
2016-11-01 00:01:13,953 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:51965-0]
org.apache.hadoop.hbase.regionserver.DefaultStoreFlusher(90): Flushed,
sequenceid=2005, memsize=320.3 K, hasBloomFilter=true, into tmp file
hdfs://localhost:41977/user/jenkins/test-data/5c3a4a72-b86b-4376-acda-4544e5207fce/data/default/T000105/04cee4a2d92226d26a4967ccfe7ec98b/.tmp/d5c8d7386c4f4570b74d9a65948af3a2
2016-11-01 00:01:13,995 INFO [sync.0]
org.apache.hadoop.hbase.regionserver.wal.FSHLog(1393): Slow sync cost: 139 ms,
current pipeline:
[DatanodeInfoWithStorage[127.0.0.1:42816,DS-dd1b49e2-f407-4013-896c-abfc4ee5e300,DISK]]
2016-11-01 00:01:13,996 INFO [RS_CLOSE_REGION-jenkins-ubuntu2:46798-2]
org.apache.hadoop.hbase.regionserver.HRegion(2462): Finished memstore flush of
~7.48 KB/7656, currentsize=0 B/0 for region
S_T000431.idx_item,,1477958376044.ef984742de7714050ec7ca9d715ed35a. in 3819ms,
sequenceid=6, compaction requested=false
2016-11-01 00:01:14,001 INFO
[StoreCloserThread-S_T000431.idx_item,,1477958376044.ef984742de7714050ec7ca9d715ed35a.-1]
org.apache.hadoop.hbase.regionserver.HStore(875): Closed 0
2016-11-01 00:01:14,008 INFO
[jenkins-ubuntu2.apache.org,51965,1477958147783_ChoreService_1]
org.apache.hadoop.hbase.ScheduledChore(179): Chore:
jenkins-ubuntu2.apache.org,51965,1477958147783-MemstoreFlusherChore was stopped
2016-11-01 00:01:14,112 INFO [sync.1]
org.apache.hadoop.hbase.regionserver.wal.FSHLog(1393): Slow sync cost: 173 ms,
current pipeline:
[DatanodeInfoWithStorage[127.0.0.1:55074,DS-622127e3-3fa4-49de-8ba8-8f2b6b63aed3,DISK]]
2016-11-01 00:01:14,112 INFO
[PostOpenDeployTasks:f640c428847a963593b971cd6dfe1642]
org.apache.hadoop.hbase.MetaTableAccessor(1394): Updated row
T000372,,1477958467603.f640c428847a963593b971cd6dfe1642. with
server=jenkins-ubuntu2.apache.org,44416,1477958291254
2016-11-01 00:01:14,151 DEBUG
[PostOpenDeployTasks:f640c428847a963593b971cd6dfe1642]
org.apache.hadoop.hbase.regionserver.HRegionServer(1946): Finished post open
deploy task for T000372,,1477958467603.f640c428847a963593b971cd6dfe1642.
2016-11-01 00:01:14,112 INFO [sync.0]
org.apache.hadoop.hbase.regionserver.wal.FSHLog(1393): Slow sync cost: 173 ms,
current pipeline:
[DatanodeInfoWithStorage[127.0.0.1:55074,DS-622127e3-3fa4-49de-8ba8-8f2b6b63aed3,DISK]]
2016-11-01 00:01:14,151 INFO
[PostOpenDeployTasks:5895aa952871281f7c8b7df9bb6ca524]
org.apache.hadoop.hbase.MetaTableAccessor(1394): Updated row
T000376,,1477958467588.5895aa952871281f7c8b7df9bb6ca524. with
server=jenkins-ubuntu2.apache.org,44416,1477958291254
2016-11-01 00:01:14,151 DEBUG
[PostOpenDeployTasks:5895aa952871281f7c8b7df9bb6ca524]
org.apache.hadoop.hbase.regionserver.HRegionServer(1946): Finished post open
deploy task for T000376,,1477958467588.5895aa952871281f7c8b7df9bb6ca524.
2016-11-01 00:01:14,151 INFO
[PostOpenDeployTasks:d3a6da933d7a9fabfb44dc4261002efe]
org.apache.hadoop.hbase.MetaTableAccessor(1394): Updated row
T000368,,1477958467600.d3a6da933d7a9fabfb44dc4261002efe. with
server=jenkins-ubuntu2.apache.org,44416,1477958291254
2016-11-01 00:01:14,152 DEBUG
[PostOpenDeployTasks:d3a6da933d7a9fabfb44dc4261002efe]
org.apache.hadoop.hbase.regionserver.HRegionServer(1946): Finished post open
deploy task for T000368,,1477958467600.d3a6da933d7a9fabfb44dc4261002efe.
2016-11-01 00:01:14,169 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-2]
org.apache.hadoop.hbase.zookeeper.ZKAssign(805):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Transitioning d3a6da933d7a9fabfb44dc4261002efe from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2016-11-01 00:01:14,169 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-0]
org.apache.hadoop.hbase.zookeeper.ZKAssign(805):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Transitioning f640c428847a963593b971cd6dfe1642 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2016-11-01 00:01:14,009 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer(1350): *BLOCK*
NameNode.blockReceivedAndDeleted: from DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0) 1 blocks.
2016-11-01 00:01:14,170 DEBUG [RS_OPEN_REGION-jenkins-ubuntu2:44416-1]
org.apache.hadoop.hbase.zookeeper.ZKAssign(805):
regionserver:44416-0x1581d2dca480001, quorum=localhost:51252, baseZNode=/hbase
Transitioning 5895aa952871281f7c8b7df9bb6ca524 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2016-11-01 00:01:14,170 INFO [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(2624): BLOCK*
addStoredBlock: blockMap updated: 127.0.0.1:51641 is added to
blk_1073742389_1565{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b5f00aa4-29bf-45e8-aa00-b81265ff47ef:NORMAL:127.0.0.1:51641|RBW]]}
size 4985
2016-11-01 00:01:14,170 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3235): BLOCK* block
RECEIVED_BLOCK: blk_1073742389_1565 is received from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0)
2016-11-01 00:01:14,170 DEBUG [IPC Server handler 0 on 41977]
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3238): *BLOCK*
NameNode.processIncrementalBlockReport: from
DatanodeRegistration(127.0.0.1:51641,
datanodeUuid=efb58bc4-daab-4332-bef5-a04a269d6090, infoPort=45711,
infoSecurePort=0, ipcPort=45264,
storageInfo=lv=-56;cid=testClusterID;nsid=683332478;c=0) receiving: 0,
received: 1, deleted: 0
Build step 'Invoke top-level Maven targets' marked build as failure
Archiving artifacts
Compressed 687.83 MB of artifacts by 95.5% relative to #1463
Updating PHOENIX-3426
Recording test results
