| Run | | | Workspace | | | Log | | | Issues | | | Fixes | | | Pre-reqs | | | Stats | | | XRef | | | Maven Repository Proxy Log | |
||
Testsuite: org.apache.catalina.tribes.group.interceptors.TestTcpFailureDetector
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 17.863 sec
------------- Standard Output ---------------
testTcpMcastFail()
Channel-2:member added[Channel-1]
Channel-1:member added[Channel-2]
testTcpFailureMemberAdd()
Channel-2:member added[Channel-1]
testTcpSendFailureMemberDrop()
Channel-2:member added[Channel-1]
Channel-1:member added[Channel-2]
Channel-1:member disappeared[Channel-2]
------------- ---------------- ---------------
------------- Standard Error -----------------
Mar 30, 2023 8:21:11 PM org.apache.catalina.tribes.group.GroupChannel start
WARNING: No utility executor was set, creating one
Mar 30, 2023 8:21:12 PM org.apache.catalina.tribes.transport.ReceiverBase bind
INFO: Receiver Server Socket bound to:[localhost/127.0.0.1:4001]
Mar 30, 2023 8:21:12 PM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket
INFO: Setting cluster mcast soTimeout to [500]
Mar 30, 2023 8:21:12 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[4]
Mar 30, 2023 8:21:12 PM org.apache.catalina.tribes.group.interceptors.DomainFilterInterceptor memberAdded
INFO: Member [org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4002,{127, 0, 0, 1},4002, alive=1002, securePort=-1, UDP Port=-1, id={84 22 -8 112 99 -2 72 54 -102 -62 -124 -29 74 63 80 -105 }, payload={}, command={}, domain={80 49 -62 -1 -12 -53 74 -107 -74 ...(16)}]] was refused to join cluster
Mar 30, 2023 8:21:12 PM org.apache.catalina.tribes.group.interceptors.DomainFilterInterceptor memberAdded
INFO: Member [org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4000,{127, 0, 0, 1},4000, alive=1003, securePort=-1, UDP Port=-1, id={-9 -98 126 117 88 66 76 70 -100 102 41 2 100 -47 68 108 }, payload={}, command={}, domain={80 49 -62 -1 -12 -53 74 -107 -74 ...(16)}]] was refused to join cluster
Mar 30, 2023 8:21:13 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[4]
Mar 30, 2023 8:21:13 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[8]
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[8]
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.group.GroupChannel start
WARNING: No utility executor was set, creating one
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.transport.ReceiverBase bind
INFO: Receiver Server Socket bound to:[localhost/127.0.0.1:4003]
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket
INFO: Setting cluster mcast soTimeout to [500]
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[4]
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.group.interceptors.DomainFilterInterceptor memberAdded
INFO: Member [org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4002,{127, 0, 0, 1},4002, alive=3003, securePort=-1, UDP Port=-1, id={84 22 -8 112 99 -2 72 54 -102 -62 -124 -29 74 63 80 -105 }, payload={}, command={}, domain={80 49 -62 -1 -12 -53 74 -107 -74 ...(16)}]] was refused to join cluster
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.group.interceptors.DomainFilterInterceptor memberAdded
INFO: Member [org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4000,{127, 0, 0, 1},4000, alive=3005, securePort=-1, UDP Port=-1, id={-9 -98 126 117 88 66 76 70 -100 102 41 2 100 -47 68 108 }, payload={}, command={}, domain={80 49 -62 -1 -12 -53 74 -107 -74 ...(16)}]] was refused to join cluster
Mar 30, 2023 8:21:14 PM org.apache.catalina.tribes.io.BufferPool getBufferPool
INFO: Created a buffer pool with max size:[104857600] bytes of type: [org.apache.catalina.tribes.io.BufferPool]
Mar 30, 2023 8:21:15 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[4]
Mar 30, 2023 8:21:15 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[8]
Mar 30, 2023 8:21:16 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[8]
Mar 30, 2023 8:21:18 PM org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread run
WARNING: Error receiving mcast package. Sleeping 500ms
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@4eb4d769[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@2fc8562c[Wrapped task = org.apache.catalina.tribes.membership.McastServiceImpl$$Lambda$83/0x0000000800cc6ed8@6acacdb1]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5b6a82a2[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 4]
at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2065)
at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:833)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.execute(ScheduledThreadPoolExecutor.java:705)
at org.apache.catalina.tribes.membership.McastServiceImpl.memberDataReceived(McastServiceImpl.java:433)
at org.apache.catalina.tribes.membership.McastServiceImpl.receive(McastServiceImpl.java:381)
at org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread.run(McastServiceImpl.java:581)
Mar 30, 2023 8:21:19 PM org.apache.catalina.tribes.group.interceptors.TcpFailureDetector memberDisappeared
INFO: Received memberDisappeared[org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4003,{127, 0, 0, 1},4003, alive=1506, securePort=-1, UDP Port=-1, id={-45 -91 -127 78 -34 -84 72 46 -115 -46 -99 -117 -65 -4 -56 51 }, payload={67 104 97 110 110 101 108 45 50 ...(9)}, command={}, domain={89 -101 4 -115 3 126 77 -95 -70 ...(16)}]] message. Will verify.
Mar 30, 2023 8:21:19 PM org.apache.catalina.tribes.group.interceptors.TcpFailureDetector memberDisappeared
INFO: Verification complete. Member still alive[org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4003,{127, 0, 0, 1},4003, alive=1506, securePort=-1, UDP Port=-1, id={-45 -91 -127 78 -34 -84 72 46 -115 -46 -99 -117 -65 -4 -56 51 }, payload={67 104 97 110 110 101 108 45 50 ...(9)}, command={}, domain={89 -101 4 -115 3 126 77 -95 -70 ...(16)}]]
Mar 30, 2023 8:21:21 PM org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread run
WARNING: Error receiving mcast package. Sleeping 500ms
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@280b1729[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@6d8d84e[Wrapped task = org.apache.catalina.tribes.membership.McastServiceImpl$$Lambda$83/0x0000000800cc6ed8@7c110ac5]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5b6a82a2[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 4]
at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2065)
at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:833)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.execute(ScheduledThreadPoolExecutor.java:705)
at org.apache.catalina.tribes.membership.McastServiceImpl.memberDataReceived(McastServiceImpl.java:433)
at org.apache.catalina.tribes.membership.McastServiceImpl.receive(McastServiceImpl.java:381)
at org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread.run(McastServiceImpl.java:581)
Mar 30, 2023 8:21:21 PM org.apache.catalina.tribes.group.GroupChannel start
WARNING: No utility executor was set, creating one
Mar 30, 2023 8:21:21 PM org.apache.catalina.tribes.transport.ReceiverBase bind
INFO: Receiver Server Socket bound to:[localhost/127.0.0.1:4000]
Mar 30, 2023 8:21:21 PM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket
INFO: Setting cluster mcast soTimeout to [500]
Mar 30, 2023 8:21:21 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[4]
Mar 30, 2023 8:21:22 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[4]
Mar 30, 2023 8:21:22 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[8]
Mar 30, 2023 8:21:23 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[8]
Mar 30, 2023 8:21:23 PM org.apache.catalina.tribes.group.GroupChannel start
WARNING: No utility executor was set, creating one
Mar 30, 2023 8:21:23 PM org.apache.catalina.tribes.transport.ReceiverBase bind
INFO: Receiver Server Socket bound to:[localhost/127.0.0.1:4001]
Mar 30, 2023 8:21:23 PM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket
INFO: Setting cluster mcast soTimeout to [500]
Mar 30, 2023 8:21:23 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[4]
Mar 30, 2023 8:21:24 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[4]
Mar 30, 2023 8:21:24 PM org.apache.catalina.tribes.group.GroupChannel start
WARNING: No utility executor was set, creating one
Mar 30, 2023 8:21:24 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[8]
Mar 30, 2023 8:21:25 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[8]
Mar 30, 2023 8:21:25 PM org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread run
WARNING: Error receiving mcast package. Sleeping 500ms
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@27d55db1[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@2d2d36c1[Wrapped task = org.apache.catalina.tribes.membership.McastServiceImpl$$Lambda$83/0x0000000800cc6ed8@23eacd14]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@62d7c72f[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 4]
at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2065)
at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:833)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.execute(ScheduledThreadPoolExecutor.java:705)
at org.apache.catalina.tribes.membership.McastServiceImpl.memberDataReceived(McastServiceImpl.java:433)
at org.apache.catalina.tribes.membership.McastServiceImpl.receive(McastServiceImpl.java:381)
at org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread.run(McastServiceImpl.java:581)
Mar 30, 2023 8:21:25 PM org.apache.catalina.tribes.group.GroupChannel start
WARNING: No utility executor was set, creating one
Mar 30, 2023 8:21:25 PM org.apache.catalina.tribes.transport.ReceiverBase bind
INFO: Receiver Server Socket bound to:[localhost/127.0.0.1:4000]
Mar 30, 2023 8:21:25 PM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket
INFO: Setting cluster mcast soTimeout to [500]
Mar 30, 2023 8:21:25 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[4]
Mar 30, 2023 8:21:26 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[4]
Mar 30, 2023 8:21:26 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[8]
Mar 30, 2023 8:21:27 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[8]
Mar 30, 2023 8:21:27 PM org.apache.catalina.tribes.group.GroupChannel start
WARNING: No utility executor was set, creating one
Mar 30, 2023 8:21:27 PM org.apache.catalina.tribes.transport.ReceiverBase bind
INFO: Receiver Server Socket bound to:[localhost/127.0.0.1:4001]
Mar 30, 2023 8:21:27 PM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket
INFO: Setting cluster mcast soTimeout to [500]
Mar 30, 2023 8:21:27 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[4]
Mar 30, 2023 8:21:28 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[4]
Mar 30, 2023 8:21:28 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Sleeping for [1000] milliseconds to establish cluster membership, start level:[8]
Mar 30, 2023 8:21:29 PM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
INFO: Done sleeping, membership established, start level:[8]
Mar 30, 2023 8:21:29 PM org.apache.catalina.tribes.transport.nio.ParallelNioSender doLoop
WARNING: Member send is failing for:[tcp://{127, 0, 0, 1}:4001] ; Setting to suspect and retrying.
Mar 30, 2023 8:21:29 PM org.apache.catalina.tribes.group.interceptors.TcpFailureDetector memberDisappeared
INFO: Received memberDisappeared[org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4001,{127, 0, 0, 1},4001, alive=2006, securePort=-1, UDP Port=-1, id={94 108 -126 114 8 -3 76 -75 -75 -117 12 -117 -38 -70 13 -2 }, payload={67 104 97 110 110 101 108 45 50 ...(9)}, command={}, domain={126 21 -116 -35 -4 113 66 33 -107 ...(16)}]] message. Will verify.
Mar 30, 2023 8:21:29 PM org.apache.catalina.tribes.group.interceptors.TcpFailureDetector memberDisappeared
INFO: Verification complete. Member disappeared[org.apache.catalina.tribes.membership.MemberImpl[tcp://{127, 0, 0, 1}:4001,{127, 0, 0, 1},4001, alive=2006, securePort=-1, UDP Port=-1, id={94 108 -126 114 8 -3 76 -75 -75 -117 12 -117 -38 -70 13 -2 }, payload={67 104 97 110 110 101 108 45 50 ...(9)}, command={}, domain={126 21 -116 -35 -4 113 66 33 -107 ...(16)}]]
Mar 30, 2023 8:21:29 PM org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread run
WARNING: Error receiving mcast package. Sleeping 500ms
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@2bf78563[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@6aca8f58[Wrapped task = org.apache.catalina.tribes.membership.McastServiceImpl$$Lambda$83/0x0000000800cc6ed8@6ef506d9]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@2418c591[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 2]
at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2065)
at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:833)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.execute(ScheduledThreadPoolExecutor.java:705)
at org.apache.catalina.tribes.membership.McastServiceImpl.memberDataReceived(McastServiceImpl.java:433)
at org.apache.catalina.tribes.membership.McastServiceImpl.receive(McastServiceImpl.java:381)
at org.apache.catalina.tribes.membership.McastServiceImpl$ReceiverThread.run(McastServiceImpl.java:581)
------------- ---------------- ---------------
Testcase: testTcpMcastFail took 9.38 sec
Testcase: testTcpFailureMemberAdd took 4.247 sec
Testcase: testTcpSendFailureMemberDrop took 4.228 sec
Apache Gump, Gump, Apache, the Apache feather logo, and the Apache Gump project logos are trademarks of The Apache Software Foundation.
Last Updated: Thu, 30 Mar 2023 18:00:04 ().