Return-Path: X-Original-To: apmail-hbase-user-archive@www.apache.org Delivered-To: apmail-hbase-user-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 54FA511B02 for ; Tue, 3 Jun 2014 22:59:40 +0000 (UTC) Received: (qmail 36367 invoked by uid 500); 3 Jun 2014 22:59:38 -0000 Delivered-To: apmail-hbase-user-archive@hbase.apache.org Received: (qmail 36297 invoked by uid 500); 3 Jun 2014 22:59:38 -0000 Mailing-List: contact user-help@hbase.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: user@hbase.apache.org Delivered-To: mailing list user@hbase.apache.org Received: (qmail 36281 invoked by uid 99); 3 Jun 2014 22:59:38 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 03 Jun 2014 22:59:38 +0000 X-ASF-Spam-Status: No, hits=1.5 required=5.0 tests=HTML_MESSAGE,NORMAL_HTTP_TO_IP,RCVD_IN_DNSWL_LOW,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (athena.apache.org: domain of saint.ack@gmail.com designates 209.85.215.43 as permitted sender) Received: from [209.85.215.43] (HELO mail-la0-f43.google.com) (209.85.215.43) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 03 Jun 2014 22:59:33 +0000 Received: by mail-la0-f43.google.com with SMTP id mc6so3881737lab.2 for ; Tue, 03 Jun 2014 15:59:11 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=mime-version:sender:in-reply-to:references:date:message-id:subject :from:to:content-type; bh=cvBT2awQPSu7Carix9gh8u7hZ8ciMuyYYcFizKI94bo=; b=EksotyFnpik83B5aIu7Q6PWx9oA+aAmoTJ9dh6Pr8IOzlCC+Uf9/Z+vD7hS0/IZjWB PmAnnYJrlFEcQ1uYdHbVkBB27TEDv64m09OpKLEphpQuzOb5lgdLi91oNFj7zEtEtn9M bLkUEu1qcpqnRTPwSP2Z8Q0h7TNXOcRnpFkKoa0ka7xTbXCFwqdvVMLQdeS2SpNw064d 1eZzwU8p/xebeW8Oy8VatjiIIYt6rvti3ksBFBQ2TI2nI5+tZh4AkqM0FsfGawPqkgJF tFGMIotqb9YR4yB2U0zc+zkQ459yldGpA4iYaMwmSOzcLbybDbSL8RrtK1eGBlx31HV0 VqDg== MIME-Version: 1.0 X-Received: by 10.152.21.130 with SMTP id v2mr37166669lae.1.1401836351493; Tue, 03 Jun 2014 15:59:11 -0700 (PDT) Sender: saint.ack@gmail.com Received: by 10.152.2.38 with HTTP; Tue, 3 Jun 2014 15:59:11 -0700 (PDT) In-Reply-To: <1504774.KXWsJz3jRX@localhost.localdomain> References: <45221639.aLRyQlZNKx@localhost.localdomain> <1767788.GEDGuJvNTJ@localhost.localdomain> <1504774.KXWsJz3jRX@localhost.localdomain> Date: Tue, 3 Jun 2014 15:59:11 -0700 X-Google-Sender-Auth: -I8YJ6PaSkvlnKX8iY4YlNLxs5s Message-ID: Subject: Re: regionserver crash after node decomission From: Stack To: Hbase-User Content-Type: multipart/alternative; boundary=089e0158b6c6c39ed204faf67590 X-Virus-Checked: Checked by ClamAV on apache.org --089e0158b6c6c39ed204faf67590 Content-Type: text/plain; charset=UTF-8 On Tue, Jun 3, 2014 at 9:18 AM, Ian Brooks wrote: > Hi, > > Well checking the hadoop logs shows the datanode restarting at that time. > looks like a rouge puppet config decided to restart the datanode. > > That said, should the regionserver not account for this and request the > data from another datanode? > Yes. Should. Something is odd in DFSClient when we get: org.apache.hadoop.ipc.RemoteException(java.lang. ArrayIndexOutOfBoundsException): 0 when it seems like there are plenty of replicas still according to: DFSClient: Error Recovery for block BP-2121456822-10.143.38.149- 1396953188241:blk_1074073683_332932 in pipeline 10.143.38.117:50010, 10.143.38.116:50010, 10.143.38.100:50010: bad datanode 10.143.38.117:50010 2014-06-03 13:05:03,915 WARN [DataStreamer for file /user/hbase/WALs/############,16020,1401716790638/########## ##%2C16020%2C1401716790638.1401796562200 block BP-2121456822-10.143.38.149- 1396953188241:blk_1074073683_332932] hdfs.DFSClient: DataStreamer Exception You can make is happen easily? It happened to you twice? St.Ack -Ian Brooks On Tuesday 03 Jun 2014 08:35:05 Stack wrote: > Anything in the namenode logs Ian? Its like we've run out of replicas. We > see this: > > 2014-06-03 13:05:03,898 WARN [DataStreamer for file > /user/hbase/WALs/############,16020,1401716790638/########## > ##%2C16020%2C1401716790638.1401796562200 block BP-2121456822-10.143.38.149- > 1396953188241:blk_1074073683_332932] hdfs.DFSClient: Error Recovery for > block BP-2121456822-10.143.38.149-1396953188241:blk_1074073683_332932 in > pipeline 10.143.38.117:50010, 10.143.38.116:50010, 10.143.38.100:50010: bad > datanode 10.143.38.117:50010 > > I presume 10.143.38.117:50010 is the local datanode? We should just be > moving on to 10.143.38.116:50010 but array index out of bounds with size of > 0. > > When is this happening? When you close the RS after all regions have been > moved off? Anything earlier in the log? > > St.Ack > > > > > On Tue, Jun 3, 2014 at 6:12 AM, Ian Brooks wrote: > > > Hi, > > > > For my testing i'm only taking one server out, ( simulating process for > > patching etc. ). The hadoop datanode process was left running at this point. > > > > -Ian Brooks > > > > On Tuesday 03 Jun 2014 06:06:33 Ted Yu wrote: > > > Please see http://hbase.apache.org/book/node.management.html > > > Especially 15.3.1.1 > > > > > > Did you stop the datanode on that server or let datanode run ? > > > > > > Cheers > > > > > > On Jun 3, 2014, at 5:47 AM, Ian Brooks wrote: > > > > > > > Hi, > > > > > > > > I've been working on testing the procedures for taking a node out of > > service in our test cluster and have come accross the same problem in both > > attempts now whereby once a regionserver has been shutdown, 5-10 minutes > > later most of the other regionservers crash with the below stack trace > > > > > > > > The procedure im using for taking the first node out is, > > > > > > > > 1. turn off balancer > > > > 2. use region_mover.rb to unload all regions from the target serve > > > > 3.once all regions have been moved, stop the region server > > > > > > > > I'm using hbase 0.96.1 on hadoop 2.3.0, any ideas why this is > > happening and how to stop it from happening? > > > > > > > > > > > > 2014-06-03 13:05:03,897 WARN [ResponseProcessor for block > > BP-2121456822-10.143.38.149-1396953188241:blk_1074073683_332932] > > hdfs.DFSClient: DFSOutputStream ResponseProcessor exception for block > > BP-2121456822-10.143.38.149-1396953188241:blk_1074073683_332932 > > > > java.io.EOFException: Premature EOF: no length prefix available > > > > at > > org.apache.hadoop.hdfs.protocolPB.PBHelper.vintPrefixed(PBHelper.java:1492) > > > > at > > org.apache.hadoop.hdfs.protocol.datatransfer.PipelineAck.readFields(PipelineAck.java:116) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer$ResponseProcessor.run(DFSOutputStream.java:721) > > > > 2014-06-03 13:05:03,898 WARN [DataStreamer for file > > /user/hbase/WALs/############,16020,1401716790638/############%2C16020%2C1401716790638.1401796562200 > > block BP-2121456822-10.143.38.149-1396953188241:blk_1074073683_332932] > > hdfs.DFSClient: Error Recovery for block > > BP-2121456822-10.143.38.149-1396953188241:blk_1074073683_332932 in pipeline > > 10.143.38.117:50010, 10.143.38.116:50010, 10.143.38.100:50010: bad > > datanode 10.143.38.117:50010 > > > > 2014-06-03 13:05:03,915 WARN [DataStreamer for file > > /user/hbase/WALs/############,16020,1401716790638/############%2C16020%2C1401716790638.1401796562200 > > block BP-2121456822-10.143.38.149-1396953188241:blk_1074073683_332932] > > hdfs.DFSClient: DataStreamer Exception > > > > > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,489 ERROR [RpcServer.handler=22,port=16020] > > wal.FSHLog: syncer encountered error, will retry. txid=211 > > > > > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,489 FATAL [RpcServer.handler=22,port=16020] > > wal.FSHLog: Could not sync. Requesting roll of hlog > > > > > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,490 DEBUG [regionserver16020.logRoller] > > regionserver.LogRoller: HLog roll requested > > > > 2014-06-03 13:05:48,490 DEBUG [RpcServer.handler=22,port=16020] > > regionserver.HRegion: rollbackMemstore rolled back 1 keyvalues from start:0 > > to end:1 > > > > 2014-06-03 13:05:48,609 DEBUG [regionserver16020.logRoller] > > wal.FSHLog: cleanupCurrentWriter waiting for transactions to get synced > > total 211 synced till here 210 > > > > 2014-06-03 13:05:48,609 FATAL [regionserver16020.logRoller] > > wal.FSHLog: Could not sync. Requesting roll of hlog > > > > > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,609 ERROR [regionserver16020.logRoller] > > wal.FSHLog: Failed close of HLog writer > > > > > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,610 FATAL [regionserver16020.logRoller] > > regionserver.HRegionServer: ABORTING region server > > ############,16020,1401716790638: Failed log close in log roller > > > > org.apache.hadoop.hbase.regionserver.wal.FailedLogCloseException: > > #1401796562200 > > > > at > > org.apache.hadoop.hbase.regionserver.wal.FSHLog.cleanupCurrentWriter(FSHLog.java:707) > > > > at > > org.apache.hadoop.hbase.regionserver.wal.FSHLog.rollWriter(FSHLog.java:538) > > > > at > > org.apache.hadoop.hbase.regionserver.LogRoller.run(LogRoller.java:96) > > > > at java.lang.Thread.run(Thread.java:744) > > > > Caused by: > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,610 FATAL [regionserver16020.logRoller] > > regionserver.HRegionServer: RegionServer abort: loaded coprocessors are: > > [org.apache.hadoop.hbase.security.access.AccessController, > > org.apache.hadoop.hbase.security.token.TokenProvider] > > > > 2014-06-03 13:05:48,612 ERROR [RpcServer.handler=21,port=16020] > > wal.FSHLog: syncer encountered error, will retry. txid=212 > > > > > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,612 FATAL [RpcServer.handler=21,port=16020] > > wal.FSHLog: Could not sync. Requesting roll of hlog > > > > > > org.apache.hadoop.ipc.RemoteException(java.lang.ArrayIndexOutOfBoundsException): > > 0 > > > > at > > org.apache.hadoop.hdfs.server.blockmanagement.DatanodeManager.getDatanodeStorageInfos(DatanodeManager.java:467) > > > > at > > org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalDatanode(FSNamesystem.java:2779) > > > > at > > org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.getAdditionalDatanode(NameNodeRpcServer.java:594) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolServerSideTranslatorPB.java:430) > > > > at > > org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:585) > > > > at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:928) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1962) > > > > at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1958) > > > > at java.security.AccessController.doPrivileged(Native Method) > > > > at javax.security.auth.Subject.doAs(Subject.java:415) > > > > at > > org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1548) > > > > at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1956) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1347) > > > > at org.apache.hadoop.ipc.Client.call(Client.java:1300) > > > > at > > org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:206) > > > > at com.sun.proxy.$Proxy13.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.getAdditionalDatanode(ClientNamenodeProtocolTranslatorPB.java:352) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:186) > > > > at > > org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) > > > > at com.sun.proxy.$Proxy14.getAdditionalDatanode(Unknown Source) > > > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > > > at > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > > > > at > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > > at java.lang.reflect.Method.invoke(Method.java:606) > > > > at > > org.apache.hadoop.hbase.fs.HFileSystem$1.invoke(HFileSystem.java:266) > > > > at com.sun.proxy.$Proxy15.getAdditionalDatanode(Unknown Source) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.addDatanode2ExistingPipeline(DFSOutputStream.java:919) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.setupPipelineForAppendOrRecovery(DFSOutputStream.java:1031) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.processDatanodeError(DFSOutputStream.java:823) > > > > at > > org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:475) > > > > 2014-06-03 13:05:48,612 DEBUG [RpcServer.handler=21,port=16020] > > regionserver.HRegion: rollbackMemstore rolled back 1 keyvalues from start:0 > > to end:1 > > > > 2014-06-03 13:05:48,624 INFO [regionserver16020.logRoller] > > regionserver.HRegionServer: STOPPED: Failed log close in log roller > > > > 2014-06-03 13:05:48,624 INFO [regionserver16020.logRoller] > > regionserver.LogRoller: LogRoller exiting. > > > > 2014-06-03 13:05:48,624 INFO [regionserver16020] ipc.RpcServer: > > Stopping server on 16020 > > > > 2014-06-03 13:05:48,624 INFO [RpcServer.handler=1,port=16020] > > ipc.RpcServer: RpcServer.handler=1,port=16020: exiting > > > > > > > > > > > > > -- > > -Ian Brooks > > Senior server administrator - Sensewhere > > -- -Ian Brooks Senior server administrator - Sensewhere --089e0158b6c6c39ed204faf67590--