[
https://issues.apache.org/jira/browse/HBASE-8883?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
stack updated HBASE-8883:
-------------------------
Fix Version/s: 0.95.2
Assignee: stack
I see that the region exists but not yet opened when we try to get the server
which has first region in the table. See below. First region in table is
e19053253da9715d44636c143da61025 In the below I mark when we try to get server
w/ first region. You'll see it not yet fully opened going by logs.
{code}
2013-07-05 17:20:44,186 INFO [pool-1-thread-1]
hbase.HBaseTestingUtility(1618): getMetaTableRows: row ->
TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.{ENCODED
=> e19053253da9715d44636c143da61025, NAME =>
'TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.',
STARTKEY => '', ENDKEY => ''}
2013-07-05 17:20:44,189 INFO
[PostOpenDeployTasks:e19053253da9715d44636c143da61025] catalog.MetaEditor(432):
Updated row
TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.
with server=hemera.apache.org,33306,1373044528830
2013-07-05 17:20:44,189 INFO
[PostOpenDeployTasks:e19053253da9715d44636c143da61025]
regionserver.HRegionServer(1724): Done with post open deploy task for
region=TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.
2013-07-05 17:20:44,189 DEBUG [RS_OPEN_REGION-hemera:33306-2]
zookeeper.ZKAssign(786): regionserver:33306-0x13fafd4d4010003 Attempting to
transition node e19053253da9715d44636c143da61025 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2013-07-05 17:20:44,191 DEBUG [pool-1-thread-1] client.ClientScanner(195):
Finished region={ENCODED => 1028785192, NAME => '.META.,,1', STARTKEY => '',
ENDKEY => ''}
2013-07-05 17:20:44,191 DEBUG [pool-1-thread-1-EventThread]
zookeeper.ZooKeeperWatcher(307): master:55016-0x13fafd4d4010000 Received
ZooKeeper Event, type=NodeDataChanged, state=SyncConnected,
path=/hbase/region-in-transition/e19053253da9715d44636c143da61025
2013-07-05 17:20:44,192 DEBUG [pool-1-thread-1]
hbase.HBaseTestingUtility(1644): Found 1 rows for table
TestHBACloseRegionWhenServerNameIsEmpty
2013-07-05 17:20:44,192 DEBUG [pool-1-thread-1]
hbase.HBaseTestingUtility(1647):
FirstRow=TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.
<===== We look for hosting server here but it not yet OPENED
2013-07-05 17:20:44,192 DEBUG [RS_OPEN_REGION-hemera:33306-2]
zookeeper.ZKAssign(862): regionserver:33306-0x13fafd4d4010003 Successfully
transitioned node e19053253da9715d44636c143da61025 from RS_ZK_REGION_OPENING to
RS_ZK_REGION_OPENED
2013-07-05 17:20:44,192 DEBUG [RS_OPEN_REGION-hemera:33306-2]
handler.OpenRegionHandler(373): region transitioned to opened in zookeeper:
{ENCODED => e19053253da9715d44636c143da61025, NAME =>
'TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.',
STARTKEY => '', ENDKEY => ''}, server: hemera.apache.org,33306,1373044528830
2013-07-05 17:20:44,192 DEBUG [RS_OPEN_REGION-hemera:33306-2]
handler.OpenRegionHandler(186): Opened
TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.
on server:hemera.apache.org,33306,1373044528830
2013-07-05 17:20:44,192 DEBUG [hbase-am-zkevent-worker-pool-2-thread-2]
master.AssignmentManager(768): Handling transition=RS_ZK_REGION_OPENED,
server=hemera.apache.org,33306,1373044528830,
region=e19053253da9715d44636c143da61025, current state from region state map
={e19053253da9715d44636c143da61025 state=OPENING, ts=1373044844134,
server=hemera.apache.org,33306,1373044528830}
2013-07-05 17:20:44,193 INFO [hbase-am-zkevent-worker-pool-2-thread-2]
master.RegionStates(265): Region transitioned from
{e19053253da9715d44636c143da61025 state=OPENING, ts=1373044844134,
server=hemera.apache.org,33306,1373044528830} to
{e19053253da9715d44636c143da61025 state=OPEN, ts=1373044844193,
server=hemera.apache.org,33306,1373044528830}
2013-07-05 17:20:44,193 INFO [StoreOpener-8fc72926a45d003c17fe5d97b1035dce-1]
compactions.CompactionConfiguration(85): size [134217728, 9223372036854775807);
files [3, 10); ratio 1.200000; off-peak ratio 5.000000; throttle point
2684354560; delete expired; major period 604800000, major jitter 0.500000
2013-07-05 17:20:44,193 DEBUG [MASTER_OPEN_REGION-hemera:55016-4]
handler.OpenedRegionHandler(145): Handling OPENED event for
e19053253da9715d44636c143da61025 from hemera.apache.org,33306,1373044528830;
deleting unassigned node
2013-07-05 17:20:44,193 DEBUG [MASTER_OPEN_REGION-hemera:55016-4]
zookeeper.ZKAssign(405): master:55016-0x13fafd4d4010000 Deleting existing
unassigned node for e19053253da9715d44636c143da61025 that is in expected state
RS_ZK_REGION_OPENED
2013-07-05 17:20:44,200 DEBUG [pool-1-thread-1-EventThread]
zookeeper.ZooKeeperWatcher(307): master:55016-0x13fafd4d4010000 Received
ZooKeeper Event, type=NodeDeleted, state=SyncConnected,
path=/hbase/region-in-transition/e19053253da9715d44636c143da61025
2013-07-05 17:20:44,200 DEBUG [pool-1-thread-1-EventThread]
zookeeper.ZooKeeperWatcher(307): master:55016-0x13fafd4d4010000 Received
ZooKeeper Event, type=NodeChildrenChanged, state=SyncConnected,
path=/hbase/region-in-transition
2013-07-05 17:20:44,200 DEBUG [hbase-am-zkevent-worker-pool-2-thread-34]
master.AssignmentManager$4(1219): The znode of region
TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.
has been deleted, region state: {e19053253da9715d44636c143da61025 state=OPEN,
ts=1373044844193, server=hemera.apache.org,33306,1373044528830}
2013-07-05 17:20:44,201 INFO [hbase-am-zkevent-worker-pool-2-thread-34]
master.RegionStates(301): Onlined region e19053253da9715d44636c143da61025 on
hemera.apache.org,33306,1373044528830
2013-07-05 17:20:44,201 DEBUG [MASTER_OPEN_REGION-hemera:55016-4]
zookeeper.ZKAssign(434): master:55016-0x13fafd4d4010000 Successfully deleted
unassigned node for region e19053253da9715d44636c143da61025 in expected state
RS_ZK_REGION_OPENED
2013-07-05 17:20:44,201 INFO [hbase-am-zkevent-worker-pool-2-thread-34]
master.AssignmentManager$4(1224): The master has opened the region
TestHBACloseRegionWhenServerNameIsEmpty,,1373044843904.e19053253da9715d44636c143da61025.
that was online on hemera.apache.org,33306,1373044528830
{code}
> TestAdmin.testCloseRegionWhenServerNameIsEmpty failed with
> ArrayIndexOutOfBoundsException
> -----------------------------------------------------------------------------------------
>
> Key: HBASE-8883
> URL: https://issues.apache.org/jira/browse/HBASE-8883
> Project: HBase
> Issue Type: Bug
> Reporter: stack
> Assignee: stack
> Fix For: 0.95.2
>
>
> From
> https://builds.apache.org/job/HBase-TRUNK/4218/testReport/org.apache.hadoop.hbase.client/TestAdmin/testCloseRegionWhenServerNameIsEmpty/
> {code}
> java.lang.ArrayIndexOutOfBoundsException: -1
> at
> java.util.concurrent.CopyOnWriteArrayList.get(CopyOnWriteArrayList.java:343)
> at java.util.Collections$UnmodifiableList.get(Collections.java:1152)
> at
> org.apache.hadoop.hbase.HBaseTestingUtility.getRSForFirstRegionInTable(HBaseTestingUtility.java:1649)
> at
> org.apache.hadoop.hbase.client.TestAdmin.testCloseRegionWhenServerNameIsEmpty(TestAdmin.java:1401)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> at java.lang.reflect.Method.invoke(Method.java:597)
> at
> org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
> at
> org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
> at
> org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
> at
> org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
> at
> org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> at
> org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
> at
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
> at
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
> at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
> at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
> at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
> at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
> at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
> at
> org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> at
> org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
> at org.junit.runners.Suite.runChild(Suite.java:127)
> at org.junit.runners.Suite.runChild(Suite.java:26)
> at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:439)
> at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
> at java.lang.Thread.run(Thread.java:662)
> {code}
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
