[IGNITE-15326] IgniteClientException in server node

• Type: Task
• Status: Resolved
• Priority: Minor
• Resolution: Cannot Reproduce
• Labels: ise
• Ignite Flags: Docs Required, Release Notes Required
• Assignee: Unassigned
• Reporter: Luchnikov Alexander
• Created: 17/Aug/21 13:03
• Updated: 27/Apr/23 12:04
• Resolved: 27/Apr/23 12:04

Description

In cases of exception on a thin client like

class org.apache.ignite.internal.processors.platform.client.IgniteClientException: Cache transaction timed out: GridNearTxLocal[xid=e38a9145b71-00000000-0e58-8a37-0000-000000000001, xidVersion=GridCacheVersion [topVer=240683575, order=1629203572798, nodeOrder=1], nearXidVersion=GridCacheVersion [topVer=240683575, order=1629203572798, nodeOrder=1], concurrency=OPTIMISTIC, isolation=SERIALIZABLE, state=ROLLED_BACK, invalidate=false, rollbackOnly=true, nodeId=12f44683-043f-4bfb-bf6d-7dc6f6348327, timeout=1000, startTime=1629203894182, duration=5021, label=null]
        at org.apache.ignite.internal.processors.platform.client.tx.ClientTxEndRequest.process(ClientTxEndRequest.java:72)
        at org.apache.ignite.internal.processors.platform.client.ClientRequestHandler.handle(ClientRequestHandler.java:99)
        at org.apache.ignite.internal.processors.odbc.ClientListenerNioListener.onMessage(ClientListenerNioListener.java:202)
        at org.apache.ignite.internal.processors.odbc.ClientListenerNioListener.onMessage(ClientListenerNioListener.java:56)
        at org.apache.ignite.internal.util.nio.GridNioFilterChain$TailFilter.onMessageReceived(GridNioFilterChain.java:279)
        at org.apache.ignite.internal.util.nio.GridNioFilterAdapter.proceedMessageReceived(GridNioFilterAdapter.java:109)
        at org.apache.ignite.internal.util.nio.GridNioAsyncNotifyFilter$3.body(GridNioAsyncNotifyFilter.java:97)
        at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at org.apache.ignite.internal.util.worker.GridWorkerPool$1.run(GridWorkerPool.java:70)
        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
        at java.base/java.lang.Thread.run(Thread.java:829)
Caused by: class org.apache.ignite.internal.transactions.IgniteTxTimeoutCheckedException: Cache transaction timed out: GridNearTxLocal[xid=e38a9145b71-00000000-0e58-8a37-0000-000000000001, xidVersion=GridCacheVersion [topVer=240683575, order=1629203572798, nodeOrder=1], nearXidVersion=GridCacheVersion [topVer=240683575, order=1629203572798, nodeOrder=1], concurrency=OPTIMISTIC, isolation=SERIALIZABLE, state=ROLLED_BACK, invalidate=false, rollbackOnly=true, nodeId=12f44683-043f-4bfb-bf6d-7dc6f6348327, timeout=1000, startTime=1629203894182, duration=5021, label=null]
        at org.apache.ignite.internal.processors.cache.transactions.IgniteTxLocalAdapter.checkValid(IgniteTxLocalAdapter.java:1389)
        at org.apache.ignite.internal.processors.cache.distributed.near.GridNearTxLocal.resume(GridNearTxLocal.java:3698)
        at org.apache.ignite.internal.processors.cache.distributed.near.GridNearTxLocal.resume(GridNearTxLocal.java:3683)
        at org.apache.ignite.internal.processors.platform.client.tx.ClientTxContext.acquire(ClientTxContext.java:58)
        at org.apache.ignite.internal.processors.platform.client.tx.ClientTxEndRequest.process(ClientTxEndRequest.java:62)
        ... 11 more

In the log of the server node, we have the ERROR message level

[15:43:45,494][SEVERE][client-connector-#73][ClientListenerNioListener] Failed to process client request [req=o.a.i.i.processors.platform.client.tx.ClientTxEndRequest@63f02b7f]
class org.apache.ignite.internal.processors.platform.client.IgniteClientException: Cache transaction timed out: GridNearTxLocal[xid=a4633245b71-00000000-0e58-8ca9-0000-000000000001, xidVersion=GridCacheVersion [topVer=240684201, order=1629204198986, nodeOrder=1], nearXidVersion=GridCacheVersion [topVer=240684201, order=1629204198986, nodeOrder=1], concurrency=OPTIMISTIC, isolation=SERIALIZABLE, state=ROLLED_BACK, invalidate=false, rollbackOnly=true, nodeId=73114d40-c975-4410-9fc1-910e72f45c16, timeout=1000, startTime=1629204220475, duration=5014, label=null]
        at org.apache.ignite.internal.processors.platform.client.tx.ClientTxEndRequest.process(ClientTxEndRequest.java:72)
        at org.apache.ignite.internal.processors.platform.client.ClientRequestHandler.handle(ClientRequestHandler.java:99)
        at org.apache.ignite.internal.processors.odbc.ClientListenerNioListener.onMessage(ClientListenerNioListener.java:202)
        at org.apache.ignite.internal.processors.odbc.ClientListenerNioListener.onMessage(ClientListenerNioListener.java:56)
        at org.apache.ignite.internal.util.nio.GridNioFilterChain$TailFilter.onMessageReceived(GridNioFilterChain.java:279)
        at org.apache.ignite.internal.util.nio.GridNioFilterAdapter.proceedMessageReceived(GridNioFilterAdapter.java:109)
        at org.apache.ignite.internal.util.nio.GridNioAsyncNotifyFilter$3.body(GridNioAsyncNotifyFilter.java:97)
        at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at org.apache.ignite.internal.util.worker.GridWorkerPool$1.run(GridWorkerPool.java:70)
        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
        at java.base/java.lang.Thread.run(Thread.java:829)
Caused by: class org.apache.ignite.internal.transactions.IgniteTxTimeoutCheckedException: Cache transaction timed out: GridNearTxLocal[xid=a4633245b71-00000000-0e58-8ca9-0000-000000000001, xidVersion=GridCacheVersion [topVer=240684201, order=1629204198986, nodeOrder=1], nearXidVersion=GridCacheVersion [topVer=240684201, order=1629204198986, nodeOrder=1], concurrency=OPTIMISTIC, isolation=SERIALIZABLE, state=ROLLED_BACK, invalidate=false, rollbackOnly=true, nodeId=73114d40-c975-4410-9fc1-910e72f45c16, timeout=1000, startTime=1629204220475, duration=5014, label=null]
        at org.apache.ignite.internal.processors.cache.transactions.IgniteTxLocalAdapter.checkValid(IgniteTxLocalAdapter.java:1389)
        at org.apache.ignite.internal.processors.cache.distributed.near.GridNearTxLocal.resume(GridNearTxLocal.java:3698)
        at org.apache.ignite.internal.processors.cache.distributed.near.GridNearTxLocal.resume(GridNearTxLocal.java:3683)
        at org.apache.ignite.internal.processors.platform.client.tx.ClientTxContext.acquire(ClientTxContext.java:58)
        at org.apache.ignite.internal.processors.platform.client.tx.ClientTxEndRequest.process(ClientTxEndRequest.java:62)
        ... 11 more

Such messages should not be in the server node logs.

To reproduce the case, you need to start the server node, connect to it with a thin client and from the thin client throw an IgniteTxTimeoutCheckedException or IgniteTxOptimisticCheckedException

Issue Links

• relates to: IGNITE-15346 Iterator of the ClientCacheQueryCursor can be closed during serialization.