JPPF Issue Tracker
star_faded.png
Please log in to bookmark issues
bug_report_small.png
CLOSED  Bug report JPPF-339  -  Driver log full of null pointer exception traces.
Posted Oct 16, 2014 - updated Oct 26, 2014
icon_info.png This issue has been closed with status "Closed" and resolution "RESOLVED".
Issue details
  • Type of issue
    Bug report
  • Status
     
    Closed
  • Assigned to
     lolo4j
  • Progress
       
  • Type of bug
    Not triaged
  • Likelihood
    Not triaged
  • Effect
    Not triaged
  • Posted by
     zorba128
  • Owned by
    Not owned by anyone
  • Category
    Server
  • Resolution
    RESOLVED
  • Priority
    Normal
  • Reproducability
    Not determined
  • Severity
    Normal
  • Targetted for
    icon_milestones.png JPPF 4.2.3
Issue description
After opening driver log I've found that it is full of NPEs - entries like below are repeated every second. Log dump is from fresh started driver.
2014-10-16 09:02:06,965 [DEBUG][org.jppf.nio.NioServer.run(516)]: accepting channel java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487], ssl=false
2014-10-16 09:02:06,965 [DEBUG][org.jppf.nio.NioServer.accept(313)]: Thread[Acceptor,5,main] performing accept() of channel java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487], ssl=false
2014-10-16 09:02:06,965 [DEBUG][org.jppf.server.nio.acceptor.AcceptorNioServer.postAccept(77)]: accepted channel Socket[addr=/192.168.92.214,port=62487,localport=11111]
2014-10-16 09:02:06,966 [DEBUG][org.jppf.nio.PlainNioObject.read(97)]: read 4 bytes for PlainNioObject[channel id=37, size=4, count=4, source=ChannelInputSource[channel=java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487]], dest=null, location=MultipleBuffersLocation[size=4, count=4, currentBuffer=org.jppf.utils.JPPFBuffer@7d3f293a, currentBufferIndex=0, transferring=false, list=[org.jppf.utils.JPPFBuffer@7d3f293a]]]
2014-10-16 09:02:06,966 [DEBUG][org.jppf.server.nio.acceptor.IdentifyingPeerState.performTransition(72)]: read identifier 'NODE_CLASSLOADER_CHANNEL' for SelectionKeyWrapper[id=37, readyOps=1, interestOps=0, context=AcceptorContext[channel=SelectionKeyWrapper[id=37], state=IDENTIFYING_PEER, uuid=null, connectionUuid=null, peer=false, ssl=false]]
2014-10-16 09:02:06,966 [DEBUG][org.jppf.server.nio.acceptor.IdentifyingPeerState.performTransition(91)]: cancelling key for SelectionKeyWrapper[id=37, readyOps=1, interestOps=0, context=AcceptorContext[channel=SelectionKeyWrapper[id=37], state=IDENTIFYING_PEER, uuid=null, connectionUuid=null, peer=false, ssl=false]]
2014-10-16 09:02:06,966 [DEBUG][org.jppf.server.nio.acceptor.IdentifyingPeerState.performTransition(95)]: registering channel with new server Thread[NodeClassServer,5,main]
2014-10-16 09:02:06,966 [DEBUG][org.jppf.nio.NioServer.accept(313)]: Thread[NodeClassServer,5,main] performing accept() of channel java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487], ssl=false
2014-10-16 09:02:06,967 [DEBUG][org.jppf.server.nio.acceptor.IdentifyingPeerState.performTransition(97)]: channel registered: SelectionKeyWrapper[id=38, readyOps=1, interestOps=1, context=ClassContext[channel=SelectionKeyWrapper[id=38], state=WAITING_INITIAL_NODE_REQUEST, resource=null, pendingResponses=0, type=node, peer=false, uuid=null, secure=false, ssl=false]]
2014-10-16 09:02:06,967 [DEBUG][org.jppf.nio.PlainNioObject.read(97)]: read 4 bytes for PlainNioObject[channel id=38, size=4, count=4, source=ChannelInputSource[channel=java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487]], dest=null, location=MultipleBuffersLocation[size=4, count=4, currentBuffer=org.jppf.utils.JPPFBuffer@6d81ab20, currentBufferIndex=0, transferring=false, list=[org.jppf.utils.JPPFBuffer@6d81ab20]]]
2014-10-16 09:02:06,967 [DEBUG][org.jppf.nio.PlainNioObject.read(97)]: read 661 bytes for PlainNioObject[channel id=38, size=661, count=661, source=ChannelInputSource[channel=java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487]], dest=null, location=MultipleBuffersLocation[size=661, count=661, currentBuffer=org.jppf.utils.JPPFBuffer@4bcef75e, currentBufferIndex=0, transferring=false, list=[org.jppf.utils.JPPFBuffer@4bcef75e]]]
2014-10-16 09:02:06,968 [DEBUG][org.jppf.server.nio.classloader.node.WaitingInitialNodeRequestState.performTransition(73)]: read initial request from node SelectionKeyWrapper[id=38, readyOps=1, interestOps=0, context=ClassContext[channel=SelectionKeyWrapper[id=38], state=WAITING_INITIAL_NODE_REQUEST, resource=null, pendingResponses=0, type=node, peer=false, uuid=null, secure=false, ssl=false]]
2014-10-16 09:02:06,968 [DEBUG][org.jppf.server.nio.classloader.node.WaitingInitialNodeRequestState.performTransition(76)]: initiating node: SelectionKeyWrapper[id=38, readyOps=1, interestOps=0, context=ClassContext[channel=SelectionKeyWrapper[id=38], state=WAITING_INITIAL_NODE_REQUEST, resource=null, pendingResponses=0, type=node, peer=false, uuid=null, secure=false, ssl=false]]
2014-10-16 09:02:06,969 [DEBUG][org.jppf.nio.StateTransitionManager.transitionChannel(146)]: transition from WAITING_INITIAL_NODE_REQUEST to SENDING_INITIAL_NODE_RESPONSE with ops=5 (readyOps=1) for channel id=38, submit=false
2014-10-16 09:02:06,969 [DEBUG][org.jppf.nio.PlainNioObject.write(126)]: read 4 bytes for PlainNioObject[channel id=38, size=4, count=4, source=null, dest=ChannelOutputDestination[channel=java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487]], location=MultipleBuffersLocation[size=4, count=4, currentBuffer=null, currentBufferIndex=0, transferring=false, list=[org.jppf.utils.JPPFBuffer@5903ba36]]]
2014-10-16 09:02:06,970 [DEBUG][org.jppf.nio.PlainNioObject.write(126)]: read 665 bytes for PlainNioObject[channel id=38, size=665, count=665, source=null, dest=ChannelOutputDestination[channel=java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487]], location=MultipleBuffersLocation[size=665, count=665, currentBuffer=null, currentBufferIndex=0, transferring=false, list=[org.jppf.utils.JPPFBuffer@71e2d49f]]]
2014-10-16 09:02:06,970 [DEBUG][org.jppf.server.nio.classloader.node.SendingInitialNodeResponseState.performTransition(70)]: sent uuid=732130A8-1F85-B1E8-4E37-0E6F1AB306C0 to node SelectionKeyWrapper[id=38, readyOps=4, interestOps=0, context=ClassContext[channel=SelectionKeyWrapper[id=38], state=SENDING_INITIAL_NODE_RESPONSE, resource=null, pendingResponses=0, type=node, peer=false, uuid=null, secure=false, ssl=false]]
2014-10-16 09:02:06,970 [DEBUG][org.jppf.nio.StateTransitionManager.transitionChannel(146)]: transition from SENDING_INITIAL_NODE_RESPONSE to WAITING_NODE_REQUEST with ops=1 (readyOps=4) for channel id=38, submit=false
2014-10-16 09:02:06,971 [DEBUG][org.jppf.nio.PlainNioObject.read(97)]: read 4 bytes for PlainNioObject[channel id=38, size=4, count=4, source=ChannelInputSource[channel=java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487]], dest=null, location=MultipleBuffersLocation[size=4, count=4, currentBuffer=org.jppf.utils.JPPFBuffer@7fb978b7, currentBufferIndex=0, transferring=false, list=[org.jppf.utils.JPPFBuffer@7fb978b7]]]
2014-10-16 09:02:06,971 [DEBUG][org.jppf.nio.PlainNioObject.read(97)]: read 891 bytes for PlainNioObject[channel id=38, size=891, count=891, source=ChannelInputSource[channel=java.nio.channels.SocketChannel[connected local=/192.168.92.207:11111 remote=/192.168.92.214:62487]], dest=null, location=MultipleBuffersLocation[size=891, count=891, currentBuffer=org.jppf.utils.JPPFBuffer@68657d31, currentBufferIndex=0, transferring=false, list=[org.jppf.utils.JPPFBuffer@68657d31]]]
2014-10-16 09:02:06,971 [DEBUG][org.jppf.nio.StateTransitionTask.run(89)]: error on channel SelectionKeyWrapper[id=38, readyOps=1, interestOps=0, context=ClassContext[channel=SelectionKeyWrapper[id=38], state=WAITING_NODE_REQUEST, resource=null, pendingResponses=0, type=node, peer=false, uuid=null, secure=false, ssl=false]] : java.lang.NullPointerException
  at org.jppf.classloader.JPPFResourceWrapper.hashCode(JPPFResourceWrapper.java:377)
  at java.util.HashMap.hash(Unknown Source)
  at java.util.HashMap.put(Unknown Source)
  at java.util.HashSet.readObject(Unknown Source)
  at sun.reflect.GeneratedMethodAccessor5.invoke(Unknown Source)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
  at java.lang.reflect.Method.invoke(Unknown Source)
  at java.io.ObjectStreamClass.invokeReadObject(Unknown Source)
  at java.io.ObjectInputStream.readSerialData(Unknown Source)
  at java.io.ObjectInputStream.readOrdinaryObject(Unknown Source)
  at java.io.ObjectInputStream.readObject0(Unknown Source)
  at java.io.ObjectInputStream.readObject(Unknown Source)
  at java.util.HashMap.readObject(Unknown Source)
  at sun.reflect.GeneratedMethodAccessor1.invoke(Unknown Source)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
  at java.lang.reflect.Method.invoke(Unknown Source)
  at java.io.ObjectStreamClass.invokeReadObject(Unknown Source)
  at java.io.ObjectInputStream.readSerialData(Unknown Source)
  at java.io.ObjectInputStream.readOrdinaryObject(Unknown Source)
  at java.io.ObjectInputStream.readObject0(Unknown Source)
  at java.io.ObjectInputStream.defaultReadFields(Unknown Source)
  at java.io.ObjectInputStream.readSerialData(Unknown Source)
  at java.io.ObjectInputStream.readOrdinaryObject(Unknown Source)
  at java.io.ObjectInputStream.readObject0(Unknown Source)
  at java.io.ObjectInputStream.readObject(Unknown Source)
  at org.jppf.serialization.DefaultJavaSerialization.deserialize(DefaultJavaSerialization.java:45)
  at org.jppf.utils.ObjectSerializerImpl.deserialize(ObjectSerializerImpl.java:154)
  at org.jppf.utils.ObjectSerializerImpl.deserialize(ObjectSerializerImpl.java:140)
  at org.jppf.io.IOHelper.unwrappedData(IOHelper.java:237)
  at org.jppf.io.IOHelper.unwrappedData(IOHelper.java:209)
  at org.jppf.server.nio.classloader.ClassContext.deserializeResource(ClassContext.java:120)
  at org.jppf.server.nio.classloader.node.WaitingNodeRequestState.performTransition(WaitingNodeRequestState.java:75)
  at org.jppf.server.nio.classloader.node.WaitingNodeRequestState.performTransition(WaitingNodeRequestState.java:39)
  at org.jppf.nio.StateTransitionTask.run(StateTransitionTask.java:82)
  at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
  at java.util.concurrent.FutureTask.run(Unknown Source)
  at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
  at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
  at java.lang.Thread.run(Unknown Source)
Steps to reproduce this issue
Start driver with debug logging enabled.

#3
Comment posted by
 lolo4j
Oct 17, 17:15
Fixed in: