6 Replies Latest reply on Jun 3, 2008 6:35 PM by ron_sigal

    Worker thread initialization failure

    gmb_it

      Hi,
      in my production enviroment ( jboss 4.2.2 GA, jboss messaging 1.4.0.SP3, JBoss Remoting 2.2.2.SP3 on Red Hat 2.6.9 ) I have a lot of errors equals this:

      2007-12-19 09:49:32,829 ERROR [org.jboss.remoting.transport.socket.ServerThread] Worker thread initialization failure
      java.io.EOFException
       at org.jboss.remoting.transport.socket.ServerThread.processInvocation(ServerThread.java:530)
       at org.jboss.remoting.transport.socket.ServerThread.dorun(ServerThread.java:373)
       at org.jboss.remoting.transport.socket.ServerThread.run(ServerThread.java:166)
      


      I have see this error in other forums and ( if I correctly understand ) this is a problem about the configurations. My remoting-bisocket-service.xml is the file relaised by messaging 1.4.0.SP3. Need it a tunning?

      Thanks in Advance .

        • 1. Re: Worker thread initialization failure
          ron_sigal

          I can't tell what's going on from that exception by itself. I would suggest turning on trace logging for both JBossMessaging and Remoting to see if there's anything suspicious happening. To turn on trace logging, add

           <!-- Remoting -->
           <category name="org.jboss.remoting">
           <priority value="TRACE"/>
           </category>
          
           <!-- JBossMessaging -->
           <category name="org.jboss.jms">
           <priority value="TRACE"/>
           </category>
          


          to your conf/jboss-log4j.xml file.

          • 2. Re: Worker thread initialization failure
            gmb_it

            Done. This is the result:

            2007-12-21 11:29:42,956 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] stopped
            ...
            2007-12-21 11:29:42,956 DEBUG [org.jboss.remoting.Client] starting callback Connector: InvokerLocator [bisocket://10.0.1.21:1035918315/callback?guid=a01l-yo11sp-faf9285u-1-fagkzauj-28ipl&callbackServerProtocol=bisocket&callbackServerHost=10.0.1.21&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper&datatype=jms&callbackServerPort=1035918315&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&isCallbackServer=true]
            2007-12-21 11:29:42,956 TRACE [org.jboss.jms.server.endpoint.ServerConsumerEndpoint] ConsumerEndpoint[ipi82-3x7zkgaf-1-u5829faf-ps11oy-l10a] NOT started!
            ...
            2007-12-21 11:29:42,957 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingFrequency to 214748364
            2007-12-21 11:29:42,957 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingWindowFactor to 10
            2007-12-21 11:29:42,957 TRACE [org.jboss.jms.server.endpoint.ServerConsumerEndpoint] ConsumerEndpoint[leg82-rtnrkgaf-1-u5829faf-ps11oy-l10a] receives Reference[16047333]:RELIABLE for delivery
            2007-12-21 11:29:42,957 TRACE [org.jboss.jms.server.endpoint.ServerConsumerEndpoint] ConsumerEndpoint[leg82-rtnrkgaf-1-u5829faf-ps11oy-l10a] NOT started!
            ...
            2007-12-21 11:29:42,958 TRACE [org.jboss.jms.server.endpoint.ServerConsumerEndpoint] ConsumerEndpoint[hue82-hc9okgaf-1-u5829faf-ps11oy-l10a] receives Reference[16047645]:RELIABLE for delivery
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] created server socket factory javax.net.DefaultServerSocketFactory@1eb904d
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.transport.Connector] org.jboss.remoting.transport.Connector@e0911a started
            2007-12-21 11:29:42,954 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,958 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.ServerInvoker] ServerInvoker (SocketServerInvoker[UNINITIALIZED]) added client callback handler CallbackManager[7eb607] with session id of a01l-yo11sp-faf9285u-1-fagkzauj-28ipj+a01l-yo11sp-faf9285u-1-fagkzaum-28ips and callback handle object of null.
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.InvokerRegistry] removed org.jboss.remoting.transport.local.LocalClientInvoker@86405 from registry
            2007-12-21 11:29:42,958 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.callback.ServerInvokerCallbackHandler] Session id for callback handler is a01l-yo11sp-faf9285u-1-fagkzauj-28ipj+a01l-yo11sp-faf9285u-1-fagkzaum-28ips
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.callback.DefaultCallbackErrorHandler] DefaultCallbackErrorHandler[UNITIALIZED] setting server invoker to SocketServerInvoker[0.0.0.0:4457]
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.callback.DefaultCallbackErrorHandler] DefaultCallbackErrorHandler[SocketServerInvoker[0.0.0.0:4457]] setting callback handler to ServerInvokerCallbackHandler[a01l-yo11sp-faf9285u-1-fagkzauj-28ipj+a01l-yo11sp-faf9285u-1-fagkzaum-28ips]
            2007-12-21 11:29:42,958 ERROR [org.jboss.remoting.transport.socket.ServerThread] Worker thread initialization failure
            java.io.EOFException
            at org.jboss.remoting.transport.socket.ServerThread.processInvocation(ServerThread.java:530)
            at org.jboss.remoting.transport.socket.ServerThread.dorun(ServerThread.java:373)
            at org.jboss.remoting.transport.socket.ServerThread.run(ServerThread.java:166)
            2007-12-21 11:29:42,957 DEBUG [org.jboss.remoting.Client] starting callback Connector: InvokerLocator [bisocket://10.0.1.21:1923421999/callback?guid=a01l-yo11sp-faf9285u-1-fagkzauk-28ipo&callbackServerProtocol=bisocket&callbackServerHost=10.0.1.21&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper&datatype=jms&callbackServerPort=1923421999&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&isCallbackServer=true]
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#1[10.0.1.21:8093] closing socketWrapper: ServerSocketWrapper[Socket[addr=/10.0.1.21,port=8093,localport=4457].4dc50b]
            2007-12-21 11:29:42,958 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingFrequency to 214748364
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingWindowFactor to 10
            2007-12-21 11:29:42,957 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] stopped
            2007-12-21 11:29:42,958 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] passed through ServerSocketRefresh.release()
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] stopped
            ...
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.Client] starting callback Connector: InvokerLocator [bisocket://10.0.1.21:1923422002/callback?guid=a01l-yo11sp-faf9285u-1-fagkzauk-28ipr&callbackServerProtocol=bisocket&callbackServerHost=10.0.1.21&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper&datatype=jms&callbackServerPort=1923422002&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&isCallbackServer=true]
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] stopped
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] stopped
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] stopped
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingFrequency to 214748364
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] stopped
            ...
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] created server socket factory javax.net.DefaultServerSocketFactory@1eb904d
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.transport.Connector] org.jboss.remoting.transport.Connector@1243554 started
            2007-12-21 11:29:42,958 TRACE [org.jboss.jms.server.endpoint.ServerConsumerEndpoint] ConsumerEndpoint[hue82-hc9okgaf-1-u5829faf-ps11oy-l10a] NOT started!
            2007-12-21 11:29:42,960 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,958 DEBUG [org.jboss.jms.server.remoting.ServerSocketWrapper] wrote CLOSING byte
            2007-12-21 11:29:42,960 DEBUG [org.jboss.remoting.ServerInvoker] ServerInvoker (SocketServerInvoker[UNINITIALIZED]) added client callback handler CallbackManager[1c36cec] with session id of a01l-yo11sp-faf9285u-1-fagkzauj-28ipm+a01l-yo11sp-faf9285u-1-fagkzaun-28ipv and callback handle object of null.
            2007-12-21 11:29:42,960 DEBUG [org.jboss.remoting.InvokerRegistry] removed org.jboss.remoting.transport.local.LocalClientInvoker@df7887 from registry
            2007-12-21 11:29:42,960 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,960 DEBUG [org.jboss.remoting.callback.ServerInvokerCallbackHandler] Session id for callback handler is a01l-yo11sp-faf9285u-1-fagkzauj-28ipm+a01l-yo11sp-faf9285u-1-fagkzaun-28ipv
            2007-12-21 11:29:42,960 DEBUG [org.jboss.remoting.callback.DefaultCallbackErrorHandler] DefaultCallbackErrorHandler[UNITIALIZED] setting server invoker to SocketServerInvoker[0.0.0.0:4457]
            2007-12-21 11:29:42,960 DEBUG [org.jboss.remoting.callback.DefaultCallbackErrorHandler] DefaultCallbackErrorHandler[SocketServerInvoker[0.0.0.0:4457]] setting callback handler to ServerInvokerCallbackHandler[a01l-yo11sp-faf9285u-1-fagkzauj-28ipm+a01l-yo11sp-faf9285u-1-fagkzaun-28ipv]
            2007-12-21 11:29:42,960 DEBUG [org.jboss.jms.server.remoting.JMSServerInvocationHandler] adding callback handler ServerInvokerCallbackHandler[a01l-yo11sp-faf9285u-1-fagkzauj-28ipm+a01l-yo11sp-faf9285u-1-fagkzaun-28ipv]
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.remoting.JMSRemotingConnection] JMSRemotingConnection[bisocket://10.0.1.21:4457/?JBM_clientMaxPoolSize=200&clientLeasePeriod=10000&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&dataType=jms&marshaller=org.jboss.jms.wireformat.JMSWireFormat&numberOfCallRetries=1&numberOfRetries=10&pingFrequency=214748364&pingWindowFactor=10&socket.check_connection=false&timeout=0&unmarshaller=org.jboss.jms.wireformat.JMSWireFormat] started
            2007-12-21 11:29:42,960 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,960 DEBUG [org.jboss.jms.server.remoting.JMSServerInvocationHandler] found calllback handler for remoting session ...-fagkzauj-28ipm UID=a01l-yo11sp-faf9285u-1-fagkzauj-28ipm
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.server.endpoint.ServerConnectionFactoryEndpoint] creating a new connection for user null
            2007-12-21 11:29:42,958 TRACE [org.jboss.jms.client.container.ClusteringAspect] ClusteringAspect[ClusteredConnectionFactoryDelegate[SIDs={4}]] has chosen ConnectionFactoryDelegate[jboss.messaging.connectionfactory:service=ClusteredConnectionFactory, SID=4] as target, 1 connection attempts
            2007-12-21 11:29:42,960 DEBUG [org.jboss.jms.server.connectionmanager.SimpleConnectionManager] registered connection ConnectionEndpoint[ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a] as ...-fagkzauj-28ipm
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.server.endpoint.ServerConnectionFactoryEndpoint] created and registered ConnectionEndpoint[ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a]
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.message.MessageIdGeneratorFactory] checked out MessageIdGenerator for 4, reference count is 174
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.state.ConnectionState] ConnectionState[ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a] constructing connection state
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.container.ClusteringAspect] ClusteringAspect[ClusteredConnectionFactoryDelegate[SIDs={4}]] got local connection delegate ConnectionDelegate[10923229, ID=ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a, SID=4]
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.remoting.ConsolidatedRemotingConnectionListener] ConnectionState[ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a].ConsolidatedListener setting delegate listener ConnectionFailureListener[FailoverCommandCenter[ConnectionState[ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a]]]
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.container.ClusteringAspect] ClusteringAspect[ClusteredConnectionFactoryDelegate[SIDs={4}]] installed failure listener on ConnectionDelegate[10923229, ID=ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a, SID=4]
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.container.ClusteringAspect] Successfully initialised new connection
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[10420294] entering
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[10420294] entered
            2007-12-21 11:29:42,960 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.server.endpoint.ServerConnectionEndpoint] ConnectionEndpoint[ypi82-ouazkgaf-1-u5829faf-ps11oy-l10a] creating non transacted session, AUTO_ACKNOWLEDGE, non XA
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.server.endpoint.ServerConnectionEndpoint] created and registered SessionEndpoint[zpi82-ouazkgaf-1-u5829faf-ps11oy-l10a]
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.server.endpoint.ServerConnectionEndpoint] created SessionDelegate[28304068, ID=zpi82-ouazkgaf-1-u5829faf-ps11oy-l10a]
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[10420294] leaving
            2007-12-21 11:29:42,960 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[10420294] left
            2007-12-21 11:29:42,958 DEBUG [org.jboss.jms.server.remoting.JMSServerInvocationHandler] adding callback handler ServerInvokerCallbackHandler[a01l-yo11sp-faf9285u-1-fagkzauj-28ipj+a01l-yo11sp-faf9285u-1-fagkzaum-28ips]
            2007-12-21 11:29:42,958 TRACE [org.jboss.jms.server.endpoint.ServerConsumerEndpoint] ConsumerEndpoint[ipi82-3x7zkgaf-1-u5829faf-ps11oy-l10a] close
            2007-12-21 11:29:42,959 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingWindowFactor to 10
            2007-12-21 11:29:42,961 TRACE [org.jboss.jms.server.endpoint.ServerConsumerEndpoint] ConsumerEndpoint[ipi82-3x7zkgaf-1-u5829faf-ps11oy-l10a] grabbed the main lock in close() ConsumerEndpoint[ipi82-3x7zkgaf-1-u5829faf-ps11oy-l10a]
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] leaving
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] left
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.container.ClosedInterceptor] ClosedInterceptor.ClientConsumerDelegate[ipi82-3x7zkgaf-1-u5829faf-ps11oy-l10a] closed
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] entering
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] entered
            2007-12-21 11:29:42,962 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] leaving
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] left
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] entering
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] entered
            2007-12-21 11:29:42,962 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.server.endpoint.ServerSessionEndpoint] SessionEndpoint[gpi82-1x7zkgaf-1-u5829faf-ps11oy-l10a] closing
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] leaving
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] left
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] entering
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] entered
            2007-12-21 11:29:42,962 TRACE [org.jboss.remoting.transport.local.LocalClientInvoker] Using local client invoker for invocation.
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.server.endpoint.ServerSessionEndpoint] SessionEndpoint[gpi82-1x7zkgaf-1-u5829faf-ps11oy-l10a] close()
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.server.endpoint.ServerSessionEndpoint] SessionEndpoint[gpi82-1x7zkgaf-1-u5829faf-ps11oy-l10a] cancelling 0 deliveries
            2007-12-21 11:29:42,962 TRACE [org.jboss.jms.client.FailoverValve2] FailoverValve[26660615] leaving

            • 3. Re: Worker thread initialization failure
              ron_sigal

              Well, the server side is responding appropriately to an end of file condition. But what is causing the socket to close on the client side? Could we see a trace log from the client side? Thanks.

              By the way, your issue may be related to the issue addressed in thread "JBREM-877: New Socket Connection is being Created for Every" (http://www.jboss.com/index.html?module=bb&op=viewtopic&t=126382).

              • 4. Re: Worker thread initialization failure
                esoyke

                When moving from 4.0.5 to 4.2.2 I ran into this since JBoss no longer binds to your IP address unless you explicitly bind it, it only binds to localhost. In your JBoss start script, try adding -b YOUR_IP_ADDRESS after the calling of org.jboss.Main.

                • 5. Re: Worker thread initialization failure

                  Hi
                  As suggested by Ron, I have added the following snippet in jboss-log4j.xml.

                  <category name="org.jboss.remoting">
                   <priority value="TRACE"/>
                  </category>


                  The log generated as a result is:
                  2008-05-13 13:50:55,659 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] accepted Socket[addr=/127.0.0.1,port=3070,localport=4446]
                  2008-05-13 13:50:55,659 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] trying to get a worker thread from threadpool for processing
                  2008-05-13 13:50:55,659 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] has an empty threadpool
                  2008-05-13 13:50:55,659 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] creating new worker thread
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] created WorkerThread#0[127.0.0.1:3070]
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] starting WorkerThread#0[127.0.0.1:3070]
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] passed through ServerSocketRefresh.release()
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] is going to wait on serverSocket.accept()
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.ServerThread] beginning dorun()
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.ServerThread] creating the socket wrapper
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.SocketWrapper] constructing org.jboss.remoting.transport.socket.ServerSocketWrapper instance for Socket[addr=/127.0.0.1,port=3070,localport=4446], using timeout 60000
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.SocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3070,localport=4446].1ff4ac0] setting timeout to 60000
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.ClientSocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3070,localport=4446].1ff4ac0] getting output stream from Socket[addr=/127.0.0.1,port=3070,localport=4446], org.jboss.invocation.unified.marshall.InvocationMarshaller@184a726
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.serialization.impl.java.JavaSerializationManager] Creating ObjectOutputStream
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.transport.socket.ClientSocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3070,localport=4446].1ff4ac0] getting input stream from Socket[addr=/127.0.0.1,port=3070,localport=4446], org.jboss.invocation.unified.marshall.InvocationUnMarshaller@1f9ef24
                  2008-05-13 13:50:55,675 TRACE [org.jboss.remoting.serialization.impl.java.JavaSerializationManager] Creating ObjectInputStreamWithClassLoader
                  2008-05-13 13:50:55,691 TRACE [org.jboss.remoting.transport.socket.SocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3070,localport=4446].1ff4ac0] setting timeout to 60000
                  2008-05-13 13:50:55,691 DEBUG [org.jboss.remoting.transport.socket.ClientSocketWrapper] reset timeout: 60000
                  2008-05-13 13:50:55,691 TRACE [org.jboss.remoting.transport.socket.ServerThread] processing first invocation without acknowledging
                  2008-05-13 13:50:55,691 TRACE [org.jboss.remoting.transport.socket.ServerThread] preparing to process next invocation invocation
                  2008-05-13 13:50:55,691 TRACE [org.jboss.remoting.transport.socket.ServerThread] blocking to read version from input stream
                  2008-05-13 13:50:55,691 TRACE [org.jboss.remoting.transport.socket.ServerThread] read version -1 from input stream
                  2008-05-13 13:50:55,691 ERROR [org.jboss.remoting.transport.socket.ServerThread] Worker thread initialization failure
                  java.io.EOFException
                  at org.jboss.remoting.transport.socket.ServerThread.processInvocation(ServerThread.java:530)
                  at org.jboss.remoting.transport.socket.ServerThread.dorun(ServerThread.java:373)
                  at org.jboss.remoting.transport.socket.ServerThread.run(ServerThread.java:166)
                  2008-05-13 13:50:55,691 DEBUG [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3070] closing socketWrapper: ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3070,localport=4446].1ff4ac0]
                  2008-05-13 13:50:55,691 DEBUG [org.jboss.remoting.transport.socket.ServerSocketWrapper] wrote CLOSING
                  2008-05-13 13:50:55,691 DEBUG [org.jboss.remoting.transport.socket.SocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3070,localport=4446].1ff4ac0] closing
                  2008-05-13 13:50:55,691 TRACE [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3070] removing itself from clientpool and going to threadpool
                  2008-05-13 13:50:55,691 TRACE [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3070] begins to wait
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] accepted Socket[addr=/127.0.0.1,port=3083,localport=4446]
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] trying to get a worker thread from threadpool for processing
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] got WorkerThread#0[127.0.0.1:3070] from threadpool
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] reusing WorkerThread#0[127.0.0.1:3070]
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3083] has notified on mutex
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] passed through ServerSocketRefresh.release()
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[127.0.0.1:4446] is going to wait on serverSocket.accept()
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3083] woke up after wait
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.ServerThread] beginning dorun()
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.ServerThread] creating the socket wrapper
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketWrapper] constructing org.jboss.remoting.transport.socket.ServerSocketWrapper instance for Socket[addr=/127.0.0.1,port=3083,localport=4446], using timeout 60000
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.SocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3083,localport=4446].17d3a8c] setting timeout to 60000
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.ClientSocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3083,localport=4446].17d3a8c] getting output stream from Socket[addr=/127.0.0.1,port=3083,localport=4446], org.jboss.invocation.unified.marshall.InvocationMarshaller@184a726
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.serialization.impl.java.JavaSerializationManager] Creating ObjectOutputStream
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.transport.socket.ClientSocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3083,localport=4446].17d3a8c] getting input stream from Socket[addr=/127.0.0.1,port=3083,localport=4446], org.jboss.invocation.unified.marshall.InvocationUnMarshaller@1f9ef24
                  2008-05-13 13:51:31,344 TRACE [org.jboss.remoting.serialization.impl.java.JavaSerializationManager] Creating ObjectInputStreamWithClassLoader
                  2008-05-13 13:51:31,376 TRACE [org.jboss.remoting.transport.socket.SocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3083,localport=4446].17d3a8c] setting timeout to 60000
                  2008-05-13 13:51:31,376 DEBUG [org.jboss.remoting.transport.socket.ClientSocketWrapper] reset timeout: 60000
                  2008-05-13 13:51:31,376 TRACE [org.jboss.remoting.transport.socket.ServerThread] processing first invocation without acknowledging
                  2008-05-13 13:51:31,376 TRACE [org.jboss.remoting.transport.socket.ServerThread] preparing to process next invocation invocation
                  2008-05-13 13:51:31,376 TRACE [org.jboss.remoting.transport.socket.ServerThread] blocking to read version from input stream
                  2008-05-13 13:51:31,376 TRACE [org.jboss.remoting.transport.socket.ServerThread] read version -1 from input stream
                  2008-05-13 13:51:31,376 ERROR [org.jboss.remoting.transport.socket.ServerThread] Worker thread initialization failure
                  java.io.EOFException
                  at org.jboss.remoting.transport.socket.ServerThread.processInvocation(ServerThread.java:530)
                  at org.jboss.remoting.transport.socket.ServerThread.dorun(ServerThread.java:373)
                  at org.jboss.remoting.transport.socket.ServerThread.run(ServerThread.java:166)
                  2008-05-13 13:51:31,376 DEBUG [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3083] closing socketWrapper: ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3083,localport=4446].17d3a8c]
                  2008-05-13 13:51:31,376 DEBUG [org.jboss.remoting.transport.socket.ServerSocketWrapper] wrote CLOSING
                  2008-05-13 13:51:31,376 DEBUG [org.jboss.remoting.transport.socket.SocketWrapper] ServerSocketWrapper[Socket[addr=/127.0.0.1,port=3083,localport=4446].17d3a8c] closing
                  2008-05-13 13:51:31,376 TRACE [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3083] removing itself from clientpool and going to threadpool
                  2008-05-13 13:51:31,376 TRACE [org.jboss.remoting.transport.socket.ServerThread] WorkerThread#0[127.0.0.1:3083] begins to wait



                  On the client side I get the following log:

                  aught in Conversioncom.pcm.rules.service.RuleServiceException: Error occurred while invoking rules from the RulesManager
                  com.pcm.rules.service.RuleServiceException: Error occurred while invoking rules from the RulesManager
                  at com.pcm.rules.RulesManager.invokeRules(RulesManager.java:75)
                  at com.myapp.process.ApplicationClient.executeRules(ApplicationClient.java:49)
                  at com.myapp.process.ApplicationClient.main(ApplicationClient.java:29)
                  Caused by: com.pcm.rules.service.RuleServiceException: Error occurred while invoking the rules
                  at com.pcm.rules.service.blaze.BlazeEJBExecutor.invokeRuleService(BlazeEJBExecutor.java:65)
                  at com.pcm.rules.service.blaze.BlazeRuleService.invokeRules(BlazeRuleService.java:68)
                  at com.pcm.rules.RulesManager.invokeRules(RulesManager.java:72)
                  ... 2 more
                  Caused by: com.pcm.rules.service.RuleServiceException: Error occurred in obtaining the Remote Reference of the Blaze EJB
                  at com.pcm.rules.service.blaze.BlazeEJBExecutor.getEJBHandle(BlazeEJBExecutor.java:84)
                  at com.pcm.rules.service.blaze.BlazeEJBExecutor.invokeRuleService(BlazeEJBExecutor.java:53)
                  ... 4 more
                  Caused by: java.rmi.MarshalException: Failed to communicate. Problem during marshalling/unmarshalling; nested exception is:
                  java.io.OptionalDataException
                  at org.jboss.remoting.transport.socket.SocketClientInvoker.transport(SocketClientInvoker.java:306)
                  at org.jboss.remoting.RemoteClientInvoker.invoke(RemoteClientInvoker.java:143)
                  at org.jboss.remoting.Client.invoke(Client.java:525)
                  at org.jboss.remoting.Client.invoke(Client.java:488)
                  at org.jboss.invocation.unified.interfaces.UnifiedInvokerProxy.invoke(UnifiedInvokerProxy.java:175)
                  at org.jboss.invocation.InvokerInterceptor.invokeInvoker(InvokerInterceptor.java:365)
                  at org.jboss.invocation.InvokerInterceptor.invoke(InvokerInterceptor.java:197)
                  at org.jboss.proxy.TransactionInterceptor.invoke(TransactionInterceptor.java:61)
                  at org.jboss.proxy.SecurityInterceptor.invoke(SecurityInterceptor.java:70)
                  at org.jboss.proxy.ejb.HomeInterceptor.invoke(HomeInterceptor.java:184)
                  at org.jboss.proxy.ClientContainer.invoke(ClientContainer.java:100)
                  at $Proxy0.create(Unknown Source)
                  at com.pcm.rules.service.blaze.BlazeEJBExecutor.getEJBHandle(BlazeEJBExecutor.java:82)
                  ... 5 more
                  Caused by: java.io.OptionalDataException
                  at java.io.ObjectInputStream.readObject0(Unknown Source)
                  at java.io.ObjectInputStream.readObject(Unknown Source)
                  at org.jboss.remoting.serialization.impl.java.JavaSerializationManager.receiveObject(JavaSerializationManager.java:128)
                  at org.jboss.remoting.marshal.serializable.SerializableUnMarshaller.read(SerializableUnMarshaller.java:66)
                  at org.jboss.invocation.unified.marshall.InvocationUnMarshaller.read(InvocationUnMarshaller.java:48)
                  at org.jboss.remoting.transport.socket.SocketClientInvoker.transport(SocketClientInvoker.java:279)
                  ... 17 more

                  I request ur assistance in resolving this


                  • 6. Re: Worker thread initialization failure
                    ron_sigal

                    The java.io.OptionalDataException suggests to me that you have different versions of Remoting on the client side and server side. You can get the version of a Remoting jar as follows:

                    java -jar jboss-remoting.jar


                    Also, on the client side, besides jboss-remoting.jar, look for jbossall-client.jar, which bundles remoting, on your classpath.