2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

classic Classic list List threaded Threaded
11 messages Options
VeenaMithare VeenaMithare
Reply | Threaded
Open this post in threaded view
|

2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

Hi Team,

First of all, thank you for the SYS schema that is now available to view via
Dbeaver. Looks interesting.

The issue I am facing is that I am unable to query any of my tables in the
public schema when I switch over to 2.8.0. These tables currently do not
have any data in it. I get this message in the server logs :
2020-03-11 11:17:27,070 [grid-timeout-worker-#71] WARN
org.apache.ignite.internal.processors.odbc.ClientListenerNioListener [] -
Unable to perform handshake within timeout [timeout=60000,
remoteAddr=/127.0.0.1:49640]

Please note the default clientconfiguration handshaketimeout is 10 seconds.
I tried to increase it to 60 seconds, but it has not helped.

Can someone guide me if I need to do any configurations etc. to get past
this or if this is a bug ?

regards,
Veena.



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
ilya.kasnacheev ilya.kasnacheev
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

Hello!

Do you have any steps to reproduce? Are you sure you are using latest version of JDBC driver with DBeaver?

Regards,
--
Ilya Kasnacheev


ср, 11 мар. 2020 г. в 14:26, VeenaMithare <[hidden email]>:
Hi Team,

First of all, thank you for the SYS schema that is now available to view via
Dbeaver. Looks interesting.

The issue I am facing is that I am unable to query any of my tables in the
public schema when I switch over to 2.8.0. These tables currently do not
have any data in it. I get this message in the server logs :
2020-03-11 11:17:27,070 [grid-timeout-worker-#71] WARN
org.apache.ignite.internal.processors.odbc.ClientListenerNioListener [] -
Unable to perform handshake within timeout [timeout=60000,
remoteAddr=/127.0.0.1:49640]

Please note the default clientconfiguration handshaketimeout is 10 seconds.
I tried to increase it to 60 seconds, but it has not helped.

Can someone guide me if I need to do any configurations etc. to get past
this or if this is a bug ?

regards,
Veena.



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
VeenaMithare VeenaMithare
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

Hi ,

Yes, I have put 2.8.0 ignite core jar in the path for the DBeaver to pickup
the latest jdbc jars.

Steps to reproduce :
1. Create a table on dbeaver :  CREATE TABLE TEST (
        USERNAME VARCHAR,
        APPLICATIONNAME VARCHAR,
        MACHINENAME VARCHAR,
        PRIMARY KEY ( USERNAME)
)
 

2. Try and do SELECT * FROM PUBLIC.TEST

The query runs for ever .

regards,
Veena.



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
ilya.kasnacheev ilya.kasnacheev
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

Hello!

I have just tried it with DBeaver 4.2.2 and it runs OK.

Consider also adding ignite indexing jar to class path.

Regards,
--
Ilya Kasnacheev


ср, 11 мар. 2020 г. в 18:39, VeenaMithare <[hidden email]>:
Hi ,

Yes, I have put 2.8.0 ignite core jar in the path for the DBeaver to pickup
the latest jdbc jars.

Steps to reproduce :
1. Create a table on dbeaver :  CREATE TABLE TEST (
        USERNAME VARCHAR,
        APPLICATIONNAME VARCHAR,
        MACHINENAME VARCHAR,
        PRIMARY KEY ( USERNAME)
)


2. Try and do SELECT * FROM PUBLIC.TEST

The query runs for ever .

regards,
Veena.



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
VeenaMithare VeenaMithare
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

index.jar is already in classpath of server. I added it on the dbeaver
classpath ( Not sure, I dont think it is needed. )
I am on DBeaver 6.1.3.

Here is what I do :

1. Clear my data directory.
2. Start the 3 servers with 2.8.0
3. Activate the cluster
4. Add the table via dbeaver : CREATE TABLE TEST (
        USERNAME VARCHAR,
        APPLICATIONNAME VARCHAR,
        MACHINENAME VARCHAR,
        PRIMARY KEY ( USERNAME)
)
5. Query the table. This hangs for me.
Here are the logs of the three servers :
Server1 :
------------------------------------------------------------------------------------------
2020-03-11 16:37:10,548 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Started
cache [name=SQL_PUBLIC_TEST, id=-2066189417, dataRegionName=Default_Region,
mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false]
2020-03-11 16:37:10,555 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
2020-03-11 16:37:10,569 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partitions release latch: ClientLatch
[coordinator=TcpDiscoveryNode [id=c04b3dfd-3ef4-40a2-9d76-68678f95618d,
consistentId=b9ec6d51-3cae-4607-b0f5-a45da0e9d3a5, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=1,
intOrder=1, lastExchangeTime=1583944444737, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], ackSent=true,
super=CompletableLatch [id=CompletableLatchUid [id=exchange,
topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]]]
2020-03-11 16:37:10,569 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=LOCAL]
2020-03-11 16:37:10,608 [exchange-worker-#105] INFO
org.apache.ignite.internal.exchange.time [] - Finished exchange init
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], crd=false]
2020-03-11 16:37:10,645 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Received full message, will finish exchange
[node=c04b3dfd-3ef4-40a2-9d76-68678f95618d, resVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,656 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finish exchange future [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
err=null, rebalanced=false, wasRebalanced=false]
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Finish
proxy initialization, cacheName=SQL_PUBLIC_TEST,
localNodeId=6d77796a-c86f-4281-ad32-6cd70ffd7eb0
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Completed partition exchange
[localNode=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
consistentId=ae4a00d7-c92e-4829-a492-8d9a7757a3c9, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=2,
intOrder=2, lastExchangeTime=1583944630556, loc=true,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange timings [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Update caches registry" (5 ms), stage="Start
caches" (120 ms), stage="Affinity initialization on cache group start" (5
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (14 ms), stage="Wait
partitions release" (0 ms), stage="Restore partition states" (25 ms),
stage="After states restored callback" (9 ms), stage="Waiting for Full
message" (40 ms), stage="Affinity recalculation" (0 ms), stage="Full map
updating" (11 ms), stage="Exchange done" (5 ms), stage="Total time" (234
ms)]
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_TEST]" (5 ms) (parent=Affinity initialization on cache group
start), stage="Restore partition states [grp=SQL_PUBLIC_TEST]" (24 ms)
(parent=Restore partition states), stage="Restore partition states
[grp=transactionIdSubjectMapCache]" (0 ms) (parent=Restore partition
states), stage="Restore partition states [grp=ignite-sys-cache]" (0 ms)
(parent=Restore partition states)]
2020-03-11 16:37:10,665 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager
[] - Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=5, minorTopVer=20], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0]
2020-03-11 16:37:19,989 [db-checkpoint-thread-#120] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint started [checkpointId=cffe4e6c-364c-4f16-9d08-1aba1ff6b1e5,
startPtr=FileWALPointer [idx=0, fileOff=1245367, len=242487],
checkpointBeforeLockTime=37ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=15ms, checkpointLockHoldTime=22ms,
walCpRecordFsyncDuration=5ms, writeCheckpointEntryDuration=4ms,
splitAndSortCpPagesDuration=0ms,  pages=155, reason='timeout']
2020-03-11 16:37:20,027 [db-checkpoint-thread-#120] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint finished [cpId=cffe4e6c-364c-4f16-9d08-1aba1ff6b1e5,
pages=155, markPos=FileWALPointer [idx=0, fileOff=1245367, len=242487],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=32ms,
pagesWrite=3ms, fsync=34ms, total=106ms]
2020-03-11 16:37:25,101 [grid-nio-worker-tcp-comm-2-#74] INFO
org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi [] - Established
outgoing communication connection [locAddr=/127.0.0.1:60349,
rmtAddr=/127.0.0.1:47102]
-------------------------------------------------------------------------------------
Server 2 :
020-03-11 16:37:10,424 [tcp-disco-msg-worker-[6d77796a 0:0:0:0:0:0:0:1:47500
crd]-#2] DEBUG
com.companyname.project.module.common.plugin.discoveryspi.CustomTcpDiscoverySpi
[] - Processing message [cls=TcpDiscoveryDiscardMessage,
id=82a027ac071-c04b3dfd-3ef4-40a2-9d76-68678f95618d]
2020-03-11 16:37:10,564 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Started
cache [name=SQL_PUBLIC_TEST, id=-2066189417, dataRegionName=Default_Region,
mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false]
2020-03-11 16:37:10,568 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
2020-03-11 16:37:10,568 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]]]
2020-03-11 16:37:10,568 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=LOCAL]
2020-03-11 16:37:10,604 [exchange-worker-#105] INFO
org.apache.ignite.internal.exchange.time [] - Finished exchange init
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], crd=true]
2020-03-11 16:37:10,612 [sys-#207] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Coordinator received single message [ver=AffinityTopologyVersion
[topVer=5, minorTopVer=20], node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
remainingNodes=1, allReceived=false]
2020-03-11 16:37:10,622 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Coordinator received single message [ver=AffinityTopologyVersion
[topVer=5, minorTopVer=20], node=a6a987ef-89a2-47bd-bc87-fc497a3f285d,
allReceived=true]
2020-03-11 16:37:10,622 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,640 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finish exchange future [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
err=null, rebalanced=false, wasRebalanced=false]
2020-03-11 16:37:10,650 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Finish
proxy initialization, cacheName=SQL_PUBLIC_TEST,
localNodeId=c04b3dfd-3ef4-40a2-9d76-68678f95618d
2020-03-11 16:37:10,650 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Completed partition exchange
[localNode=c04b3dfd-3ef4-40a2-9d76-68678f95618d,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
consistentId=ae4a00d7-c92e-4829-a492-8d9a7757a3c9, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=2,
intOrder=2, lastExchangeTime=1583944444650, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,650 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange timings [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Update caches registry" (4 ms), stage="Start
caches" (138 ms), stage="Affinity initialization on cache group start" (2
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (0 ms), stage="Wait
partitions release" (0 ms), stage="Restore partition states" (29 ms),
stage="After states restored callback" (5 ms), stage="Waiting for all single
messages" (18 ms), stage="Affinity recalculation (crd)" (0 ms),
stage="Collect update counters and create affinity messages" (0 ms),
stage="Validate partitions states" (6 ms), stage="Assign partitions states"
(2 ms), stage="Apply update counters" (1 ms), stage="Full message preparing"
(7 ms), stage="Full message sending" (0 ms), stage="Exchange done" (9 ms),
stage="Total time" (221 ms), Discovery lag=3 ms, Latest started node
id=a6a987ef-89a2-47bd-bc87-fc497a3f285d]
2020-03-11 16:37:10,655 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_TEST]" (2 ms) (parent=Affinity initialization on cache group
start), stage="Restore partition states [grp=SQL_PUBLIC_TEST]" (29 ms)
(parent=Restore partition states), stage="Restore partition states
[grp=transactionIdSubjectMapCache]" (0 ms) (parent=Restore partition
states), stage="Restore partition states [grp=ignite-sys-cache]" (0 ms)
(parent=Restore partition states)]
2020-03-11 16:37:10,665 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager
[] - Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=5, minorTopVer=20], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0]
2020-03-11 16:37:19,986 [db-checkpoint-thread-#116] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint started [checkpointId=33ee4248-56aa-4c4f-8ba8-216350394aff,
startPtr=FileWALPointer [idx=0, fileOff=1257755, len=245584],
checkpointBeforeLockTime=37ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=21ms, checkpointLockHoldTime=28ms,
walCpRecordFsyncDuration=8ms, writeCheckpointEntryDuration=5ms,
splitAndSortCpPagesDuration=0ms,  pages=155, reason='timeout']
2020-03-11 16:37:20,026 [db-checkpoint-thread-#116] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint finished [cpId=33ee4248-56aa-4c4f-8ba8-216350394aff,
pages=155, markPos=FileWALPointer [idx=0, fileOff=1257755, len=245584],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=41ms,
pagesWrite=4ms, fsync=35ms, total=117ms]
2020-03-11 16:40:19,991 [db-checkpoint-thread-#116] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Skipping checkpoint (no pages were modified)
[checkpointBeforeLockTime=18ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=19ms, checkpointLockHoldTime=24ms,
reason='timeout']
-------------------------------------------------------------------------------------------------------
Server 3 :
2020-03-11 16:37:10,558 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Started
cache [name=SQL_PUBLIC_TEST, id=-2066189417, dataRegionName=Default_Region,
mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false]
2020-03-11 16:37:10,562 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
2020-03-11 16:37:10,573 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partitions release latch: ClientLatch
[coordinator=TcpDiscoveryNode [id=c04b3dfd-3ef4-40a2-9d76-68678f95618d,
consistentId=b9ec6d51-3cae-4607-b0f5-a45da0e9d3a5, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=1,
intOrder=1, lastExchangeTime=1583944472211, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], ackSent=true,
super=CompletableLatch [id=CompletableLatchUid [id=exchange,
topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]]]
2020-03-11 16:37:10,573 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=LOCAL]
2020-03-11 16:37:10,618 [exchange-worker-#105] INFO
org.apache.ignite.internal.exchange.time [] - Finished exchange init
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], crd=false]
2020-03-11 16:37:10,645 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Received full message, will finish exchange
[node=c04b3dfd-3ef4-40a2-9d76-68678f95618d, resVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,660 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finish exchange future [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
err=null, rebalanced=false, wasRebalanced=false]
2020-03-11 16:37:10,668 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Finish
proxy initialization, cacheName=SQL_PUBLIC_TEST,
localNodeId=a6a987ef-89a2-47bd-bc87-fc497a3f285d
2020-03-11 16:37:10,669 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Completed partition exchange
[localNode=a6a987ef-89a2-47bd-bc87-fc497a3f285d,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
consistentId=ae4a00d7-c92e-4829-a492-8d9a7757a3c9, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=2,
intOrder=2, lastExchangeTime=1583944472211, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,669 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange timings [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Update caches registry" (5 ms), stage="Start
caches" (128 ms), stage="Affinity initialization on cache group start" (2
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (10 ms), stage="Wait
partitions release" (0 ms), stage="Restore partition states" (28 ms),
stage="After states restored callback" (12 ms), stage="Waiting for Full
message" (30 ms), stage="Affinity recalculation" (0 ms), stage="Full map
updating" (14 ms), stage="Exchange done" (8 ms), stage="Total time" (237
ms)]
2020-03-11 16:37:10,669 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_TEST]" (2 ms) (parent=Affinity initialization on cache group
start), stage="Restore partition states [grp=SQL_PUBLIC_TEST]" (27 ms)
(parent=Restore partition states), stage="Restore partition states
[grp=transactionIdSubjectMapCache]" (0 ms) (parent=Restore partition
states), stage="Restore partition states [grp=ignite-sys-cache]" (0 ms)
(parent=Restore partition states)]
2020-03-11 16:37:10,674 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager
[] - Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=5, minorTopVer=20], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0]
2020-03-11 16:37:25,097 [grid-nio-worker-tcp-comm-1-#73] INFO
org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi [] - Accepted
incoming communication connection [locAddr=/127.0.0.1:47102,
rmtAddr=/127.0.0.1:60349]
2020-03-11 16:37:32,468 [db-checkpoint-thread-#113] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint started [checkpointId=3fd04e70-9137-498c-9d1c-1495e9561e6a,
startPtr=FileWALPointer [idx=0, fileOff=1463055, len=237984],
checkpointBeforeLockTime=36ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=16ms, checkpointLockHoldTime=22ms,
walCpRecordFsyncDuration=7ms, writeCheckpointEntryDuration=6ms,
splitAndSortCpPagesDuration=0ms,  pages=155, reason='timeout']
2020-03-11 16:37:32,497 [db-checkpoint-thread-#113] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint finished [cpId=3fd04e70-9137-498c-9d1c-1495e9561e6a,
pages=155, markPos=FileWALPointer [idx=0, fileOff=1463055, len=237984],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=36ms,
pagesWrite=5ms, fsync=24ms, total=101ms]
2020-03-11 16:40:32,470 [db-checkpoint-thread-#113] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Skipping checkpoint (no pages were modified)
[checkpointBeforeLockTime=18ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=16ms, checkpointLockHoldTime=21ms,
reason='timeout']




--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
李玉珏@163 李玉珏@163
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

In reply to this post by VeenaMithare
in my environment, no problem.

在 2020/3/11 下午11:39, VeenaMithare 写道:

> Hi ,
>
> Yes, I have put 2.8.0 ignite core jar in the path for the DBeaver to pickup
> the latest jdbc jars.
>
> Steps to reproduce :
> 1. Create a table on dbeaver :  CREATE TABLE TEST (
> USERNAME VARCHAR,
> APPLICATIONNAME VARCHAR,
> MACHINENAME VARCHAR,
> PRIMARY KEY ( USERNAME)
> )
>  
>
> 2. Try and do SELECT * FROM PUBLIC.TEST
>
> The query runs for ever .
>
> regards,
> Veena.
>
>
>
> --
> Sent from: http://apache-ignite-users.70518.x6.nabble.com/

VeenaMithare VeenaMithare
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

This fails when the security plugin is enabled. When I remove the security
plugin, the query goes through.

With the security plugin enabled, it hangs at :
GridReduceQueryExecutor - on awaitallReplies method. Attached screenshot of
thread , when it hangs.
Steps I take :
1. Start my server with 2.8.0 and the security plugin enabled.

Also the logs are :

============================================================================================

Server 1 :
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: New next node [newNext=TcpDiscoveryNode
[id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47501, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=0,
intOrder=2, lastExchangeTime=1584018173124, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery accepted incoming connection [rmtAddr=/0:0:0:0:0:0:0:1,
rmtPort=63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery spawning a new thread for connection
[rmtAddr=/0:0:0:0:0:0:0:1, rmtPort=63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started serving remote node connection
[rmtAddr=/0:0:0:0:0:0:0:1:63143, rmtPort=63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Initialized connection with remote server node
[nodeId=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
rmtAddr=/0:0:0:0:0:0:0:1:63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Received activate request with BaselineTopology[id=0]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started state transition: true
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Received state change finish message: true
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Added new node to topology: TcpDiscoveryNode
[id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47501, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=2,
intOrder=2, lastExchangeTime=1584018173124, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Topology snapshot [ver=2, locNode=96b07658, servers=2, clients=0,
state=ACTIVE, CPUs=32, offheap=13.0GB, heap=14.0GB]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO:   ^-- Baseline [id=0, size=2, online=2, offline=0]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], crd=true, evt=NODE_JOINED,
evtNode=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a, customEvt=null,
allowMerge=true, exchangeFreeSwitch=false]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0]]]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], crd=true]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Accepted incoming communication connection [locAddr=/127.0.0.1:47100,
rmtAddr=/127.0.0.1:63144]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received single message [ver=AffinityTopologyVersion
[topVer=2, minorTopVer=0], node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
allReceived=true]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received all messages, try merge
[ver=AffinityTopologyVersion [topVer=2, minorTopVer=0]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=0]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=0],
err=null, rebalanced=false, wasRebalanced=true]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=96b07658-525d-4230-92bc-4bb756c633c4,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=0], evt=NODE_JOINED, evtNode=TcpDiscoveryNode
[id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47501, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=2,
intOrder=2, lastExchangeTime=1584018173124, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=0],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Determine exchange type" (2 ms),
stage="Preloading notification" (0 ms), stage="WAL history reservation" (0
ms), stage="Wait partitions release" (12 ms), stage="Wait partitions release
latch" (7 ms), stage="Wait partitions release" (0 ms), stage="After states
restored callback" (0 ms), stage="Waiting for all single messages" (298 ms),
stage="Exchanges merge" (0 ms), stage="Affinity recalculation (crd)" (14
ms), stage="Collect update counters and create affinity messages" (2 ms),
stage="Validate partitions states" (0 ms), stage="Assign partitions states"
(0 ms), stage="Apply update counters" (0 ms), stage="Full message preparing"
(3 ms), stage="Full message sending" (6 ms), stage="State finish message
sending" (0 ms), stage="Exchange done" (1 ms), stage="Total time" (345 ms),
Discovery lag=53 ms, Latest started node
id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], stage="Affinity initialization (node join)
[grp=ignite-sys-cache, crd=true]" (9 ms) (parent=Affinity recalculation
(crd))]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=0], force=false, evt=NODE_JOINED,
node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished supplying rebalancing [grp=ignite-sys-cache,
demander=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], topic=0]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], crd=true, evt=DISCOVERY_CUSTOM_EVT,
evtNode=96b07658-525d-4230-92bc-4bb756c633c4,
customEvt=CacheAffinityChangeMessage
[id=2c7d6dec071-87d6067d-1b6b-44b8-a596-18ff29dca0f4,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], allowMerge=false,
exchangeFreeSwitch=false]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1]]]]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], crd=true]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received single message [ver=AffinityTopologyVersion
[topVer=2, minorTopVer=1], node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
allReceived=true]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=1]]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=1],
err=null, rebalanced=true, wasRebalanced=false]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=96b07658-525d-4230-92bc-4bb756c633c4,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=1], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018175368, loc=true,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1]]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=1],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Determine exchange type" (2 ms),
stage="Preloading notification" (0 ms), stage="WAL history reservation" (0
ms), stage="Wait partitions release" (0 ms), stage="Wait partitions release
latch" (46 ms), stage="Wait partitions release" (0 ms), stage="After states
restored callback" (0 ms), stage="Waiting for all single messages" (11 ms),
stage="Affinity recalculation (crd)" (0 ms), stage="Collect update counters
and create affinity messages" (0 ms), stage="Validate partitions states" (0
ms), stage="Apply update counters" (0 ms), stage="Full message preparing" (2
ms), stage="Full message sending" (0 ms), stage="Exchange done" (1 ms),
stage="Total time" (62 ms), Discovery lag=25 ms, Latest started node
id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=1], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=96b07658-525d-4230-92bc-4bb756c633c4]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], stage="Affinity change by custom message
[grp=ignite-sys-cache]" (2 ms) (parent=Determine exchange type)]
Mar 12, 2020 1:03:31 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 3108 milliseconds.
2020-03-12 13:03:31,644 [client-connector-#140] DEBUG
com.companynamemarkets.prophet.configstore.common.plugin.security.SecurityLDAPProcessorPlugin
[] - IGNITE SECURITY : SecurityLDAPProcessorPlugin : Entered the
authenticate block 1921da96-1171-4a66-abbb-721958381659 Login vmithare
2020-03-12 13:03:31,786 [client-connector-#142] DEBUG
com.companynamemarkets.prophet.configstore.common.plugin.security.SecurityLDAPProcessorPlugin
[] - IGNITE SECURITY : SecurityLDAPProcessorPlugin : Entered the
authenticate block 79d90536-1589-46b1-b609-7e6692356787 Login vmithare
Mar 12, 2020 1:03:38 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=96b07658, uptime=00:01:00.010]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.17%, avg=0.14%, GC=0%]
    ^-- PageMemory [pages=200]
    ^-- Heap [used=178MB, free=97.53%, comm=732MB]
    ^-- Off-heap [used=0MB, free=99.99%, comm=80MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=0MB, free=100%, comm=0MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=27, qSize=0]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=true, evt=DISCOVERY_CUSTOM_EVT,
evtNode=96b07658-525d-4230-92bc-4bb756c633c4,
customEvt=DynamicCacheChangeBatch
[id=679d6dec071-87d6067d-1b6b-44b8-a596-18ff29dca0f4, reqs=ArrayList
[DynamicCacheChangeRequest [cacheName=SQL_PUBLIC_DEF, hasCfg=true,
nodeId=96b07658-525d-4230-92bc-4bb756c633c4, clientStartOnly=false,
stop=false, destroy=false, disabledAfterStartfalse]],
exchangeActions=ExchangeActions [startCaches=[SQL_PUBLIC_DEF],
stopCaches=null, startGrps=[SQL_PUBLIC_DEF], stopGrps=[], resetParts=null,
stateChangeRequest=null], startCaches=false], allowMerge=false,
exchangeFreeSwitch=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started cache [name=SQL_PUBLIC_DEF, id=-897950656,
dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=0,
mvcc=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]]]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received single message [ver=AffinityTopologyVersion
[topVer=2, minorTopVer=2], node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
allReceived=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
err=null, rebalanced=true, wasRebalanced=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish proxy initialization, cacheName=SQL_PUBLIC_DEF,
localNodeId=96b07658-525d-4230-92bc-4bb756c633c4
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=96b07658-525d-4230-92bc-4bb756c633c4,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018221884, loc=true,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (3 ms), stage="Update caches registry" (3 ms), stage="Start
caches" (78 ms), stage="Affinity initialization on cache group start" (7
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (1 ms), stage="Wait partitions release latch" (2 ms), stage="Wait
partitions release" (0 ms), stage="After states restored callback" (39 ms),
stage="Waiting for all single messages" (0 ms), stage="Affinity
recalculation (crd)" (0 ms), stage="Collect update counters and create
affinity messages" (0 ms), stage="Validate partitions states" (0 ms),
stage="Assign partitions states" (0 ms), stage="Apply update counters" (0
ms), stage="Full message preparing" (2 ms), stage="Full message sending" (0
ms), stage="Exchange done" (2 ms), stage="Total time" (137 ms), Discovery
lag=17 ms, Latest started node id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_DEF]" (7 ms) (parent=Affinity initialization on cache group
start)]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=2], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=96b07658-525d-4230-92bc-4bb756c633c4]
Mar 12, 2020 1:04:12 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 3170 milliseconds.
Mar 12, 2020 1:04:13 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 565 milliseconds.
Mar 12, 2020 1:04:13 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 534 milliseconds.
Mar 12, 2020 1:04:14 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 890 milliseconds.
Mar 12, 2020 1:04:15 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 623 milliseconds.
Mar 12, 2020 1:04:16 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 600 milliseconds.
Mar 12, 2020 1:04:17 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 969 milliseconds.
Mar 12, 2020 1:04:18 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 755 milliseconds.
Mar 12, 2020 1:04:19 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 601 milliseconds.
Mar 12, 2020 1:04:21 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 597 milliseconds.
Mar 12, 2020 1:04:23 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 884 milliseconds.
Mar 12, 2020 1:04:23 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 576 milliseconds.
Mar 12, 2020 1:04:24 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 565 milliseconds.
Mar 12, 2020 1:04:27 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 792 milliseconds.
Mar 12, 2020 1:04:29 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 1046 milliseconds.
Mar 12, 2020 1:04:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Local node seems to be disconnected from topology (failure detection
timeout is reached) [failureDetectionTimeout=10000, connCheckInterval=500]
Mar 12, 2020 1:04:41 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 12185 milliseconds.
Mar 12, 2020 1:04:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery accepted incoming connection [rmtAddr=/127.0.0.1,
rmtPort=63159]
Mar 12, 2020 1:04:42 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-11,
threadName=grid-nio-worker-tcp-comm-11-#83, blockedFor=12s]
Mar 12, 2020 1:04:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery spawning a new thread for connection
[rmtAddr=/127.0.0.1, rmtPort=63159]
Mar 12, 2020 1:04:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished serving remote node connection
[rmtAddr=/0:0:0:0:0:0:0:1:63143, rmtPort=63143
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-11-#83", id=112,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 1208 milliseconds.
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started serving remote node connection [rmtAddr=/127.0.0.1:63159,
rmtPort=63159]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=96b07658, uptime=00:02:04.973]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.13%, avg=0.12%, GC=0%]
    ^-- PageMemory [pages=1264]
    ^-- Heap [used=214MB, free=97.03%, comm=732MB]
    ^-- Off-heap [used=4MB, free=99.93%, comm=336MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=4MB, free=99.94%, comm=256MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=5, qSize=0]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Initialized connection with remote server node
[nodeId=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a, rmtAddr=/127.0.0.1:63159]
Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-11,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-11, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283558, 2020-03-12
13:04:43.558)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=partition-exchanger,
threadName=exchange-worker-#103, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="exchange-worker-#103", id=162, state=TIMED_WAITING,
blockCnt=0, waitCnt=32]
    Lock
[object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7eb5c0f3,
ownerName=null, ownerId=-1]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=partition-exchanger,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=partition-exchanger, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283564, 2020-03-12
13:04:43.564)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-10,
threadName=grid-nio-worker-tcp-comm-10-#82, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-10-#82", id=111,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-10,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-10, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283565, 2020-03-12
13:04:43.565)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-14,
threadName=grid-nio-worker-tcp-comm-14-#86, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-14-#86", id=115,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-14,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-14, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283566, 2020-03-12
13:04:43.566)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=nio-acceptor-tcp-comm,
threadName=nio-acceptor-tcp-comm-#88, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="nio-acceptor-tcp-comm-#88", id=120, state=RUNNABLE,
blockCnt=1, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=nio-acceptor-tcp-comm,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=nio-acceptor-tcp-comm, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283566, 2020-03-12
13:04:43.566)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-9,
threadName=grid-nio-worker-tcp-comm-9-#81, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-9-#81", id=110,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-9,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-9, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283567, 2020-03-12
13:04:43.567)



=====================================================================
Server 2 :
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=false, evt=DISCOVERY_CUSTOM_EVT,
evtNode=96b07658-525d-4230-92bc-4bb756c633c4,
customEvt=DynamicCacheChangeBatch
[id=679d6dec071-87d6067d-1b6b-44b8-a596-18ff29dca0f4, reqs=ArrayList
[DynamicCacheChangeRequest [cacheName=SQL_PUBLIC_DEF, hasCfg=true,
nodeId=96b07658-525d-4230-92bc-4bb756c633c4, clientStartOnly=false,
stop=false, destroy=false, disabledAfterStartfalse]],
exchangeActions=ExchangeActions [startCaches=[SQL_PUBLIC_DEF],
stopCaches=null, startGrps=[SQL_PUBLIC_DEF], stopGrps=[], resetParts=null,
stateChangeRequest=null], startCaches=false], allowMerge=false,
exchangeFreeSwitch=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started cache [name=SQL_PUBLIC_DEF, id=-897950656,
dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=0,
mvcc=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ClientLatch
[coordinator=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018173220, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], ackSent=true,
super=CompletableLatch [id=CompletableLatchUid [id=exchange,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]]]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=false]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Received full message, will finish exchange
[node=96b07658-525d-4230-92bc-4bb756c633c4, resVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
err=null, rebalanced=true, wasRebalanced=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish proxy initialization, cacheName=SQL_PUBLIC_DEF,
localNodeId=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018173220, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (3 ms), stage="Update caches registry" (3 ms), stage="Start
caches" (60 ms), stage="Affinity initialization on cache group start" (8
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (4 ms), stage="Wait
partitions release" (0 ms), stage="After states restored callback" (32 ms),
stage="Waiting for Full message" (13 ms), stage="Affinity recalculation" (0
ms), stage="Full map updating" (2 ms), stage="Exchange done" (2 ms),
stage="Total time" (127 ms)]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_DEF]" (8 ms) (parent=Affinity initialization on cache group
start)]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=2], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=96b07658-525d-4230-92bc-4bb756c633c4]
Mar 12, 2020 1:03:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=c003c5d9, uptime=00:01:00.007]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.07%, avg=0.12%, GC=0%]
    ^-- PageMemory [pages=1192]
    ^-- Heap [used=138MB, free=98.09%, comm=620MB]
    ^-- Off-heap [used=4MB, free=99.93%, comm=336MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=3MB, free=99.94%, comm=256MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=27, qSize=0]
Mar 12, 2020 1:04:40 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Timed out waiting for message delivery receipt (most probably, the
reason is in long GC pauses on remote node; consider tuning GC and
increasing 'ackTimeout' configuration property). Will retry to send message
with increased timeout [currentTimeout=10000,
rmtAddr=/0:0:0:0:0:0:0:1:47500, rmtPort=47500]
Mar 12, 2020 1:04:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=c003c5d9, uptime=00:02:00.027]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.03%, avg=0.09%, GC=0%]
    ^-- PageMemory [pages=1192]
    ^-- Heap [used=142MB, free=98.02%, comm=620MB]
    ^-- Off-heap [used=4MB, free=99.93%, comm=336MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=3MB, free=99.94%, comm=256MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=6, qSize=0]
Mar 12, 2020 1:05:10 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished serving remote node connection
[rmtAddr=/0:0:0:0:0:0:0:1:63142, rmtPort=63142

====================================================================

My Ignite config looks like :

<beans xmlns="http://www.springframework.org/schema/beans"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="
       http://www.springframework.org/schema/beans
       http://www.springframework.org/schema/beans/spring-beans.xsd">
   
    <bean id="grid.cfg"
class="org.apache.ignite.configuration.IgniteConfiguration">
        <property name="pluginConfigurations">
            <bean id="securityPlugin"
                 
class="com.xxx.xxx.xxx.xxx.plugin.security.SecurityPluginConfiguration">
                <constructor-arg value="xxx"/>
            </bean>
        </property>
        <property name="discoverySpi" ref="tcpDiscSpiSpecific"/>
        <property name="clientFailureDetectionTimeout" value="30000"/>

    </bean>
    <bean id="tcpPortConfig"
         
class="org.apache.ignite.spi.discovery.tcp.ipfinder.vm.TcpDiscoveryVmIpFinder">
        <property name="addresses">
            <list>
                <value>127.0.0.1:47500..47502</value>
            </list>
        </property>
    </bean>
    <bean id="nodeSecurityCredential"
class="org.apache.ignite.plugin.security.SecurityCredentials">
        <constructor-arg value="xxx"/>
        <constructor-arg value="xxx"/>
    </bean>
    <bean id="tcpDiscSpiSpecific"
         
class="com.xxx.xxx.xxx.common.plugin.discoveryspi.CustomTcpDiscoverySpi"
          parent="tcpDiscSpi">

    </bean>
    <bean id="tcpDiscSpi"
class="com.xxx.xxx.xxx.common.plugin.discoveryspi.CustomTcpDiscoverySpi"
          abstract="true">
        <constructor-arg ref="nodeSecurityCredential"/>
        <property name="ipFinder" ref="tcpPortConfig"/>
    </bean>
</beans>

<http://apache-ignite-users.70518.x6.nabble.com/file/t2757/queryhanging.png>



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/

ilya.kasnacheev ilya.kasnacheev
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

Hello!

I can see a lot of activity from your side, and ask you to participate in debugging and development.

Please file tickets about the issues that you encounter and propose fixes. I think you have as much expertise around writing security plugin as anybody else.

Regards,
--
Ilya Kasnacheev


чт, 12 мар. 2020 г. в 16:19, VeenaMithare <[hidden email]>:
This fails when the security plugin is enabled. When I remove the security
plugin, the query goes through.

With the security plugin enabled, it hangs at :
GridReduceQueryExecutor - on awaitallReplies method. Attached screenshot of
thread , when it hangs.
Steps I take :
1. Start my server with 2.8.0 and the security plugin enabled.

Also the logs are :

============================================================================================

Server 1 :
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: New next node [newNext=TcpDiscoveryNode
[id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47501, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=0,
intOrder=2, lastExchangeTime=1584018173124, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery accepted incoming connection [rmtAddr=/0:0:0:0:0:0:0:1,
rmtPort=63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery spawning a new thread for connection
[rmtAddr=/0:0:0:0:0:0:0:1, rmtPort=63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started serving remote node connection
[rmtAddr=/0:0:0:0:0:0:0:1:63143, rmtPort=63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Initialized connection with remote server node
[nodeId=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
rmtAddr=/0:0:0:0:0:0:0:1:63143]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Received activate request with BaselineTopology[id=0]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started state transition: true
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Received state change finish message: true
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Added new node to topology: TcpDiscoveryNode
[id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47501, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=2,
intOrder=2, lastExchangeTime=1584018173124, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Topology snapshot [ver=2, locNode=96b07658, servers=2, clients=0,
state=ACTIVE, CPUs=32, offheap=13.0GB, heap=14.0GB]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO:   ^-- Baseline [id=0, size=2, online=2, offline=0]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], crd=true, evt=NODE_JOINED,
evtNode=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a, customEvt=null,
allowMerge=true, exchangeFreeSwitch=false]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0]]]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], crd=true]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Accepted incoming communication connection [locAddr=/127.0.0.1:47100,
rmtAddr=/127.0.0.1:63144]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received single message [ver=AffinityTopologyVersion
[topVer=2, minorTopVer=0], node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
allReceived=true]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received all messages, try merge
[ver=AffinityTopologyVersion [topVer=2, minorTopVer=0]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=0]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=0],
err=null, rebalanced=false, wasRebalanced=true]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=96b07658-525d-4230-92bc-4bb756c633c4,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=0], evt=NODE_JOINED, evtNode=TcpDiscoveryNode
[id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47501, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=2,
intOrder=2, lastExchangeTime=1584018173124, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0]]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=0],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Determine exchange type" (2 ms),
stage="Preloading notification" (0 ms), stage="WAL history reservation" (0
ms), stage="Wait partitions release" (12 ms), stage="Wait partitions release
latch" (7 ms), stage="Wait partitions release" (0 ms), stage="After states
restored callback" (0 ms), stage="Waiting for all single messages" (298 ms),
stage="Exchanges merge" (0 ms), stage="Affinity recalculation (crd)" (14
ms), stage="Collect update counters and create affinity messages" (2 ms),
stage="Validate partitions states" (0 ms), stage="Assign partitions states"
(0 ms), stage="Apply update counters" (0 ms), stage="Full message preparing"
(3 ms), stage="Full message sending" (6 ms), stage="State finish message
sending" (0 ms), stage="Exchange done" (1 ms), stage="Total time" (345 ms),
Discovery lag=53 ms, Latest started node
id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=0], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], stage="Affinity initialization (node join)
[grp=ignite-sys-cache, crd=true]" (9 ms) (parent=Affinity recalculation
(crd))]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=0], force=false, evt=NODE_JOINED,
node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:02:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished supplying rebalancing [grp=ignite-sys-cache,
demander=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], topic=0]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], crd=true, evt=DISCOVERY_CUSTOM_EVT,
evtNode=96b07658-525d-4230-92bc-4bb756c633c4,
customEvt=CacheAffinityChangeMessage
[id=2c7d6dec071-87d6067d-1b6b-44b8-a596-18ff29dca0f4,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], allowMerge=false,
exchangeFreeSwitch=false]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1]]]]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], crd=true]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received single message [ver=AffinityTopologyVersion
[topVer=2, minorTopVer=1], node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
allReceived=true]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=1]]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=1],
err=null, rebalanced=true, wasRebalanced=false]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=96b07658-525d-4230-92bc-4bb756c633c4,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=1], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018175368, loc=true,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=1]]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=1],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Determine exchange type" (2 ms),
stage="Preloading notification" (0 ms), stage="WAL history reservation" (0
ms), stage="Wait partitions release" (0 ms), stage="Wait partitions release
latch" (46 ms), stage="Wait partitions release" (0 ms), stage="After states
restored callback" (0 ms), stage="Waiting for all single messages" (11 ms),
stage="Affinity recalculation (crd)" (0 ms), stage="Collect update counters
and create affinity messages" (0 ms), stage="Validate partitions states" (0
ms), stage="Apply update counters" (0 ms), stage="Full message preparing" (2
ms), stage="Full message sending" (0 ms), stage="Exchange done" (1 ms),
stage="Total time" (62 ms), Discovery lag=25 ms, Latest started node
id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=1], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=96b07658-525d-4230-92bc-4bb756c633c4]
Mar 12, 2020 1:02:55 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=1], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], stage="Affinity change by custom message
[grp=ignite-sys-cache]" (2 ms) (parent=Determine exchange type)]
Mar 12, 2020 1:03:31 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 3108 milliseconds.
2020-03-12 13:03:31,644 [client-connector-#140] DEBUG
com.companynamemarkets.prophet.configstore.common.plugin.security.SecurityLDAPProcessorPlugin
[] - IGNITE SECURITY : SecurityLDAPProcessorPlugin : Entered the
authenticate block 1921da96-1171-4a66-abbb-721958381659 Login vmithare
2020-03-12 13:03:31,786 [client-connector-#142] DEBUG
com.companynamemarkets.prophet.configstore.common.plugin.security.SecurityLDAPProcessorPlugin
[] - IGNITE SECURITY : SecurityLDAPProcessorPlugin : Entered the
authenticate block 79d90536-1589-46b1-b609-7e6692356787 Login vmithare
Mar 12, 2020 1:03:38 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=96b07658, uptime=00:01:00.010]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.17%, avg=0.14%, GC=0%]
    ^-- PageMemory [pages=200]
    ^-- Heap [used=178MB, free=97.53%, comm=732MB]
    ^-- Off-heap [used=0MB, free=99.99%, comm=80MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=0MB, free=100%, comm=0MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=27, qSize=0]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=true, evt=DISCOVERY_CUSTOM_EVT,
evtNode=96b07658-525d-4230-92bc-4bb756c633c4,
customEvt=DynamicCacheChangeBatch
[id=679d6dec071-87d6067d-1b6b-44b8-a596-18ff29dca0f4, reqs=ArrayList
[DynamicCacheChangeRequest [cacheName=SQL_PUBLIC_DEF, hasCfg=true,
nodeId=96b07658-525d-4230-92bc-4bb756c633c4, clientStartOnly=false,
stop=false, destroy=false, disabledAfterStartfalse]],
exchangeActions=ExchangeActions [startCaches=[SQL_PUBLIC_DEF],
stopCaches=null, startGrps=[SQL_PUBLIC_DEF], stopGrps=[], resetParts=null,
stateChangeRequest=null], startCaches=false], allowMerge=false,
exchangeFreeSwitch=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started cache [name=SQL_PUBLIC_DEF, id=-897950656,
dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=0,
mvcc=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]]]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Coordinator received single message [ver=AffinityTopologyVersion
[topVer=2, minorTopVer=2], node=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
allReceived=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
err=null, rebalanced=true, wasRebalanced=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish proxy initialization, cacheName=SQL_PUBLIC_DEF,
localNodeId=96b07658-525d-4230-92bc-4bb756c633c4
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=96b07658-525d-4230-92bc-4bb756c633c4,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018221884, loc=true,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (3 ms), stage="Update caches registry" (3 ms), stage="Start
caches" (78 ms), stage="Affinity initialization on cache group start" (7
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (1 ms), stage="Wait partitions release latch" (2 ms), stage="Wait
partitions release" (0 ms), stage="After states restored callback" (39 ms),
stage="Waiting for all single messages" (0 ms), stage="Affinity
recalculation (crd)" (0 ms), stage="Collect update counters and create
affinity messages" (0 ms), stage="Validate partitions states" (0 ms),
stage="Assign partitions states" (0 ms), stage="Apply update counters" (0
ms), stage="Full message preparing" (2 ms), stage="Full message sending" (0
ms), stage="Exchange done" (2 ms), stage="Total time" (137 ms), Discovery
lag=17 ms, Latest started node id=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_DEF]" (7 ms) (parent=Affinity initialization on cache group
start)]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=2], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=96b07658-525d-4230-92bc-4bb756c633c4]
Mar 12, 2020 1:04:12 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 3170 milliseconds.
Mar 12, 2020 1:04:13 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 565 milliseconds.
Mar 12, 2020 1:04:13 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 534 milliseconds.
Mar 12, 2020 1:04:14 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 890 milliseconds.
Mar 12, 2020 1:04:15 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 623 milliseconds.
Mar 12, 2020 1:04:16 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 600 milliseconds.
Mar 12, 2020 1:04:17 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 969 milliseconds.
Mar 12, 2020 1:04:18 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 755 milliseconds.
Mar 12, 2020 1:04:19 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 601 milliseconds.
Mar 12, 2020 1:04:21 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 597 milliseconds.
Mar 12, 2020 1:04:23 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 884 milliseconds.
Mar 12, 2020 1:04:23 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 576 milliseconds.
Mar 12, 2020 1:04:24 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 565 milliseconds.
Mar 12, 2020 1:04:27 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 792 milliseconds.
Mar 12, 2020 1:04:29 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 1046 milliseconds.
Mar 12, 2020 1:04:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Local node seems to be disconnected from topology (failure detection
timeout is reached) [failureDetectionTimeout=10000, connCheckInterval=500]
Mar 12, 2020 1:04:41 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 12185 milliseconds.
Mar 12, 2020 1:04:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery accepted incoming connection [rmtAddr=/127.0.0.1,
rmtPort=63159]
Mar 12, 2020 1:04:42 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-11,
threadName=grid-nio-worker-tcp-comm-11-#83, blockedFor=12s]
Mar 12, 2020 1:04:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: TCP discovery spawning a new thread for connection
[rmtAddr=/127.0.0.1, rmtPort=63159]
Mar 12, 2020 1:04:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished serving remote node connection
[rmtAddr=/0:0:0:0:0:0:0:1:63143, rmtPort=63143
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-11-#83", id=112,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Possible too long JVM pause: 1208 milliseconds.
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started serving remote node connection [rmtAddr=/127.0.0.1:63159,
rmtPort=63159]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=96b07658, uptime=00:02:04.973]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.13%, avg=0.12%, GC=0%]
    ^-- PageMemory [pages=1264]
    ^-- Heap [used=214MB, free=97.03%, comm=732MB]
    ^-- Off-heap [used=4MB, free=99.93%, comm=336MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=4MB, free=99.94%, comm=256MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=5, qSize=0]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Initialized connection with remote server node
[nodeId=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a, rmtAddr=/127.0.0.1:63159]
Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-11,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-11, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283558, 2020-03-12
13:04:43.558)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=partition-exchanger,
threadName=exchange-worker-#103, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="exchange-worker-#103", id=162, state=TIMED_WAITING,
blockCnt=0, waitCnt=32]
    Lock
[object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7eb5c0f3,
ownerName=null, ownerId=-1]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=partition-exchanger,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=partition-exchanger, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283564, 2020-03-12
13:04:43.564)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-10,
threadName=grid-nio-worker-tcp-comm-10-#82, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-10-#82", id=111,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-10,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-10, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283565, 2020-03-12
13:04:43.565)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-14,
threadName=grid-nio-worker-tcp-comm-14-#86, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-14-#86", id=115,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-14,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-14, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283566, 2020-03-12
13:04:43.566)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=nio-acceptor-tcp-comm,
threadName=nio-acceptor-tcp-comm-#88, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="nio-acceptor-tcp-comm-#88", id=120, state=RUNNABLE,
blockCnt=1, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=nio-acceptor-tcp-comm,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=nio-acceptor-tcp-comm, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283566, 2020-03-12
13:04:43.566)



Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger error
SEVERE: Blocked system-critical thread has been detected. This can lead to
cluster-wide undefined behaviour [workerName=grid-nio-worker-tcp-comm-9,
threadName=grid-nio-worker-tcp-comm-9-#81, blockedFor=14s]
Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Thread [name="grid-nio-worker-tcp-comm-9-#81", id=110,
state=RUNNABLE, blockCnt=0, waitCnt=0]

Mar 12, 2020 1:04:43 PM java.util.logging.LogManager$RootLogger log
WARNING: Possible failure suppressed accordingly to a configured handler
[hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0,
super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet
[SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]],
failureCtx=FailureContext [type=SYSTEM_WORKER_BLOCKED, err=class
o.a.i.IgniteException: GridWorker [name=grid-nio-worker-tcp-comm-9,
igniteInstanceName=null, finished=false, heartbeatTs=1584018269555]]]
class org.apache.ignite.IgniteException: GridWorker
[name=grid-nio-worker-tcp-comm-9, igniteInstanceName=null, finished=false,
heartbeatTs=1584018269555]
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1803)
        at
org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance$2.apply(IgnitionEx.java:1798)
        at
org.apache.ignite.internal.worker.WorkersRegistry.onIdle(WorkersRegistry.java:234)
        at
org.apache.ignite.internal.util.worker.GridWorker.onIdle(GridWorker.java:297)
        at
org.apache.ignite.internal.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:194)
        at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
        at java.lang.Thread.run(Thread.java:748)

Mar 12, 2020 1:04:43 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Page locks dump:

Thread=[name=exchange-worker-#103, id=162], state=TIMED_WAITING
Locked pages = []
Locked pages log: name=exchange-worker-#103 time=(1584018283567, 2020-03-12
13:04:43.567)



=====================================================================
Server 2 :
INFO: Started exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=false, evt=DISCOVERY_CUSTOM_EVT,
evtNode=96b07658-525d-4230-92bc-4bb756c633c4,
customEvt=DynamicCacheChangeBatch
[id=679d6dec071-87d6067d-1b6b-44b8-a596-18ff29dca0f4, reqs=ArrayList
[DynamicCacheChangeRequest [cacheName=SQL_PUBLIC_DEF, hasCfg=true,
nodeId=96b07658-525d-4230-92bc-4bb756c633c4, clientStartOnly=false,
stop=false, destroy=false, disabledAfterStartfalse]],
exchangeActions=ExchangeActions [startCaches=[SQL_PUBLIC_DEF],
stopCaches=null, startGrps=[SQL_PUBLIC_DEF], stopGrps=[], resetParts=null,
stateChangeRequest=null], startCaches=false], allowMerge=false,
exchangeFreeSwitch=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Started cache [name=SQL_PUBLIC_DEF, id=-897950656,
dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=0,
mvcc=false]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partitions release latch: ClientLatch
[coordinator=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018173220, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], ackSent=true,
super=CompletableLatch [id=CompletableLatchUid [id=exchange,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]]]
Mar 12, 2020 1:03:41 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], waitTime=0ms,
futInfo=NA, mode=LOCAL]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished exchange init [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], crd=false]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Received full message, will finish exchange
[node=96b07658-525d-4230-92bc-4bb756c633c4, resVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish exchange future [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
err=null, rebalanced=true, wasRebalanced=true]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finish proxy initialization, cacheName=SQL_PUBLIC_DEF,
localNodeId=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Completed partition exchange
[localNode=c003c5d9-08c9-404e-91c8-0a2b3ddbbb5a,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=96b07658-525d-4230-92bc-4bb756c633c4,
consistentId=0:0:0:0:0:0:0:1,x.x.x.y,127.0.0.1:47500, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1584018173220, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2]]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange timings [startVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (3 ms), stage="Update caches registry" (3 ms), stage="Start
caches" (60 ms), stage="Affinity initialization on cache group start" (8
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (4 ms), stage="Wait
partitions release" (0 ms), stage="After states restored callback" (32 ms),
stage="Waiting for Full message" (13 ms), stage="Affinity recalculation" (0
ms), stage="Full map updating" (2 ms), stage="Exchange done" (2 ms),
stage="Total time" (127 ms)]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], resVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_DEF]" (8 ms) (parent=Affinity initialization on cache group
start)]
Mar 12, 2020 1:03:42 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=2, minorTopVer=2], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=96b07658-525d-4230-92bc-4bb756c633c4]
Mar 12, 2020 1:03:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=c003c5d9, uptime=00:01:00.007]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.07%, avg=0.12%, GC=0%]
    ^-- PageMemory [pages=1192]
    ^-- Heap [used=138MB, free=98.09%, comm=620MB]
    ^-- Off-heap [used=4MB, free=99.93%, comm=336MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=3MB, free=99.94%, comm=256MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=27, qSize=0]
Mar 12, 2020 1:04:40 PM org.apache.ignite.logger.java.JavaLogger warning
WARNING: Timed out waiting for message delivery receipt (most probably, the
reason is in long GC pauses on remote node; consider tuning GC and
increasing 'ackTimeout' configuration property). Will retry to send message
with increased timeout [currentTimeout=10000,
rmtAddr=/0:0:0:0:0:0:0:1:47500, rmtPort=47500]
Mar 12, 2020 1:04:53 PM org.apache.ignite.logger.java.JavaLogger info
INFO:
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
    ^-- Node [id=c003c5d9, uptime=00:02:00.027]
    ^-- H/N/C [hosts=1, nodes=2, CPUs=32]
    ^-- CPU [cur=0.03%, avg=0.09%, GC=0%]
    ^-- PageMemory [pages=1192]
    ^-- Heap [used=142MB, free=98.02%, comm=620MB]
    ^-- Off-heap [used=4MB, free=99.93%, comm=336MB]
    ^--   sysMemPlc region [used=0MB, free=99.21%, comm=40MB]
    ^--   default region [used=3MB, free=99.94%, comm=256MB]
    ^--   TxLog region [used=0MB, free=100%, comm=40MB]
    ^-- Outbound messages queue [size=0]
    ^-- Public thread pool [active=0, idle=0, qSize=0]
    ^-- System thread pool [active=0, idle=6, qSize=0]
Mar 12, 2020 1:05:10 PM org.apache.ignite.logger.java.JavaLogger info
INFO: Finished serving remote node connection
[rmtAddr=/0:0:0:0:0:0:0:1:63142, rmtPort=63142

====================================================================

My Ignite config looks like :

<beans xmlns="http://www.springframework.org/schema/beans"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="
       http://www.springframework.org/schema/beans
       http://www.springframework.org/schema/beans/spring-beans.xsd">

    <bean id="grid.cfg"
class="org.apache.ignite.configuration.IgniteConfiguration">
        <property name="pluginConfigurations">
            <bean id="securityPlugin"

class="com.xxx.xxx.xxx.xxx.plugin.security.SecurityPluginConfiguration">
                <constructor-arg value="xxx"/>
            </bean>
        </property>
        <property name="discoverySpi" ref="tcpDiscSpiSpecific"/>
        <property name="clientFailureDetectionTimeout" value="30000"/>

    </bean>
    <bean id="tcpPortConfig"

class="org.apache.ignite.spi.discovery.tcp.ipfinder.vm.TcpDiscoveryVmIpFinder">
        <property name="addresses">
            <list>
                <value>127.0.0.1:47500..47502</value>
            </list>
        </property>
    </bean>
    <bean id="nodeSecurityCredential"
class="org.apache.ignite.plugin.security.SecurityCredentials">
        <constructor-arg value="xxx"/>
        <constructor-arg value="xxx"/>
    </bean>
    <bean id="tcpDiscSpiSpecific"

class="com.xxx.xxx.xxx.common.plugin.discoveryspi.CustomTcpDiscoverySpi"
          parent="tcpDiscSpi">

    </bean>
    <bean id="tcpDiscSpi"
class="com.xxx.xxx.xxx.common.plugin.discoveryspi.CustomTcpDiscoverySpi"
          abstract="true">
        <constructor-arg ref="nodeSecurityCredential"/>
        <property name="ipFinder" ref="tcpPortConfig"/>
    </bean>
</beans>

<http://apache-ignite-users.70518.x6.nabble.com/file/t2757/queryhanging.png>



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/

VeenaMithare VeenaMithare
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

HI Ilya,

Thank you for the invite. I will be very happy to debug and develop for
Ignite.

For the issue mentioned, I will debug a bit more and then file a ticket,

regards,
Veena.



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
VeenaMithare VeenaMithare
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

This post was updated on .
Hi , On further debugging, I found out that when security is enabled, and I
do updates/selects from dbeaver, the security context in
class GridIOManager ,
method -createGridIoMessage -
line - ctx.security().securityContext() returns me the securitycontext of the thin client.

The message generated out of createGridIoMessage  is passed on to the next node.

This is used in
class - IgniteSecurityProcessor
method - ( withContext)
line - ctx.discovery().node(uuid)
on the next node :    

@Override public OperationSecurityContext withContext(UUID nodeId)
{        return withContext(            secCtxs.computeIfAbsent(nodeId,              
uuid -> nodeSecurityContext(                    marsh,
U.resolveClassLoader(ctx.config()), ctx.discovery().node(uuid)              
)            )        );    }


The ctx.discovery().node(uuid) used to
determine the ClusterNode that is passed into nodeSecurityContext() returns
null, since the uuid is that of the remote client id not the remote node id.


Hence
class: SecurityUtils.java
method : nodeSecurityContext
line :         byte[] subjBytes = node.attribute(IgniteNodeAttributes.ATTR_SECURITY_SUBJECT_V2);

Throws null pointer exception since node is null.

I feel this might be a bug and handling security for thin clients might have
broken. Could you advice.



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
VeenaMithare VeenaMithare
Reply | Threaded
Open this post in threaded view
|

Re: 2.8.0 : JDBC Thin Client : Unable to load the tables via DBeaver

HI ,
I created a ticket for Select Operation since IGNITE-12579 mentions only
insert operation failure :
https://issues.apache.org/jira/browse/IGNITE-12833

regards,
Veena.



--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/