phoenix-commits mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Phoenix | Master #1467
Date Tue, 01 Nov 2016 00:07:51 GMT
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

Mime
View raw message