JBAS015052: Did not receive a response to the deployment operation within the allowed timeout period

[guru_bv]

Hi All,

I am in the process of migrating my application to JBoss AS 7 (7.1.1.Final). After the server deploys all my EJB's. I get the following error:-

JBAS015052: Did not receive a response to the deployment operation within the allowed timeout period [60 seconds]. Check the server configuration file and the server logs to find more about the status of the deployment.

After reading a lot of discussion on the same topic, I tried increasing the time out period to 120 and then later even until 1200, but there wasn't any success. Then I changed the log level to "DEBUG" and found this stack trace:-

16:00:57,013 FINE  [org.hornetq.core.asyncio.impl.AsynchronousFileImpl] (MSC service thread 1-7) HornetQAIO -> error loading the native library: java.lang.UnsatisfiedLinkError: no HornetQAIO in java.library.path

    at java.lang.ClassLoader.loadLibrary(ClassLoader.java:1738) [rt.jar:1.6.0_34]

    at java.lang.Runtime.loadLibrary0(Runtime.java:823) [rt.jar:1.6.0_34]

    at java.lang.System.loadLibrary(System.java:1028) [rt.jar:1.6.0_34]

    at org.hornetq.core.asyncio.impl.AsynchronousFileImpl.loadLibrary(AsynchronousFileImpl.java:92) [hornetq-core-2.2.13.Final.jar:]

    at org.hornetq.core.asyncio.impl.AsynchronousFileImpl.<clinit>(AsynchronousFileImpl.java:119) [hornetq-core-2.2.13.Final.jar:]

    at org.hornetq.core.journal.impl.AIOSequentialFileFactory.isSupported(AIOSequentialFileFactory.java:113) [hornetq-core-2.2.13.Final.jar:]

    at org.jboss.as.messaging.HornetQService.start(HornetQService.java:89) [jboss-as-messaging-7.1.1.Final.jar:7.1.1.Final]

    at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]

    at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]

    at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [rt.jar:1.6.0_34]

    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [rt.jar:1.6.0_34]

    at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_34]

I am not sure if the above error is in any way causing the issue. Any suggestion is greatly appreciated.

Regards,

Guru

[sfcoy]

What is your runtime environment?

[guru_bv]

I am running on Win 7 with JDK 1.6.0_34

[nickarls]

That's a FINE level log-entry so I doubt it is causing the problem.

Is it an EAR or a WAR? Deploying it by dropping into the deployment folder? Tried using jstack in order to see where it hangs?

[guru_bv]

So you mean to suggest to first fix the java.lang.UnsatisfiedLinkError: no HornetQAIO in java.library.path?

My deployment is an exploded EAR into the deployment folder. I ran the Java VisualVM to check the Thread dump, I see that most of the hornetq threads are either under "WAITING" or "TIMED_WAITING" state as can be seen below

"Thread-29 (HornetQ-server-HornetQServerImpl::serverUUID=6df35109-296e-11e2-b543-386077b439ee-18467372)" prio=6 tid=0x06457800 nid=0x1b70 waiting on condition [0x0764f000]

   java.lang.Thread.State: WAITING (parking)

    at sun.misc.Unsafe.park(Native Method)

    - parking to wait for  <0x137949d0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)

    at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)

    at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947)

    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)

    at java.lang.Thread.run(Thread.java:662)

   Locked ownable synchronizers:

    - None

"Thread-28 (HornetQ-server-HornetQServerImpl::serverUUID=6df35109-296e-11e2-b543-386077b439ee-18467372)" prio=6 tid=0x06457000 nid=0x1b88 waiting on condition [0x075bf000]

   java.lang.Thread.State: WAITING (parking)

    at sun.misc.Unsafe.park(Native Method)

    - parking to wait for  <0x137949d0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)

    at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)

    at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947)

    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)

    at java.lang.Thread.run(Thread.java:662)

   Locked ownable synchronizers:

    - None

"Thread-2 (HornetQ-remoting-threads-HornetQServerImpl::serverUUID=6df35109-296e-11e2-b543-386077b439ee-24086409-19619895)" prio=6 tid=0x06a2d800 nid=0xa80 waiting on condition [0x05c4f000]

   java.lang.Thread.State: TIMED_WAITING (parking)

    at sun.misc.Unsafe.park(Native Method)

    - parking to wait for  <0x1394b9d8> (a java.util.concurrent.SynchronousQueue$TransferStack)

    at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)

    at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:424)

    at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:323)

    at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:874)

    at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:945)

    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)

    at java.lang.Thread.run(Thread.java:662)

   Locked ownable synchronizers:

    - None

......

.......

.......

"Thread-0 (HornetQ-client-global-scheduled-threads-18029976)" daemon prio=6 tid=0x06a2d000 nid=0x1a4 waiting on condition [0x05bbf000]

   java.lang.Thread.State: TIMED_WAITING (parking)

    at sun.misc.Unsafe.park(Native Method)

    - parking to wait for  <0x15e72fe8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)

    at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)

    at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)

    at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947)

    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)

    at java.lang.Thread.run(Thread.java:662)

   Locked ownable synchronizers:

    - None