[ZOOKEEPER-1043] Looped NPE at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:244)

• Type: Bug
• Status: Open
• Priority: Major
• Resolution: Unresolved
• Affects Version/s: 3.3.3, 3.4.6

Environment

Sparc Solaris 10 and 11
Java 6u17 64 bits
5 nodes ensemble

• Assignee: Unassigned
• Reporter: César Álvarez Núñez
• Created: 04/Apr/11 15:34
• Updated: 28/Aug/15 20:03

Description

I'm sorry but I only have this log (which belongs to a "follower" node) and a previous message Unexpected NodeCreated event after a reconnection. where I describe a potential side-effect at client side.

2011-04-04 09:31:09,608 - INFO  [Snapshot Thread:FileTxnSnapLog@208][] - Snapshotting: 1700527e36
2011-04-04 09:31:09,653 - INFO  [SyncThread:1:FileTxnLog@197][] - Creating new log file: log.1700527e38
2011-04-04 10:13:39,287 - INFO  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@251][] - Accepted socket connection from /XXX.XXX.XXX.69:1093
2011-04-04 10:13:39,371 - INFO  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn@777][] - Client attempting to establish new session at /XXX.XXX.XXX.69:1093
2011-04-04 10:13:39,376 - INFO  [CommitProcessor:1:NIOServerCnxn@1580][] - Established session 0x12ee79c4a720022 with negotiated timeout 20000 for client /XXX.XXX.XXX.69:1093
2011-04-04 12:04:11,131 - INFO  [SyncThread:1:FileTxnLog@197][] - Creating new log file: log.170053bf15
2011-04-04 12:04:11,131 - INFO  [Snapshot Thread:FileTxnSnapLog@208][] - Snapshotting: 170053bf17
2011-04-04 12:13:10,779 - INFO  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@251][] - Accepted socket connection from /XXX.XXX.XXX.63:1817
2011-04-04 12:13:10,790 - INFO  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn@777][] - Client attempting to establish new session at /XXX.XXX.XXX.63:1817
2011-04-04 12:13:10,794 - INFO  [CommitProcessor:1:NIOServerCnxn@1580][] - Established session 0x12ee79c4a720023 with negotiated timeout 20000 for client /XXX.XXX.XXX.63:1817
2011-04-04 12:13:10,814 - WARN  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn@634][] - EndOfStreamException: Unable to read additional data from client sessionid 0x12ee79c4a720023, likely client has closed socket
2011-04-04 12:13:10,816 - INFO  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn@1435][] - Closed socket connection for client /XXX.XXX.XXX.63:1817 which had sessionid 0x12ee79c4a720023
2011-04-04 12:13:10,839 - INFO  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@251][] - Accepted socket connection from /XXX.XXX.XXX.63:1814
2011-04-04 12:13:10,840 - WARN  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@274][] - Ignoring exception
java.net.SocketException: Invalid argument
        at sun.nio.ch.Net.setIntOption0(Native Method)
        at sun.nio.ch.Net.setIntOption(Unknown Source)
        at sun.nio.ch.SocketChannelImpl$1.setInt(Unknown Source)
        at sun.nio.ch.SocketOptsImpl.setBoolean(Unknown Source)
        at sun.nio.ch.SocketOptsImpl$IP$TCP.noDelay(Unknown Source)
        at sun.nio.ch.OptionAdaptor.setTcpNoDelay(Unknown Source)
        at sun.nio.ch.SocketAdaptor.setTcpNoDelay(Unknown Source)
        at org.apache.zookeeper.server.NIOServerCnxn.<init>(NIOServerCnxn.java:1367)
        at org.apache.zookeeper.server.NIOServerCnxn$Factory.createConnection(NIOServerCnxn.java:215)
        at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:256)
2011-04-04 12:13:10,841 - WARN  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@272][] - Ignoring unexpected runtime exception
java.lang.NullPointerException
        at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:244)
2011-04-04 12:13:10,841 - WARN  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@272][] - Ignoring unexpected runtime exception
java.lang.NullPointerException
        at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:244)
2011-04-04 12:13:10,842 - WARN  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@272][] - Ignoring unexpected runtime exception
java.lang.NullPointerException
        at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:244)
...
...
...
2011-04-04 16:49:23,101 - WARN  [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2301:NIOServerCnxn$Factory@272][] - Ignoring unexpected runtime exception
java.lang.NullPointerException
        at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:244)