Skip to content

Instantly share code, notes, and snippets.

Created July 23, 2012 14:40
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save anonymous/3163968 to your computer and use it in GitHub Desktop.
Save anonymous/3163968 to your computer and use it in GitHub Desktop.
infinispan log files
<config xmlns="urn:org:jgroups"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="urn:org:jgroups file:schema/JGroups-3.1.xsd">
<TCP
bind_addr="server2"
bind_port="${jgroups.tcp.port:7800}"
loopback="true"
port_range="30"
recv_buf_size="20m"
send_buf_size="640k"
discard_incompatible_packets="true"
max_bundle_size="64000"
max_bundle_timeout="30"
enable_bundling="true"
use_send_queues="true"
enable_diagnostics="false"
bundler_type="old"
thread_naming_pattern="pl"
thread_pool.enabled="true"
thread_pool.min_threads="2"
thread_pool.max_threads="30"
thread_pool.keep_alive_time="60000"
thread_pool.queue_enabled="true"
thread_pool.queue_max_size="100"
thread_pool.rejection_policy="Discard"
oob_thread_pool.enabled="true"
oob_thread_pool.min_threads="2"
oob_thread_pool.max_threads="30"
oob_thread_pool.keep_alive_time="60000"
oob_thread_pool.queue_enabled="false"
oob_thread_pool.queue_max_size="100"
oob_thread_pool.rejection_policy="Discard"
/>
<TCPPING
initial_hosts="server3[7800],server4[7800]"
timeout="10000"
ergonomics="false"
/>
<MERGE2 max_interval="30000" min_interval="10000"/>
<FD_SOCK/>
<FD timeout="3000" max_tries="3"/>
<VERIFY_SUSPECT timeout="1500"/>
<pbcast.NAKACK2 use_mcast_xmit="false"
xmit_interval="1000"
xmit_table_num_rows="100"
xmit_table_msgs_per_row="10000"
xmit_table_max_compaction_time="10000"
max_msg_batch_size="100"/>
<UNICAST2 stable_interval="5000"
xmit_interval="500"
max_bytes="1m"
xmit_table_num_rows="20"
xmit_table_msgs_per_row="10000"
xmit_table_max_compaction_time="10000"
max_msg_batch_size="100"/>
<pbcast.STABLE stability_delay="500" desired_avg_gossip="5000" max_bytes="1m"/>
<pbcast.GMS print_local_addr="false" join_timeout="3000" view_bundling="true"/>
<UFC max_credits="200k" min_threshold="0.20"/>
<MFC max_credits="200k" min_threshold="0.20"/>
<FRAG2 frag_size="60000"/>
<RSVP timeout="60000" resend_interval="500" ack_on_delivery="false" />
</config>
2008-06-29 07:03:27,220 INFO [STDOUT] ( ) 07:03:27,220 INFO [JGroupsTransport] ISPN000078: Starting JGroups Channel
2008-06-29 07:03:35,063 INFO [STDOUT] ( ) 07:03:35,063 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|2] [server4-51070, server3-43888, server2-55794]
2008-06-29 07:03:35,104 INFO [STDOUT] ( ) 07:03:35,104 INFO [JGroupsTransport] ISPN000079: Cache local address is server2-55794, physical addresses are [xxx:7800]
2008-06-29 07:03:35,134 INFO [STDOUT] ( ) 07:03:35,134 INFO [GlobalComponentRegistry] ISPN000128: Infinispan version: Infinispan 'Delirium' 5.2.0.ALPHA1
2008-06-29 07:17:15,800 INFO [STDOUT] (Incoming-2,server2-55794) 07:17:15,800 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|3] [server4-51070, server2-55794]
2008-06-29 07:17:28,144 INFO [STDOUT] (Incoming-1,server2-55794) 07:17:28,144 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|4] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|2] [server3-43888], [server4-51070|3] [server4-51070, server2-55794]
2008-06-29 07:18:11,767 INFO [STDOUT] (Incoming-1,server2-55794) 07:18:11,767 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server2-55794|5] [server2-55794, server3-43888]
2008-06-29 07:18:24,177 INFO [STDOUT] (Incoming-2,server2-55794) 07:18:24,177 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|6] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|4] [server4-51070], [server2-55794|5] [server2-55794, server3-43888]
2008-06-29 07:18:24,286 INFO [STDOUT] (OOB-8,server2-55794) 07:18:24,286 INFO [CacheViewsManagerImpl] ISPN000170: Our last committed view (CacheView{viewId=11, members=[server3-43888, server2-55794]}) is not the same as the coordinator's last committed view (CacheView{viewId=8, members=[server3-43888, server4-51070, server2-55794]}). This is normal during a merge
2008-06-29 07:19:06,742 INFO [STDOUT] (OOB-13,server2-55794) 07:19:06,742 WARN [FD] I was suspected by server4-51070; ignoring the SUSPECT message and sending back a HEARTBEAT_ACK
2008-06-29 07:19:19,853 INFO [STDOUT] (Incoming-1,server2-55794) 07:19:19,852 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|8] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|7] [server4-51070, server3-43888], [server4-51070|6] [server2-55794]
2008-06-29 07:19:19,959 INFO [STDOUT] (OOB-14,server2-55794) 07:19:19,959 INFO [CacheViewsManagerImpl] ISPN000170: Our last committed view (CacheView{viewId=15, members=[server4-51070, server3-43888, server2-55794]}) is not the same as the coordinator's last committed view (CacheView{viewId=16, members=[server4-51070, server3-43888]}). This is normal during a merge
2008-06-29 07:24:01,689 INFO [STDOUT] (Incoming-1,server2-55794) 07:24:01,689 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server2-55794|9] [server2-55794, server3-43888]
2008-06-29 07:24:15,198 INFO [STDOUT] (Incoming-2,server2-55794) 07:24:15,198 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|10] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|8] [server4-51070], [server2-55794|9] [server2-55794, server3-43888]
2008-06-29 07:24:15,315 INFO [STDOUT] (OOB-16,server2-55794) 07:24:15,315 INFO [CacheViewsManagerImpl] ISPN000170: Our last committed view (CacheView{viewId=23, members=[server3-43888, server2-55794]}) is not the same as the coordinator's last committed view (CacheView{viewId=20, members=[server4-51070, server3-43888, server2-55794]}). This is normal during a merge
2008-06-29 07:24:56,115 INFO [STDOUT] (Incoming-1,server2-55794) 07:24:56,115 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|12] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|11] [server4-51070, server3-43888], [server4-51070|10] [server2-55794]
2008-06-29 07:24:56,218 INFO [STDOUT] (OOB-13,server2-55794) 07:24:56,218 INFO [CacheViewsManagerImpl] ISPN000170: Our last committed view (CacheView{viewId=27, members=[server4-51070, server3-43888, server2-55794]}) is not the same as the coordinator's last committed view (CacheView{viewId=28, members=[server4-51070, server3-43888]}). This is normal during a merge
2008-06-29 07:28:22,708 INFO [STDOUT] (OOB-21,server2-55794) 07:28:22,708 WARN [FD] I was suspected by server4-51070; ignoring the SUSPECT message and sending back a HEARTBEAT_ACK
2008-06-29 07:28:33,772 INFO [STDOUT] (Incoming-2,server2-55794) 07:28:33,772 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|14] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|13] [server4-51070, server3-43888], [server4-51070|12] [server2-55794]
2008-06-29 07:28:33,891 INFO [STDOUT] (OOB-25,server2-55794) 07:28:33,891 INFO [CacheViewsManagerImpl] ISPN000170: Our last committed view (CacheView{viewId=32, members=[server4-51070, server3-43888, server2-55794]}) is not the same as the coordinator's last committed view (CacheView{viewId=33, members=[server4-51070, server3-43888]}). This is normal during a merge
2008-06-29 07:03:19,575 INFO [STDOUT] ( ) 07:03:19,575 INFO [JGroupsTransport] ISPN000078: Starting JGroups Channel
2008-06-29 07:03:23,943 INFO [STDOUT] ( ) 07:03:23,943 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|1] [server4-51070, server3-43888]
2008-06-29 07:03:23,989 INFO [STDOUT] ( ) 07:03:23,989 INFO [JGroupsTransport] ISPN000079: Cache local address is server3-43888, physical addresses are [xxx:7800]
2008-06-29 07:03:24,021 INFO [STDOUT] ( ) 07:03:24,021 INFO [GlobalComponentRegistry] ISPN000128: Infinispan version: Infinispan 'Delirium' 5.2.0.ALPHA1
2008-06-29 07:03:33,880 INFO [STDOUT] (Incoming-2,server3-43888) 07:03:33,880 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|2] [server4-51070, server3-43888, server2-55794]
2008-06-29 07:17:17,731 INFO [STDOUT] (OOB-4,server3-43888) 07:17:17,731 WARN [FD] I was suspected by server4-51070; ignoring the SUSPECT message and sending back a HEARTBEAT_ACK
2008-06-29 07:17:28,772 INFO [STDOUT] (Incoming-2,server3-43888) 07:17:28,772 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|4] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|2] [server3-43888], [server4-51070|3] [server4-51070, server2-55794]
2008-06-29 07:17:28,899 INFO [STDOUT] (OOB-12,server3-43888) 07:17:28,899 INFO [CacheViewsManagerImpl] ISPN000170: Our last committed view (CacheView{viewId=3, members=[server4-51070, server3-43888, server2-55794]}) is not the same as the coordinator's last committed view (CacheView{viewId=4, members=[server4-51070, server2-55794]}). This is normal during a merge
2008-06-29 07:18:12,462 INFO [STDOUT] (Incoming-2,server3-43888) 07:18:12,462 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server2-55794|5] [server2-55794, server3-43888]
2008-06-29 07:18:24,850 INFO [STDOUT] (Incoming-1,server3-43888) 07:18:24,849 WARN [NAKACK2] server3-43888: dropped message 10 from server4-51070 (sender not in table [server2-55794, server3-43888]), view=[server2-55794|5] [server2-55794, server3-43888]
2008-06-29 07:18:24,865 INFO [STDOUT] (Incoming-2,server3-43888) 07:18:24,865 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|6] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|4] [server4-51070], [server2-55794|5] [server2-55794, server3-43888]
2008-06-29 07:18:24,942 INFO [STDOUT] (OOB-5,server3-43888) 07:18:24,942 INFO [CacheViewsManagerImpl] ISPN000170: Our last committed view (CacheView{viewId=11, members=[server3-43888, server2-55794]}) is not the same as the coordinator's last committed view (CacheView{viewId=8, members=[server3-43888, server4-51070, server2-55794]}). This is normal during a merge
2008-06-29 07:19:06,897 INFO [STDOUT] (Incoming-2,server3-43888) 07:19:06,897 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|7] [server4-51070, server3-43888]
2008-06-29 07:19:20,540 INFO [STDOUT] (Incoming-1,server3-43888) 07:19:20,540 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|8] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|7] [server4-51070, server3-43888], [server4-51070|6] [server2-55794]
2008-06-29 07:24:01,697 INFO [STDOUT] ( ) 07:24:01,694 ERROR [InvocationContextInterceptor] ISPN000136: Execution error
org.infinispan.CacheException: org.jgroups.TimeoutException: timeout sending message to server4-51070
at org.infinispan.util.Util.rewrapAsCacheException(Util.java:526)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.invokeRemoteCommand(CommandAwareRpcDispatcher.java:173)
at org.infinispan.remoting.transport.jgroups.JGroupsTransport.invokeRemotely(JGroupsTransport.java:494)
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:168)
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:190)
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:247)
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:234)
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:229)
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:224)
at org.infinispan.interceptors.DistributionInterceptor.handleWriteCommand(DistributionInterceptor.java:507)
at org.infinispan.interceptors.DistributionInterceptor.visitPutKeyValueCommand(DistributionInterceptor.java:276)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.infinispan.interceptors.EntryWrappingInterceptor.invokeNextAndApplyChanges(EntryWrappingInterceptor.java:212)
at org.infinispan.interceptors.EntryWrappingInterceptor.visitPutKeyValueCommand(EntryWrappingInterceptor.java:147)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.infinispan.interceptors.locking.NonTransactionalLockingInterceptor.visitPutKeyValueCommand(NonTransactionalLockingInterceptor.java:68)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:130)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:62)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.infinispan.interceptors.StateTransferLockInterceptor.handleWithRetries(StateTransferLockInterceptor.java:212)
at org.infinispan.interceptors.StateTransferLockInterceptor.handleWriteCommand(StateTransferLockInterceptor.java:180)
at org.infinispan.interceptors.StateTransferLockInterceptor.visitPutKeyValueCommand(StateTransferLockInterceptor.java:151)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:132)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:91)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:62)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:345)
at org.infinispan.CacheImpl.executeCommandAndCommitIfNeeded(CacheImpl.java:1007)
at org.infinispan.CacheImpl.putIfAbsent(CacheImpl.java:718)
Caused by: org.jgroups.TimeoutException: timeout sending message to server4-51070
at org.jgroups.blocks.MessageDispatcher.sendMessage(MessageDispatcher.java:359)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.processSingleCall(CommandAwareRpcDispatcher.java:276)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.invokeRemoteCommand(CommandAwareRpcDispatcher.java:166)
... 42 more
2008-06-29 07:03:12,149 INFO [STDOUT] ( ) 07:03:12,149 INFO [JGroupsTransport] ISPN000078: Starting JGroups Channel
2008-06-29 07:03:23,943 INFO [STDOUT] ( ) 07:03:23,943 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|0] [server4-51070]
2008-06-29 07:03:23,974 INFO [STDOUT] ( ) 07:03:23,974 INFO [JGroupsTransport] ISPN000079: Cache local address is server4-51070, physical addresses are [xxx:7800]
2008-06-29 07:03:24,005 INFO [STDOUT] ( ) 07:03:24,005 INFO [GlobalComponentRegistry] ISPN000128: Infinispan version: Infinispan 'Delirium' 5.2.0.ALPHA1
2008-06-29 07:03:24,910 INFO [STDOUT] (ViewHandler,server4-51070) 07:03:24,910 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|1] [server4-51070, server3-43888]
2008-06-29 07:03:33,615 INFO [STDOUT] (Incoming-1,server4-51070) 07:03:33,615 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|2] [server4-51070, server3-43888, server2-55794]
2008-06-29 07:17:17,403 INFO [STDOUT] (Incoming-1,server4-51070) 07:17:17,403 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|3] [server4-51070, server2-55794]
2008-06-29 07:17:29,746 INFO [STDOUT] (Incoming-2,server4-51070) 07:17:29,746 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|4] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|2] [server3-43888], [server4-51070|3] [server4-51070, server2-55794]
2008-06-29 07:18:15,796 INFO [STDOUT] (OOB-11,server4-51070) 07:18:15,774 WARN [FD] I was suspected by server3-43888; ignoring the SUSPECT message and sending back a HEARTBEAT_ACK
2008-06-29 07:18:25,797 INFO [STDOUT] (Incoming-2,server4-51070) 07:18:25,797 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|6] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|4] [server4-51070], [server2-55794|5] [server2-55794, server3-43888]
2008-06-29 07:19:07,840 INFO [STDOUT] (Incoming-2,server4-51070) 07:19:07,840 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|7] [server4-51070, server3-43888]
2008-06-29 07:19:21,488 INFO [STDOUT] (Incoming-1,server4-51070) 07:19:21,488 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|8] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|7] [server4-51070, server3-43888], [server4-51070|6] [server2-55794]
2008-06-29 07:24:06,760 INFO [STDOUT] (OOB-19,server4-51070) 07:24:06,760 WARN [FD] I was suspected by server3-43888; ignoring the SUSPECT message and sending back a HEARTBEAT_ACK
2008-06-29 07:24:16,834 INFO [STDOUT] (Incoming-2,server4-51070) 07:24:16,834 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|10] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|8] [server4-51070], [server2-55794|9] [server2-55794, server3-43888]
2008-06-29 07:24:41,329 INFO [STDOUT] (Incoming-2,server4-51070) 07:24:41,329 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|11] [server4-51070, server3-43888]
2008-06-29 07:24:57,748 INFO [STDOUT] (Incoming-1,server4-51070) 07:24:57,748 INFO [JGroupsTransport] ISPN000093: Received new, MERGED cluster view: MergeView::[server4-51070|12] [server4-51070, server2-55794, server3-43888], subgroups=[server4-51070|11] [server4-51070, server3-43888], [server4-51070|10] [server2-55794]
2008-06-29 07:28:17,322 INFO [STDOUT] (Incoming-2,server4-51070) 07:28:17,322 INFO [JGroupsTransport] ISPN000094: Received new cluster view: [server4-51070|13] [server4-51070, server3-43888]
2008-06-29 07:28:17,344 INFO [STDOUT] ( ) 07:28:17,340 ERROR [InvocationContextInterceptor] ISPN000136: Execution error
org.infinispan.util.concurrent.TimeoutException: Timed out waiting for the state transfer to end
at org.infinispan.interceptors.StateTransferLockInterceptor.handleWithRetries(StateTransferLockInterceptor.java:222)
at org.infinispan.interceptors.StateTransferLockInterceptor.handleWriteCommand(StateTransferLockInterceptor.java:180)
at org.infinispan.interceptors.StateTransferLockInterceptor.visitPutKeyValueCommand(StateTransferLockInterceptor.java:151)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:132)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:91)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:62)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:77)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:345)
at org.infinispan.CacheImpl.executeCommandAndCommitIfNeeded(CacheImpl.java:1007)
at org.infinispan.CacheImpl.putIfAbsent(CacheImpl.java:718)
at org.infinispan.CacheImpl.putIfAbsent(CacheImpl.java:709)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment