Skip to Main Content

Integration

Announcement

For appeals, questions and feedback about Oracle Forums, please email oracle-forums-moderators_us@oracle.com. Technical questions should be asked in the appropriate category. Thank you!

com.tangosol.net.RequestTimeoutException on Windows - easy to reproduce...

snidely_whiplashJun 8 2010 — edited Jun 8 2010
I have 3 storage enabled Coherence instances running on one server. Client apps connect from other other machines. Each of those three nodes is in a cmd console window. If you either turn on the window's "quick edit mode" and left-click in the console window to select text or you choose the menu items "Edit->Select All" you will pause output to the screen while selecting text to copy to the clipboard. If one of the nodes' consoles is left in this "select" state it will screw up the cluster. The node doesn't get kicked out of the cluster but it doesn't quite work right either. Some services seem to work but others don't. It seems like console output should be paused but the node should continue to run in the background or if Windows doesn't allow that, then the node should be removed from the cluster.

-Andrew


Map (test): cache orders
2010-06-08 08:04:39.615/22.885 Oracle Coherence GE 3.5.3/465 <D5> (thread=DistributedCache:DistributedOrdersService, member=45): Service DistributedOrdersService joined the cluster with senior service member 1
2010-06-08 08:04:39.615/22.885 Oracle Coherence GE 3.5.3/465 <D5> (thread=DistributedCache:DistributedOrdersService, member=45): Service DistributedOrdersService: received ServiceConfigSync containing 259 entries
2010-06-08 08:05:10.035/53.305 Oracle Coherence GE 3.5.3/465 <Error> (thread=main, member=45): Error while starting service "DistributedOrdersService": com.tangosol.net.RequestTimeoutException: Timeout during service start: ServiceInfo(Id=5, Name=DistributedOrdersService, Type=DistributedCache
  MemberSet=ServiceMemberSet(
    OldestMember=Member(Id=1, Timestamp=2010-05-19 20:53:49.341, Address=192.168.10.197:8088, MachineId=38410, Location=machine:amd4,process:6148,member:Administrator, Role=CoherenceServer)
    ActualMemberSet=MemberSet(Size=10, BitSetCount=2
      Member(Id=1, Timestamp=2010-05-19 20:53:49.341, Address=192.168.10.197:8088, MachineId=38410, Location=machine:amd4,process:6148,member:Administrator, Role=CoherenceServer)
      Member(Id=2, Timestamp=2010-05-19 20:53:52.679, Address=192.168.10.197:8089, MachineId=38410, Location=machine:amd4,process:5656,member:Administrator, Role=CoherenceServer)
      Member(Id=3, Timestamp=2010-05-19 20:53:54.216, Address=192.168.10.197:8090, MachineId=38410, Location=machine:amd4,process:4344,member:Administrator, Role=CoherenceServer)
      Member(Id=18, Timestamp=2010-06-08 05:26:29.119, Address=192.168.10.197:8093, MachineId=38410, Location=machine:amd4,process:10656,member:Administrator, Role=TestApp1)
      Member(Id=28, Timestamp=2010-06-08 06:30:06.123, Address=192.168.10.100:8088, MachineId=25188, Location=machine:testbox,process:1416,member:r1, Role=TestApp2)
      Member(Id=29, Timestamp=2010-06-08 06:30:07.529, Address=192.168.10.100:8089, MachineId=25188, Location=machine:testbox,process:3552,member:r1, Role=TestApp3)
      Member(Id=31, Timestamp=2010-06-08 06:50:00.259, Address=192.168.10.28:8088, MachineId=25116, Location=process:1868,member:b2, Role=TestApp4)
      Member(Id=32, Timestamp=2010-06-08 06:50:00.345, Address=192.168.10.28:8089, MachineId=25116, Location=process:3888,member:b2, Role=TestApp2)
      Member(Id=38, Timestamp=2010-06-08 07:38:07.711, Address=192.168.10.180:8090, MachineId=25268, Location=machine:b3,process:2024,member:Administrator, Role=TestApp2)
      Member(Id=45, Timestamp=2010-06-08 08:04:41.02, Address=192.168.10.167:8088, MachineId=25255, Location=machine:box2,process:1048,member:andrew, Role=CoherenceConsole)
      )
    MemberId/ServiceVersion/ServiceJoined/ServiceLeaving
      1/3.5/Wed May 19 20:53:52 CDT 2010/false,
      2/3.5/Wed May 19 20:53:53 CDT 2010/false,
      3/3.5/Wed May 19 20:53:55 CDT 2010/false,
      18/3.5/Tue Jun 08 05:26:29 CDT 2010/false,
      28/3.5/Tue Jun 08 06:30:27 CDT 2010/false,
      29/3.5/Tue Jun 08 06:36:34 CDT 2010/false,
      31/3.5/Tue Jun 08 06:50:25 CDT 2010/false,
      32/3.5/Tue Jun 08 06:50:28 CDT 2010/false,
      38/3.5/Tue Jun 08 07:38:32 CDT 2010/false,
      45/3.5/Tue Jun 08 08:05:01 CDT 2010/false
    )
)
        at com.tangosol.coherence.component.util.daemon.queueProcessor.service.Grid.onStartupTimeout(Grid.CDB:6)
        at com.tangosol.coherence.component.util.daemon.queueProcessor.Service.start(Service.CDB:28)
        at com.tangosol.coherence.component.util.daemon.queueProcessor.service.Grid.start(Grid.CDB:38)
        at com.tangosol.coherence.component.util.SafeService.startService(SafeService.CDB:28)
        at com.tangosol.coherence.component.util.safeService.SafeCacheService.startService(SafeCacheService.CDB:5)
        at com.tangosol.coherence.component.util.SafeService.ensureRunningService(SafeService.CDB:27)
        at com.tangosol.coherence.component.util.SafeService.start(SafeService.CDB:14)
        at com.tangosol.net.DefaultConfigurableCacheFactory.ensureService(DefaultConfigurableCacheFactory.java:1008)
        at com.tangosol.net.DefaultConfigurableCacheFactory.ensureCache(DefaultConfigurableCacheFactory.java:877)
        at com.tangosol.net.DefaultConfigurableCacheFactory.configureCache(DefaultConfigurableCacheFactory.java:1088)
        at com.tangosol.net.DefaultConfigurableCacheFactory.ensureCache(DefaultConfigurableCacheFactory.java:304)
        at com.tangosol.net.CacheFactory.getCache(CacheFactory.java:735)
        at com.tangosol.coherence.component.application.console.Coherence.doCache(Coherence.CDB:18)
        at com.tangosol.coherence.component.application.console.Coherence.processCommand(Coherence.CDB:209)
        at com.tangosol.coherence.component.application.console.Coherence.run(Coherence.CDB:39)
        at com.tangosol.coherence.component.application.console.Coherence.main(Coherence.CDB:3)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:613)
        at com.tangosol.net.CacheFactory.main(CacheFactory.java:1400)

2010-06-08 08:05:10.035/53.305 Oracle Coherence GE 3.5.3/465 <Error> (thread=main, member=45):
com.tangosol.net.RequestTimeoutException: Timeout during service start: ServiceInfo(Id=5, Name=DistributedOrdersService, Type=DistributedCache
  MemberSet=ServiceMemberSet(
    OldestMember=Member(Id=1, Timestamp=2010-05-19 20:53:49.341, Address=192.168.10.197:8088, MachineId=38410, Location=machine:amd4,process:6148,member:Administrator, Role=CoherenceServer)
    ActualMemberSet=MemberSet(Size=10, BitSetCount=2
      Member(Id=1, Timestamp=2010-05-19 20:53:49.341, Address=192.168.10.197:8088, MachineId=38410, Location=machine:amd4,process:6148,member:Administrator, Role=CoherenceServer)
      Member(Id=2, Timestamp=2010-05-19 20:53:52.679, Address=192.168.10.197:8089, MachineId=38410, Location=machine:amd4,process:5656,member:Administrator, Role=CoherenceServer)
      Member(Id=3, Timestamp=2010-05-19 20:53:54.216, Address=192.168.10.197:8090, MachineId=38410, Location=machine:amd4,process:4344,member:Administrator, Role=CoherenceServer)
      Member(Id=18, Timestamp=2010-06-08 05:26:29.119, Address=192.168.10.197:8093, MachineId=38410, Location=machine:amd4,process:10656,member:Administrator, Role=CoupGsecGsecCoup)
      Member(Id=28, Timestamp=2010-06-08 06:30:06.123, Address=192.168.10.100:8088, MachineId=25188, Location=machine:testbox,process:1416,member:r1, Role=TestApp2)
      Member(Id=29, Timestamp=2010-06-08 06:30:07.529, Address=192.168.10.100:8089, MachineId=25188, Location=machine:testbox,process:3552,member:r1, Role=TestApp3)
      Member(Id=31, Timestamp=2010-06-08 06:50:00.259, Address=192.168.10.28:8088, MachineId=25116, Location=process:1868,member:b2, Role=TestApp4)
      Member(Id=32, Timestamp=2010-06-08 06:50:00.345, Address=192.168.10.28:8089, MachineId=25116, Location=process:3888,member:b2, Role=TestApp2)
      Member(Id=38, Timestamp=2010-06-08 07:38:07.711, Address=192.168.10.180:8090, MachineId=25268, Location=machine:b3,process:2024,member:Administrator, Role=TestApp2)
      Member(Id=45, Timestamp=2010-06-08 08:04:41.02, Address=192.168.10.167:8088, MachineId=25255, Location=machine:box2,process:1048,member:andrew, Role=CoherenceConsole)
      )
    MemberId/ServiceVersion/ServiceJoined/ServiceLeaving
      1/3.5/Wed May 19 20:53:52 CDT 2010/false,
      2/3.5/Wed May 19 20:53:53 CDT 2010/false,
      3/3.5/Wed May 19 20:53:55 CDT 2010/false,
      18/3.5/Tue Jun 08 05:26:29 CDT 2010/false,
      28/3.5/Tue Jun 08 06:30:27 CDT 2010/false,
      29/3.5/Tue Jun 08 06:36:34 CDT 2010/false,
      31/3.5/Tue Jun 08 06:50:25 CDT 2010/false,
      32/3.5/Tue Jun 08 06:50:28 CDT 2010/false,
      38/3.5/Tue Jun 08 07:38:32 CDT 2010/false,
      45/3.5/Tue Jun 08 08:05:01 CDT 2010/false
    )
)
        at com.tangosol.coherence.component.util.daemon.queueProcessor.service.Grid.onStartupTimeout(Grid.CDB:6)
        at com.tangosol.coherence.component.util.daemon.queueProcessor.Service.start(Service.CDB:28)
        at com.tangosol.coherence.component.util.daemon.queueProcessor.service.Grid.start(Grid.CDB:38)
        at com.tangosol.coherence.component.util.SafeService.startService(SafeService.CDB:28)
        at com.tangosol.coherence.component.util.safeService.SafeCacheService.startService(SafeCacheService.CDB:5)
        at com.tangosol.coherence.component.util.SafeService.ensureRunningService(SafeService.CDB:27)
        at com.tangosol.coherence.component.util.SafeService.start(SafeService.CDB:14)
        at com.tangosol.net.DefaultConfigurableCacheFactory.ensureService(DefaultConfigurableCacheFactory.java:1008)
        at com.tangosol.net.DefaultConfigurableCacheFactory.ensureCache(DefaultConfigurableCacheFactory.java:877)
        at com.tangosol.net.DefaultConfigurableCacheFactory.configureCache(DefaultConfigurableCacheFactory.java:1088)
        at com.tangosol.net.DefaultConfigurableCacheFactory.ensureCache(DefaultConfigurableCacheFactory.java:304)
        at com.tangosol.net.CacheFactory.getCache(CacheFactory.java:735)
        at com.tangosol.coherence.component.application.console.Coherence.doCache(Coherence.CDB:18)
        at com.tangosol.coherence.component.application.console.Coherence.processCommand(Coherence.CDB:209)
        at com.tangosol.coherence.component.application.console.Coherence.run(Coherence.CDB:39)
        at com.tangosol.coherence.component.application.console.Coherence.main(Coherence.CDB:3)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:613)
        at com.tangosol.net.CacheFactory.main(CacheFactory.java:1400)
2010-06-08 08:05:10.035/53.305 Oracle Coherence GE 3.5.3/465 <Error> (thread=DistributedCache:DistributedOrdersService, member=45): validatePolls: This service timed-out due to unanswered handshake request. Manual intervention is required to stop the members that have not responded to this Poll
  {
  PollId=1, active
  InitTimeMillis=1276002279615
  Service=DistributedOrdersService (5)
  RespondedMemberSet=[1,2,18,28,29,31,32,38]
  LeftMemberSet=[]
  RemainingMemberSet=[3]
  }
2010-06-08 08:05:10.035/53.305 Oracle Coherence GE 3.5.3/465 <D5> (thread=DistributedCache:DistributedOrdersService, member=45): Service DistributedOrdersService left the cluster

 
Comments
Locked Post
New comments cannot be posted to this locked post.
Post Details
Locked on Jul 6 2010
Added on Jun 8 2010
3 comments
1,336 views