Re: Errors during shutdown/startup of ZooKeeper

2009-06-02 Thread Mahadev Konar
Hi Nitay,
  This is not an error but should be a warning. I have opened up a jira for
it.

http://issues.apache.org/jira/browse/ZOOKEEPER-428


The message just says that a client is connecting to a server that is behind
that a server is was connected to earlier. The log should be warn and not
error and should be fixed in the next release.

mahadev

On 6/2/09 2:12 PM, Nitay nit...@gmail.com wrote:

 Hey guys,
 
 We are getting a lot of messages like this in HBase:
 
 [junit] 2009-06-02 11:57:23,658 ERROR [NIOServerCxn.Factory:21810]
 server.NIOServerCnxn(514): Client has seen zxid 0xe our last zxid is 0xd
 
 For more context, the block it usually appears in is:
 
 [junit] 2009-06-02 13:27:54,083 INFO  [main-SendThread]
 zookeeper.ClientCnxn$SendThread(737): Priming connection to
 java.nio.channels.SocketChannel[connected local=/0:0:0:0:0:0:0:1%0:56511
 remote=localhost/0:0:0:0:0:0:0:1:21810]
 [junit] 2009-06-02 13:27:54,084 INFO  [main-SendThread]
 zookeeper.ClientCnxn$SendThread(889): Server connection successful
 [junit] 2009-06-02 13:27:54,093 INFO  [NIOServerCxn.Factory:21810]
 server.NIOServerCnxn(532): Connected to /0:0:0:0:0:0:0:1%0:56511 lastZxid 16
 [junit] 2009-06-02 13:27:54,094 ERROR [NIOServerCxn.Factory:21810]
 server.NIOServerCnxn(543): Client has seen zxid 0x10 our last zxid is 0x4
 [junit] 2009-06-02 13:27:54,094 WARN  [NIOServerCxn.Factory:21810]
 server.NIOServerCnxn(444): Exception causing close of session 0x0 due to
 java.io.IOException: Client has seen zxid 0x10 our last zxid is 0x4
 [junit] 2009-06-02 13:27:54,094 DEBUG [NIOServerCxn.Facto777ry:21810]
 server.NIOServerCnxn(447): IOException stack trace
 [junit] java.io.IOException: Client has seen zxid 0x10 our last zxid is
 0x4
 [junit] at
 org.apache.zookeeper.server.NIOServerCnxn.readConnectRequest(NIOServerCnxn.jav
 a:544)
 [junit] at
 org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:331)
 [junit] at
 org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:176)
 [junit] 2009-06-02 13:27:54,094 INFO  [NIOServerCxn.Factory:21810]
 server.NIOServerCnxn(777): closing session:0x0 NIOServerCnxn:
 java.nio.channels.SocketChannel[connected local=/0:0:0:0:0:0:0:1%0:21810
 remote=/0:0:0:0:0:0:0:1%0:56511]
 [junit] 2009-06-02 13:27:54,097 WARN  [main-SendThread]
 zookeeper.ClientCnxn$SendThread(919): Exception closing session
 0x121a2a7c43a0002 to sun.nio.ch.selectionkeyi...@2c662b4e
 [junit] java.io.IOException: Read error rc = -1
 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
 [junit] at
 org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:653)
 [junit] at
 org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:897)
 [junit] 2009-06-02 13:27:54,097 WARN  [main-SendThread]
 zookeeper.ClientCnxn$SendThread(953): Ignoring exception during shutdown
 input
 [junit] java.net.SocketException: Socket is not connected
 [junit] at sun.nio.ch.SocketChannelImpl.shutdown(Native Method)
 [junit] at
 sun.nio.ch.SocketChannelImpl.shutdownInput(SocketChannelImpl.java:640)
 [junit] at
 sun.nio.ch.SocketAdaptor.shutdownInput(SocketAdaptor.java:360)
 [junit] at
 org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:951)
 [junit] at
 org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:922)
 
 
 This happens in a seemingly endless loop. We are not quite sure what it
 means. Can someone help shed some light on these messages?
 
 Thanks,
 -n



Re: Errors during shutdown/startup of ZooKeeper

2009-06-02 Thread Nitay
I see. That helps. However, even as warnings, these go on seemingly
endlessly. Why do they not get fixed by themselves? What are we doing wrong
here?

On Tue, Jun 2, 2009 at 2:24 PM, Mahadev Konar maha...@yahoo-inc.com wrote:

 Hi Nitay,
  This is not an error but should be a warning. I have opened up a jira for
 it.

 http://issues.apache.org/jira/browse/ZOOKEEPER-428


 The message just says that a client is connecting to a server that is
 behind
 that a server is was connected to earlier. The log should be warn and not
 error and should be fixed in the next release.

 mahadev

 On 6/2/09 2:12 PM, Nitay nit...@gmail.com wrote:

  Hey guys,
 
  We are getting a lot of messages like this in HBase:
 
  [junit] 2009-06-02 11:57:23,658 ERROR [NIOServerCxn.Factory:21810]
  server.NIOServerCnxn(514): Client has seen zxid 0xe our last zxid is 0xd
 
  For more context, the block it usually appears in is:
 
  [junit] 2009-06-02 13:27:54,083 INFO  [main-SendThread]
  zookeeper.ClientCnxn$SendThread(737): Priming connection to
  java.nio.channels.SocketChannel[connected local=/0:0:0:0:0:0:0:1%0:56511
  remote=localhost/0:0:0:0:0:0:0:1:21810]
  [junit] 2009-06-02 13:27:54,084 INFO  [main-SendThread]
  zookeeper.ClientCnxn$SendThread(889): Server connection successful
  [junit] 2009-06-02 13:27:54,093 INFO  [NIOServerCxn.Factory:21810]
  server.NIOServerCnxn(532): Connected to /0:0:0:0:0:0:0:1%0:56511 lastZxid
 16
  [junit] 2009-06-02 13:27:54,094 ERROR [NIOServerCxn.Factory:21810]
  server.NIOServerCnxn(543): Client has seen zxid 0x10 our last zxid is 0x4
  [junit] 2009-06-02 13:27:54,094 WARN  [NIOServerCxn.Factory:21810]
  server.NIOServerCnxn(444): Exception causing close of session 0x0 due to
  java.io.IOException: Client has seen zxid 0x10 our last zxid is 0x4
  [junit] 2009-06-02 13:27:54,094 DEBUG [NIOServerCxn.Facto777ry:21810]
  server.NIOServerCnxn(447): IOException stack trace
  [junit] java.io.IOException: Client has seen zxid 0x10 our last zxid
 is
  0x4
  [junit] at
 
 org.apache.zookeeper.server.NIOServerCnxn.readConnectRequest(NIOServerCnxn.jav
  a:544)
  [junit] at
  org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:331)
  [junit] at
 
 org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:176)
  [junit] 2009-06-02 13:27:54,094 INFO  [NIOServerCxn.Factory:21810]
  server.NIOServerCnxn(777): closing session:0x0 NIOServerCnxn:
  java.nio.channels.SocketChannel[connected local=/0:0:0:0:0:0:0:1%0:21810
  remote=/0:0:0:0:0:0:0:1%0:56511]
  [junit] 2009-06-02 13:27:54,097 WARN  [main-SendThread]
  zookeeper.ClientCnxn$SendThread(919): Exception closing session
  0x121a2a7c43a0002 to sun.nio.ch.selectionkeyi...@2c662b4e
  [junit] java.io.IOException: Read error rc = -1
  java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
  [junit] at
  org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:653)
  [junit] at
  org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:897)
  [junit] 2009-06-02 13:27:54,097 WARN  [main-SendThread]
  zookeeper.ClientCnxn$SendThread(953): Ignoring exception during shutdown
  input
  [junit] java.net.SocketException: Socket is not connected
  [junit] at sun.nio.ch.SocketChannelImpl.shutdown(Native Method)
  [junit] at
  sun.nio.ch.SocketChannelImpl.shutdownInput(SocketChannelImpl.java:640)
  [junit] at
  sun.nio.ch.SocketAdaptor.shutdownInput(SocketAdaptor.java:360)
  [junit] at
  org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:951)
  [junit] at
  org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:922)
 
 
  This happens in a seemingly endless loop. We are not quite sure what it
  means. Can someone help shed some light on these messages?
 
  Thanks,
  -n