OC4J cannot update access log: NullPointerException

[Stan Ca]

Hello,

I recently installed the Standalone OC4j 10.1.3.0.0... I have a two EJB applications with a web-app running in this container (one in HTTP, the other in HTTPS). Both are set as default-web-app in their appropriate *-web-site.xml files.

Now, occassionally -- can't really figure out what triggers it -- I get this NullpointerException about OC4J not being able to update an access log.

The server is started with the following command:

java -Xms630m -Xmx630M -XX:MaxPermSize=128m -Doracle.j2ee.http.socket.timeout=150000 
-Dcom.sun.management.jmxremote -jar oc4j.jar -userThreads

This is the server output:

06/07/17 09:14:09 SEVERE: HttpRequestHandler.updateAccessLog OC4J cannot update access log: nulljava
.lang.NullPointerException
at com.evermind.server.http.StandardRequestLogger.appendIP(StandardRequestLogger.java:168)
at com.evermind.server.http.StandardRequestLogger.log(StandardRequestLogger.java:59)
at com.evermind.server.http.FormattedRequestLogger.log(FormattedRequestLogger.java:330)
at com.evermind.server.http.HttpRequestHandler.updateAccessLog(HttpRequestHandler.java:1043)
 
at com.evermind.server.http.HttpRequestHandler.doProcessRequest(HttpRequestHandler.java:971)
 
at com.evermind.server.http.HttpRequestHandler.processRequest(HttpRequestHandler.java:451)
at com.evermind.server.http.HttpRequestHandler.serveOneRequest(HttpRequestHandler.java:218)
at com.evermind.server.http.HttpRequestHandler.run(HttpRequestHandler.java:119)
at com.evermind.server.http.HttpRequestHandler.run(HttpRequestHandler.java:112)
at oracle.oc4j.network.ServerSocketReadHandler$SafeRunnable.run(ServerSocketReadHandler.java
:260)
at com.evermind.util.ReleasableResourcePooledExecutor$MyWorker.run(ReleasableResourcePooledE
xecutor.java:303)
at java.lang.Thread.run(Thread.java:595)
 
17-jul-2006 9:14:09 com.evermind.server.http.HttpRequestHandler updateAccessLog
SEVERE: OC4J cannot update access log: null
java.lang.NullPointerException
at com.evermind.server.http.StandardRequestLogger.appendIP(StandardRequestLogger.java:168)
at com.evermind.server.http.StandardRequestLogger.log(StandardRequestLogger.java:59)
at com.evermind.server.http.FormattedRequestLogger.log(FormattedRequestLogger.java:330)
at com.evermind.server.http.HttpRequestHandler.updateAccessLog(HttpRequestHandler.java:1043)
 
at com.evermind.server.http.HttpRequestHandler.doProcessRequest(HttpRequestHandler.java:971)
 
at com.evermind.server.http.HttpRequestHandler.processRequest(HttpRequestHandler.java:451)
at com.evermind.server.http.HttpRequestHandler.serveOneRequest(HttpRequestHandler.java:218)
at com.evermind.server.http.HttpRequestHandler.run(HttpRequestHandler.java:119)
at com.evermind.server.http.HttpRequestHandler.run(HttpRequestHandler.java:112)
at oracle.oc4j.network.ServerSocketReadHandler$SafeRunnable.run(ServerSocketReadHandler.java
:260)
at com.evermind.util.ReleasableResourcePooledExecutor$MyWorker.run(ReleasableResourcePooledE
xecutor.java:303)
at java.lang.Thread.run(Thread.java:595)
2006-07-17 09:14:09.752 WARNING Exception in NIOServerSocketDriver:selectForRead
2006-07-17 09:16:39.814 WARNING Exception in SelectionKey cancel

And this is what I find in the log.xml:

<EXEC_CONTEXT_ID><UNIQUE_ID>192.168.200.87:74981:1152889958197:24</UNIQUE_ID><SEQ>0</SEQ></EXEC_CONTEXT_ID>
</CORRELATION_DATA>
<PAYLOAD>
<MSG_TEXT>Exception in NIOServerSocketDriver:selectForRead</MSG_TEXT>
<SUPPL_DETAIL><![CDATA[java.nio.channels.ClosedChannelException
at java.nio.channels.spi.AbstractSelectableChannel.configureBlocking(AbstractSelectableChannel.java:252)
at oracle.oc4j.network.NIOServerSocketDriver$SelectorThreadTask.selectForRead(NIOServerSocketDriver.java:321)
at oracle.oc4j.network.NIOServerSocketDriver.selectForRead(NIOServerSocketDriver.java:58)
at oracle.oc4j.network.ServerSocketAcceptHandler.persistConnection(ServerSocketAcceptHandler.java:371)
at oracle.oc4j.network.ServerSocketAcceptHandler.endReadHandlerRun(ServerSocketAcceptHandler.java:391)
at oracle.oc4j.network.ServerSocketReadHandler$SafeRunnable.run(ServerSocketReadHandler.java:275)
at com.evermind.util.ReleasableResourcePooledExecutor$MyWorker.run(ReleasableResourcePooledExecutor.java:303)
at java.lang.Thread.run(Thread.java:595)
]]></SUPPL_DETAIL>
</PAYLOAD>
</MESSAGE>
<MESSAGE>
<HEADER>
<TSTZ_ORIGINATING>2006-07-14T17:15:10.776+02:00</TSTZ_ORIGINATING>
<COMPONENT_ID>oc4j</COMPONENT_ID>
<MSG_TYPE TYPE="WARNING"></MSG_TYPE>
<MSG_LEVEL>1</MSG_LEVEL>
<HOST_ID>STELLA</HOST_ID>
<HOST_NWADDR>192.168.200.87</HOST_NWADDR>
<MODULE_ID>network</MODULE_ID>
<THREAD_ID>11</THREAD_ID>
<USER_ID>Stijnc</USER_ID>
</HEADER>
<CORRELATION_DATA>
 
<EXEC_CONTEXT_ID><UNIQUE_ID>192.168.200.87:74981:1152890110776:34</UNIQUE_ID><SEQ>0</SEQ></EXEC_CONTEXT_ID>
</CORRELATION_DATA>
<PAYLOAD>
<MSG_TEXT>Exception in SelectionKey cancel</MSG_TEXT>
<SUPPL_DETAIL><![CDATA[java.lang.NullPointerException
at oracle.oc4j.network.ServerSocketAcceptHandler$IdleHandlers.closeIdleHandler(ServerSocketAcceptHandler.java:548)
at oracle.oc4j.network.ServerSocketAcceptHandler$IdleHandlers.expireHandlers(ServerSocketAcceptHandler.java:569)
at oracle.oc4j.network.ServerSocketAcceptHandler.housekeeping(ServerSocketAcceptHandler.java:496)
at com.evermind.server.http.HttpConnectionListener$HttpNIOAcceptHandler$AcceptHandlerTask.run(HttpConnectionListener.java:122)
at com.evermind.util.Task.schedule(Task.java:51)
at com.evermind.util.TaskManager.run(TaskManager.java:212)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:814)
at java.lang.Thread.run(Thread.java:595)
]]></SUPPL_DETAIL>
</PAYLOAD>
</MESSAGE>
<MESSAGE>
<HEADER>
<TSTZ_ORIGINATING>2006-07-17T08:42:59.755+02:00</TSTZ_ORIGINATING>
<COMPONENT_ID>j2ee</COMPONENT_ID>
<MSG_ID>J2EE DS265</MSG_ID>
<MSG_TYPE TYPE="WARNING"></MSG_TYPE>
<MSG_LEVEL>1</MSG_LEVEL>
<HOST_ID>STELLA</HOST_ID>
<HOST_NWADDR>192.168.200.87</HOST_NWADDR>
<MODULE_ID>datasource</MODULE_ID>
<THREAD_ID>12</THREAD_ID>
<USER_ID>Stijnc</USER_ID>
</HEADER>
<CORRELATION_DATA>

Anyone have any clue as to what might be the problem?

Regards,
Stan
[ July 17, 2006: Message edited by: Stan Ca ]

[Stan Ca]

Apparently, it's a bug in the OC4J. Everything works fine, just as long as you don't use SSL. Ain't that great?

Regards,
Stan

[Halcon Guatemala]

Hi Stan, I'm facing a similar problem, and I want to know what are you referring about when you say

Everything works fine, just as long as you don't use SSL.

Where are you using SSL?

Thanks in advance