Petals ESB Container

Embedded Registry Overlay errors in the logs during container stop

Details

  • Type: Bug Bug
  • Status: Resolved Resolved
  • Priority: Minor Minor
  • Resolution: Fixed
  • Affects Version/s: 5.0.1
  • Fix Version/s: 5.0.2
  • Component/s: Registry
  • Security Level: Public
  • Description:
    Hide

    When Petals is stopped using "./bin/petals-esb.sh stop", we can sometimes witness this kind of errors in the logs:

    sample-0 2016/05/25 09:32:08,600 GMT+0200 INFO [Petals.PetalsAdminService] : PEtALS is stopping...
sample-0 2016/05/25 09:32:08,604 GMT+0200 INFO [Petals.Extensions.AutoLoaderService] : Petals extension "Autoloader" stopped.
sample-0 2016/05/25 09:32:08,624 GMT+0200 WARNING [com.hazelcast.nio.tcp.nonblocking.NonBlockingSocketReader] : [127.0.0.1]:7900 [default-sample] [3.6.2] hz._hzInstance_1_default-sample.IO.thread-in-0 Closing socket to endpoint Address[127.0.0.1]:41794, Cause:java.io.EOFException: Remote socket closed!
sample-0 2016/05/25 09:32:08,625 GMT+0200 INFO [Petals.Extensions.EmbeddedRegistryOverlayService] : Stopping Petals Registry Overlay local member...
sample-0 2016/05/25 09:32:08,628 GMT+0200 INFO [Petals.Registry.Overlay] : Stopping Petals Registry Overlay local member...
sample-0 2016/05/25 09:32:08,638 GMT+0200 SEVERE [com.hazelcast.spi.impl.eventservice.impl.EventServiceImpl] : [127.0.0.1]:7900 [default-sample] [3.6.2] hz._hzInstance_1_default-sample.event-5 caught an exception while processing task:com.hazelcast.spi.impl.eventservice.impl.LocalEventDispatcher@1d172ac0
com.hazelcast.core.HazelcastInstanceNotActiveException: Hazelcast instance is not active!
    at com.hazelcast.spi.AbstractDistributedObject.throwNotActiveException(AbstractDistributedObject.java:85)
    at com.hazelcast.spi.AbstractDistributedObject.lifecycleCheck(AbstractDistributedObject.java:80)
    at com.hazelcast.spi.AbstractDistributedObject.getNodeEngine(AbstractDistributedObject.java:74)
    at com.hazelcast.concurrent.lock.LockProxy.unlock(LockProxy.java:106)
    at org.ow2.petals.registry_overlay.core.ClientListener.clientDisconnected(ClientListener.java:89)
    at com.hazelcast.client.impl.ClientEngineImpl.dispatchEvent(ClientEngineImpl.java:293)
    at com.hazelcast.client.impl.ClientEngineImpl.dispatchEvent(ClientEngineImpl.java:99)
    at com.hazelcast.spi.impl.eventservice.impl.LocalEventDispatcher.run(LocalEventDispatcher.java:56)
    at com.hazelcast.util.executor.StripedExecutor$Worker.process(StripedExecutor.java:187)
    at com.hazelcast.util.executor.StripedExecutor$Worker.run(StripedExecutor.java:171)
sample-0 2016/05/25 09:32:08,671 GMT+0200 INFO [Petals.Extensions.EmbeddedRegistryOverlayService] : Petals Registry Overlay local member stopped.
sample-0 2016/05/25 09:32:08,674 GMT+0200 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : The NIO Server agent is stopped.
sample-0 2016/05/25 09:32:08,675 GMT+0200 INFO [Petals.Server] : Server STOPPED

    Even though they are inoffensive, this shows a problem of concurrency during registry stop w.r.t. to client disconnections.

    Show
    When Petals is stopped using "./bin/petals-esb.sh stop", we can sometimes witness this kind of errors in the logs: 
    sample-0 2016/05/25 09:32:08,600 GMT+0200 INFO [Petals.PetalsAdminService] : PEtALS is stopping...
sample-0 2016/05/25 09:32:08,604 GMT+0200 INFO [Petals.Extensions.AutoLoaderService] : Petals extension "Autoloader" stopped.
sample-0 2016/05/25 09:32:08,624 GMT+0200 WARNING [com.hazelcast.nio.tcp.nonblocking.NonBlockingSocketReader] : [127.0.0.1]:7900 [default-sample] [3.6.2] hz._hzInstance_1_default-sample.IO.thread-in-0 Closing socket to endpoint Address[127.0.0.1]:41794, Cause:java.io.EOFException: Remote socket closed!
sample-0 2016/05/25 09:32:08,625 GMT+0200 INFO [Petals.Extensions.EmbeddedRegistryOverlayService] : Stopping Petals Registry Overlay local member...
sample-0 2016/05/25 09:32:08,628 GMT+0200 INFO [Petals.Registry.Overlay] : Stopping Petals Registry Overlay local member...
sample-0 2016/05/25 09:32:08,638 GMT+0200 SEVERE [com.hazelcast.spi.impl.eventservice.impl.EventServiceImpl] : [127.0.0.1]:7900 [default-sample] [3.6.2] hz._hzInstance_1_default-sample.event-5 caught an exception while processing task:com.hazelcast.spi.impl.eventservice.impl.LocalEventDispatcher@1d172ac0
com.hazelcast.core.HazelcastInstanceNotActiveException: Hazelcast instance is not active!
    at com.hazelcast.spi.AbstractDistributedObject.throwNotActiveException(AbstractDistributedObject.java:85)
    at com.hazelcast.spi.AbstractDistributedObject.lifecycleCheck(AbstractDistributedObject.java:80)
    at com.hazelcast.spi.AbstractDistributedObject.getNodeEngine(AbstractDistributedObject.java:74)
    at com.hazelcast.concurrent.lock.LockProxy.unlock(LockProxy.java:106)
    at org.ow2.petals.registry_overlay.core.ClientListener.clientDisconnected(ClientListener.java:89)
    at com.hazelcast.client.impl.ClientEngineImpl.dispatchEvent(ClientEngineImpl.java:293)
    at com.hazelcast.client.impl.ClientEngineImpl.dispatchEvent(ClientEngineImpl.java:99)
    at com.hazelcast.spi.impl.eventservice.impl.LocalEventDispatcher.run(LocalEventDispatcher.java:56)
    at com.hazelcast.util.executor.StripedExecutor$Worker.process(StripedExecutor.java:187)
    at com.hazelcast.util.executor.StripedExecutor$Worker.run(StripedExecutor.java:171)
sample-0 2016/05/25 09:32:08,671 GMT+0200 INFO [Petals.Extensions.EmbeddedRegistryOverlayService] : Petals Registry Overlay local member stopped.
sample-0 2016/05/25 09:32:08,674 GMT+0200 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : The NIO Server agent is stopped.
sample-0 2016/05/25 09:32:08,675 GMT+0200 INFO [Petals.Server] : Server STOPPED
    Even though they are inoffensive, this shows a problem of concurrency during registry stop w.r.t. to client disconnections.
  • Environment:
    -

Issue Links

Depends
 

Activity

Ascending order - Click to sort in descending order
Victor NOËL made changes - Fri, 27 May 2016 - 11:46:51 +0200
Field Original Value New Value
Status New [ 10000 ] Open [ 10002 ]
Priority Minor [ 4 ]
Assignee Christophe DENEUX [ cdeneux ] Victor NOËL [ vnoel ]
Victor NOËL made changes - Fri, 27 May 2016 - 11:46:55 +0200
Status Open [ 10002 ] In Progress [ 10003 ]
Victor NOËL made changes - Fri, 27 May 2016 - 11:48:57 +0200
Link This issue depends on PETALSREGOVER-18 [ PETALSREGOVER-18 ]
Victor NOËL made changes - Fri, 27 May 2016 - 11:49:04 +0200
Status In Progress [ 10003 ] Resolved [ 10004 ]
Fix Version/s 5.0.2 [ 10661 ]
Resolution Fixed [ 1 ]

People

Dates

  • Created:
    Fri, 27 May 2016 - 11:46:38 +0200
    Updated:
    Fri, 27 May 2016 - 11:49:04 +0200
    Resolved:
    Fri, 27 May 2016 - 11:49:04 +0200
Atlassian JIRA (v4.1.2#531) | Report a problem | Request a feature | Contact administrators
Powered by a free Atlassian JIRA open source license for Petals ESB. Try JIRA - bug tracking software for your team.