You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Luchnikov Alexander (Jira)" <ji...@apache.org> on 2021/08/17 13:20:00 UTC

[jira] [Updated] (IGNITE-15326) IgniteClientException in server node

     [ https://issues.apache.org/jira/browse/IGNITE-15326?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Luchnikov Alexander updated IGNITE-15326:
-----------------------------------------
    Description: 
In cases of exception on a thin client like

{code:java}
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
{code}


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

{code:java}
[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

{code}

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

  was:
In cases of exception on a thin client like

{code:java}
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
{code}


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

{code:java}
[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

{code}

Such messages should not be in the server node logs.

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


> IgniteClientException in server node
> ------------------------------------
>
>                 Key: IGNITE-15326
>                 URL: https://issues.apache.org/jira/browse/IGNITE-15326
>             Project: Ignite
>          Issue Type: Task
>            Reporter: Luchnikov Alexander
>            Priority: Minor
>
> In cases of exception on a thin client like
> {code:java}
> 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
> {code}
> In the log of the server node, we have the ERROR message level
> {code:java}
> [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
> {code}
> 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



--
This message was sent by Atlassian Jira
(v8.3.4#803005)