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

    Worker thread initialization failure

    Gian Marco Brusaca' Newbie

      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 Master

          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
            Gian Marco Brusaca' Newbie

            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 Master

              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
                Eric Soyke Newbie

                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
                  sarfaraz Mandal Newbie

                  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 Master

                    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.