You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Juris Pipurs (Jira)" <ji...@apache.org> on 2021/07/19 08:50:00 UTC
[jira] [Created] (IGNITE-15145) javax.cache.CacheException: Failed
to run reduce query locally. The query was cancelled while executing.
Juris Pipurs created IGNITE-15145:
-------------------------------------
Summary: javax.cache.CacheException: Failed to run reduce query locally. The query was cancelled while executing.
Key: IGNITE-15145
URL: https://issues.apache.org/jira/browse/IGNITE-15145
Project: Ignite
Issue Type: Bug
Environment: Windows 10 pro
Processor: Intel i9 2.4Ghz
RAM: 32GB
64bit Operating system
Ignite startup log:
>>> __________ ________________
>>> / _/ ___/ |/ / _/_ __/ __/
>>> _/ // (7 7 // / / / / _/
>>> /___/\___/_/|_/___/ /_/ /___/
>>>
>>> ver. 2.10.0#20210310-sha1:bc24f6ba
>>> 2021 Copyright(C) Apache Software Foundation
>>>
>>> Ignite documentation: http://ignite.apache.org
[15:57:49,865][INFO][main][IgniteKernal] Config URL: file:/C:/Tools/apache_ignite/config/ignite_config.xml
[15:57:49,878][INFO][main][IgniteKernal] IgniteConfiguration [igniteInstanceName=null, pubPoolSize=16, svcPoolSize=16, callbackPoolSize=16, stripedPoolSize=16, sysPoolSize=16, mgmtPoolSize=4, dataStreamerPoolSize=16, utilityCachePoolSize=16, utilityCacheKeepAliveTime=60000, p2pPoolSize=2, qryPoolSize=16, buildIdxPoolSize=4, igniteHome=c:/Tools/apache-ignite-2.10.0-bin, igniteWorkDir=C:\Tools\apache_ignite\work_directory, mbeanSrv=com.sun.jmx.mbeanserver.JmxMBeanServer@2ea6137, nodeId=b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91, marsh=BinaryMarshaller [], marshLocJobs=false, daemon=false, p2pEnabled=false, netTimeout=5000, netCompressionLevel=1, sndRetryDelay=1000, sndRetryCnt=3, metricsHistSize=10000, metricsUpdateFreq=2000, metricsExpTime=9223372036854775807, discoSpi=TcpDiscoverySpi [addrRslvr=null, sockTimeout=0, ackTimeout=0, marsh=null, reconCnt=10, reconDelay=2000, maxAckTimeout=600000, soLinger=0, forceSrvMode=false, clientReconnectDisabled=false, internalLsnr=null, skipAddrsRandomization=false], segPlc=STOP, segResolveAttempts=2, waitForSegOnStart=true, allResolversPassReq=true, segChkFreq=10000, commSpi=TcpCommunicationSpi [connectGate=org.apache.ignite.spi.communication.tcp.internal.ConnectGateway@57250572, ctxInitLatch=java.util.concurrent.CountDownLatch@5609159b[Count = 1], stopping=false, clientPool=null, nioSrvWrapper=null, stateProvider=null], evtSpi=org.apache.ignite.spi.eventstorage.NoopEventStorageSpi@2118cddf, colSpi=NoopCollisionSpi [], deploySpi=LocalDeploymentSpi [], indexingSpi=org.apache.ignite.spi.indexing.noop.NoopIndexingSpi@3f56875e, addrRslvr=null, encryptionSpi=org.apache.ignite.spi.encryption.noop.NoopEncryptionSpi@2b4bac49, tracingSpi=org.apache.ignite.spi.tracing.NoopTracingSpi@fd07cbb, clientMode=false, rebalanceThreadPoolSize=4, rebalanceTimeout=10000, rebalanceBatchesPrefetchCnt=3, rebalanceThrottle=0, rebalanceBatchSize=524288, txCfg=TransactionConfiguration [txSerEnabled=false, dfltIsolation=REPEATABLE_READ, dfltConcurrency=PESSIMISTIC, dfltTxTimeout=0, txTimeoutOnPartitionMapExchange=0, deadlockTimeout=10000, pessimisticTxLogSize=0, pessimisticTxLogLinger=10000, tmLookupClsName=null, txManagerFactory=null, useJtaSync=false], cacheSanityCheckEnabled=true, discoStartupDelay=60000, deployMode=SHARED, p2pMissedCacheSize=100, locHost=null, timeSrvPortBase=31100, timeSrvPortRange=100, failureDetectionTimeout=10000, sysWorkerBlockedTimeout=null, clientFailureDetectionTimeout=30000, metricsLogFreq=60000, connectorCfg=ConnectorConfiguration [jettyPath=null, host=null, port=11211, noDelay=true, directBuf=false, sndBufSize=32768, rcvBufSize=32768, idleQryCurTimeout=600000, idleQryCurCheckFreq=60000, sndQueueLimit=0, selectorCnt=4, idleTimeout=7000, sslEnabled=false, sslClientAuth=false, sslCtxFactory=null, sslFactory=null, portRange=100, threadPoolSize=16, msgInterceptor=null], odbcCfg=null, warmupClos=null, atomicCfg=AtomicConfiguration [seqReserveSize=1000, cacheMode=PARTITIONED, backups=1, aff=null, grpName=null], classLdr=null, sslCtxFactory=null, platformCfg=null, binaryCfg=null, memCfg=null, pstCfg=null, dsCfg=DataStorageConfiguration [sysRegionInitSize=41943040, sysRegionMaxSize=104857600, pageSize=0, concLvl=0, dfltDataRegConf=DataRegionConfiguration [name=default, maxSize=6818405580, initSize=268435456, swapPath=null, pageEvictionMode=DISABLED, evictionThreshold=0.9, emptyPagesPoolSize=100, metricsEnabled=false, metricsSubIntervalCount=5, metricsRateTimeInterval=60000, persistenceEnabled=true, checkpointPageBufSize=0, lazyMemoryAllocation=true, warmUpCfg=null], dataRegions=DataRegionConfiguration[] [DataRegionConfiguration [name=4GB_Region, maxSize=4294967296, initSize=524288000, swapPath=null, pageEvictionMode=RANDOM_2_LRU, evictionThreshold=0.9, emptyPagesPoolSize=100, metricsEnabled=false, metricsSubIntervalCount=5, metricsRateTimeInterval=60000, persistenceEnabled=true, checkpointPageBufSize=0, lazyMemoryAllocation=true, warmUpCfg=null]], storagePath=C:\Tools\apache_ignite\storage, checkpointFreq=180000, lockWaitTime=10000, checkpointThreads=4, checkpointWriteOrder=SEQUENTIAL, walHistSize=20, maxWalArchiveSize=1073741824, walSegments=10, walSegmentSize=67108864, walPath=C:\Tools\apache_ignite\wal, walArchivePath=C:\Tools\apache_ignite\wal\archive, metricsEnabled=false, walMode=LOG_ONLY, walTlbSize=131072, walBuffSize=0, walFlushFreq=2000, walFsyncDelay=1000, walRecordIterBuffSize=67108864, alwaysWriteFullPages=false, fileIOFactory=org.apache.ignite.internal.processors.cache.persistence.file.AsyncFileIOFactory@dd0c991, metricsSubIntervalCnt=5, metricsRateTimeInterval=60000, walAutoArchiveAfterInactivity=-1, writeThrottlingEnabled=false, walCompactionEnabled=false, walCompactionLevel=1, checkpointReadLockTimeout=null, walPageCompression=DISABLED, walPageCompressionLevel=null, dfltWarmUpCfg=null, encCfg=org.apache.ignite.configuration.EncryptionConfiguration@69fb6037, defragmentationThreadPoolSize=4], snapshotPath=snapshots, activeOnStart=true, activeOnStartPropSetFlag=false, autoActivation=true, autoActivationPropSetFlag=false, clusterStateOnStart=null, sqlConnCfg=null, cliConnCfg=ClientConnectorConfiguration [host=null, port=10800, portRange=100, sockSndBufSize=0, sockRcvBufSize=0, tcpNoDelay=true, maxOpenCursorsPerConn=128, threadPoolSize=16, selectorCnt=8, idleTimeout=0, handshakeTimeout=10000, jdbcEnabled=true, odbcEnabled=true, thinCliEnabled=true, sslEnabled=false, useIgniteSslCtxFactory=true, sslClientAuth=false, sslCtxFactory=null, thinCliCfg=ThinClientConfiguration [maxActiveTxPerConn=100, maxActiveComputeTasksPerConn=0]], mvccVacuumThreadCnt=2, mvccVacuumFreq=5000, authEnabled=true, failureHnd=null, commFailureRslvr=null, sqlCfg=SqlConfiguration [longQryWarnTimeout=3000, dfltQryTimeout=0, sqlQryHistSize=1000, validationEnabled=false]]
[15:57:49,878][INFO][main][IgniteKernal] Daemon mode: off
[15:57:49,878][INFO][main][IgniteKernal] OS: Windows 10 10.0 amd64
[15:57:49,878][INFO][main][IgniteKernal] OS user: juris.pipurs
[15:57:49,882][INFO][main][IgniteKernal] PID: 42924
[15:57:49,882][INFO][main][IgniteKernal] Language runtime: Java Platform API Specification ver. 1.8
[15:57:49,882][INFO][main][IgniteKernal] VM information: Java(TM) SE Runtime Environment 1.8.0_201-b09 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.201-b09
[15:57:49,883][INFO][main][IgniteKernal] VM total memory: 7.1GB
[15:57:49,883][INFO][main][IgniteKernal] Remote Management [restart: on, REST: on, JMX (remote: off)]
[15:57:49,883][INFO][main][IgniteKernal] Logger: JavaLogger [quiet=true, config=null]
[15:57:49,883][INFO][main][IgniteKernal] IGNITE_HOME=c:/Tools/apache-ignite-2.10.0-bin
[15:57:49,883][INFO][main][IgniteKernal] VM arguments: [-XX:+AggressiveOpts, -Dfile.encoding=UTF-8, -DIGNITE_QUIET=true, -DIGNITE_SUCCESS_FILE=c:/Tools/apache-ignite-2.10.0-bin/work/ignite_success_933c5f0b-aa3c-48cf-8ea8-092a8b85c229, -DIGNITE_HOME=c:/Tools/apache-ignite-2.10.0-bin, -DIGNITE_PROG_NAME=C:\Tools\apache-ignite-2.10.0-bin\bin\ignite.sh]
[15:57:49,883][INFO][main][IgniteKernal] System cache's DataRegion size is configured to 40 MB. Use DataStorageConfiguration.systemRegionInitialSize property to change the setting.
[15:57:49,884][INFO][main][IgniteKernal] Configured caches [in 'sysMemPlc' dataRegion: ['ignite-sys-cache'], in 'default' dataRegion: ['mlp']]
[15:57:49,884][WARNING][main][IgniteKernal] Please set system property '-Djava.net.preferIPv4Stack=true' to avoid possible problems in mixed environments.
[15:57:49,885][INFO][main][IgniteKernal] 3-rd party licenses can be found at: c:/Tools/apache-ignite-2.10.0-bin\libs\licenses
[15:57:49,886][WARNING][pub-#37][GridDiagnostic] Initial heap size is 510MB (should be no less than 512MB, use -Xms512m -Xmx512m).
[15:57:49,939][INFO][main][IgnitePluginProcessor] Configured plugins:
[15:57:49,940][INFO][main][IgnitePluginProcessor] ^-- None
[15:57:49,940][INFO][main][IgnitePluginProcessor]
[15:57:49,942][INFO][main][FailureProcessor] Configured failure handler: [hnd=StopNodeOrHaltFailureHandler [tryStop=false, timeout=0, super=AbstractFailureHandler [ignoredFailureTypes=UnmodifiableSet [SYSTEM_WORKER_BLOCKED, SYSTEM_CRITICAL_OPERATION_TIMEOUT]]]]
[15:57:50,116][INFO][main][TcpCommunicationSpi] Successfully bound communication NIO server to TCP port [port=47100, locHost=0.0.0.0/0.0.0.0, selectorsCnt=8, selectorSpins=0, pairedConn=false]
[15:57:50,116][WARNING][main][TcpCommunicationSpi] Message queue limit is set to 0 which may lead to potential OOMEs when running cache operations in FULL_ASYNC or PRIMARY_SYNC modes due to message queues growth on sender and receiver sides.
[15:57:50,249][WARNING][main][NoopCheckpointSpi] Checkpoints are disabled (to enable configure any GridCheckpointSpi implementation)
[15:57:50,264][WARNING][main][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[15:57:50,265][INFO][main][IgniteKernal] Security status [authentication=off, sandbox=off, tls/ssl=off]
[15:57:50,289][INFO][main][TcpDiscoverySpi] Successfully bound to TCP port [port=47500, localHost=0.0.0.0/0.0.0.0, locNodeId=b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91]
[15:57:50,296][INFO][main][PdsFoldersResolver] Successfully locked persistence storage folder [C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2]
[15:57:50,296][INFO][main][PdsFoldersResolver] Consistent ID used for local node is [12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2] according to persistence data storage folders
[15:57:50,297][INFO][main][MaintenanceProcessor] Resolved store directory for node persistent data: C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2
[15:57:50,319][INFO][main][CacheObjectBinaryProcessorImpl] Resolved directory for serialized binary metadata: C:\Tools\apache_ignite\work_directory\db\binary_meta\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2
[15:57:50,441][INFO][main][FilePageStoreManager] Resolved page store work directory: C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2
[15:57:50,445][INFO][main][FileWriteAheadLogManager] Resolved write ahead log work directory: C:\Tools\apache_ignite\wal\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2
[15:57:50,445][INFO][main][FileWriteAheadLogManager] Resolved write ahead log archive directory: C:\Tools\apache_ignite\wal\archive\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2
[15:57:50,465][INFO][main][FileHandleManagerImpl] Initialized write-ahead log manager [mode=LOG_ONLY]
[15:57:50,471][WARNING][main][GridCacheDatabaseSharedManager] Page eviction mode will have no effect because the oldest pages are evicted automatically if Ignite persistence is enabled: 4GB_Region
[15:57:50,472][WARNING][main][GridCacheDatabaseSharedManager] DataRegionConfiguration.maxWalArchiveSize instead DataRegionConfiguration.walHistorySize would be used for removing old archive wal files
[15:57:50,485][INFO][main][GridCacheDatabaseSharedManager] Configured data regions initialized successfully [total=6]
[15:57:50,499][INFO][main][IgniteSnapshotManager] Resolved snapshot work directory: C:\Tools\apache_ignite\work_directory\snapshots
[15:57:50,499][INFO][main][IgniteSnapshotManager] Resolved temp directory for snapshot creation: C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\snp
[15:57:50,523][WARNING][main][IgniteH2Indexing] Serialization of Java objects in H2 was enabled.
[15:57:50,623][INFO][main][ClientListenerProcessor] Client connector processor has started on TCP port 10800
[15:57:50,686][INFO][main][GridTcpRestProtocol] Command protocol successfully started [name=TCP binary, host=0.0.0.0/0.0.0.0, port=11211]
[15:57:51,058][INFO][main][IgniteKernal] Non-loopback local IPs: 192.168.8.106, fd74:ae1:c5e4:9700:124:2911:c031:bdbc, fd74:ae1:c5e4:9700:5064:5148:c025:3716, fe80:0:0:0:5064:5148:c025:3716%wlan2, fe80:0:0:0:a18d:bbca:9df4:399e%eth6, fe80:0:0:0:cc64:8fb3:fcbd:74b7%wlan1, fe80:0:0:0:ccd3:b1d1:f47:7885%net8, fe80:0:0:0:cf5:9480:6e23:e864%wlan0, fe80:0:0:0:d1f7:570a:a016:251e%net4, fe80:0:0:0:fc4a:9a10:568:8fb7%eth5
[15:57:51,058][INFO][main][IgniteKernal] Enabled local MACs: 00FF6634E03D, 2CDB07CF9D88, 2CDB07CF9D89, 2CDB07CF9D8C, 2EDB07CF9D88, CC483AA6F8FE
[15:57:51,063][INFO][main][CheckpointMarkersStorage] Read checkpoint status [startMarker=C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\cp\1626439591774-bb49af05-20a3-4cb0-911e-86c58307c84f-START.bin, endMarker=C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\cp\1626439591774-bb49af05-20a3-4cb0-911e-86c58307c84f-END.bin]
[15:57:51,066][INFO][main][PageMemoryImpl] Started page memory [memoryAllocated=100,0 MiB, pages=24800, tableSize=1,9 MiB, checkpointBuffer=100,0 MiB]
[15:57:51,067][INFO][main][GridCacheDatabaseSharedManager] Checking memory state [lastValidPos=WALPointer [idx=6839, fileOff=46078161, len=99273], lastMarked=WALPointer [idx=6839, fileOff=46078161, len=99273], lastCheckpointId=bb49af05-20a3-4cb0-911e-86c58307c84f]
[15:57:51,098][INFO][main][GridCacheDatabaseSharedManager] Found last checkpoint marker [cpId=bb49af05-20a3-4cb0-911e-86c58307c84f, pos=WALPointer [idx=6839, fileOff=46078161, len=99273]]
[15:57:51,125][INFO][main][GridCacheDatabaseSharedManager] Applying lost cache updates since last checkpoint record [lastMarked=WALPointer [idx=6839, fileOff=46078161, len=99273], lastCheckpointId=bb49af05-20a3-4cb0-911e-86c58307c84f]
[15:57:51,140][INFO][main][GridCacheDatabaseSharedManager] Finished applying WAL changes [updatesApplied=0, time=0 ms]
[15:57:51,140][INFO][main][GridCacheProcessor] Restoring partition state for local groups.
[15:57:51,140][INFO][main][GridCacheProcessor] Finished restoring partition state for local groups [groupsProcessed=0, partitionsProcessed=0, time=0ms]
[15:57:51,299][INFO][main][GridEncryptionManager] Encryption keys loaded from metastore. [grps=, masterKeyName=null]
[15:57:51,314][INFO][main][GridClusterStateProcessor] Restoring history for BaselineTopology[id=0]
[15:57:51,331][WARNING][main][GridLocalConfigManager] Static configuration for the following caches will be ignored because a persistent cache with the same name already exist (see https://apacheignite.readme.io/docs/cache-configuration for more information): [mlp]
[15:57:51,337][INFO][main][ClusterProcessor] Cluster ID and tag has been read from metastorage: ClusterIdAndTag [id=78aedd53-ce03-4b85-af1a-b108a79b969e, tag=upbeat_tesla]
[15:57:51,339][INFO][main][IgniteClusterImpl] Shutdown policy was updated [oldVal=null, newVal=null]
[15:57:51,340][INFO][main][DistributedBaselineConfiguration] Baseline parameter 'baselineAutoAdjustEnabled' was changed from 'null' to 'false'
[15:57:51,341][INFO][main][DistributedBaselineConfiguration] Baseline parameter 'baselineAutoAdjustTimeout' was changed from 'null' to '300000'
[15:57:51,341][INFO][main][IgniteH2Indexing] SQL parameter 'sql.disabledFunctions' was changed from 'null' to '[FILE_WRITE, CANCEL_SESSION, MEMORY_USED, CSVREAD, LINK_SCHEMA, MEMORY_FREE, FILE_READ, CSVWRITE, SESSION_ID, LOCK_MODE]'
[15:57:51,341][INFO][main][IgniteH2Indexing] SQL parameter 'sql.defaultQueryTimeout' was changed from 'null' to '0'
[15:57:51,344][INFO][main][FilePageStoreManager] Cleanup cache stores [total=1, left=0, cleanFiles=false]
[15:57:51,349][INFO][main][PageMemoryImpl] Started page memory [memoryAllocated=100,0 MiB, pages=24800, tableSize=1,9 MiB, checkpointBuffer=100,0 MiB]
[15:57:51,349][INFO][main][PageMemoryImpl] Started page memory [memoryAllocated=100,0 MiB, pages=24800, tableSize=1,9 MiB, checkpointBuffer=100,0 MiB]
[15:57:51,351][INFO][main][PageMemoryImpl] Started page memory [memoryAllocated=100,0 MiB, pages=24800, tableSize=1,9 MiB, checkpointBuffer=100,0 MiB]
[15:57:51,352][INFO][main][GridCacheDatabaseSharedManager] Starting binary memory restore for: [108209, -2100569601, 461239097, 673411800, -1469961571]
[15:57:51,814][INFO][main][CheckpointMarkersStorage] Read checkpoint status [startMarker=C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\cp\1626439591774-bb49af05-20a3-4cb0-911e-86c58307c84f-START.bin, endMarker=C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\cp\1626439591774-bb49af05-20a3-4cb0-911e-86c58307c84f-END.bin]
[15:57:51,814][INFO][main][GridCacheDatabaseSharedManager] Checking memory state [lastValidPos=WALPointer [idx=6839, fileOff=46078161, len=99273], lastMarked=WALPointer [idx=6839, fileOff=46078161, len=99273], lastCheckpointId=bb49af05-20a3-4cb0-911e-86c58307c84f]
[15:57:51,842][INFO][main][GridCacheDatabaseSharedManager] Found last checkpoint marker [cpId=bb49af05-20a3-4cb0-911e-86c58307c84f, pos=WALPointer [idx=6839, fileOff=46078161, len=99273]]
[15:57:51,848][INFO][main][GridCacheDatabaseSharedManager] Binary memory state restored at node startup [restoredPtr=WALPointer [idx=6839, fileOff=46177434, len=0]]
[15:57:51,850][INFO][main][FileWriteAheadLogManager] Resuming logging to WAL segment [file=C:\Tools\apache_ignite\wal\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\0000000000000009.wal, offset=46177434, ver=2]
[15:57:51,873][INFO][main][PageMemoryImpl] Started page memory [memoryAllocated=6,4 GiB, pages=1613600, tableSize=125,5 MiB, checkpointBuffer=1,6 GiB]
[15:57:51,941][INFO][main][GridCacheProcessor] Started cache in recovery mode [name=mlp, id=108209, dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false]
[15:57:51,950][INFO][main][GridCacheProcessor] Started cache in recovery mode [name=ignite-sys-cache, id=-2100569601, dataRegionName=sysMemPlc, mode=REPLICATED, atomicity=TRANSACTIONAL, backups=2147483647, mvcc=false]
[15:57:51,968][INFO][main][GridCacheProcessor] Started cache in recovery mode [name=MLP_DFF_CACHE, id=461239097, dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=1, mvcc=false]
[15:57:51,975][INFO][main][GridCacheProcessor] Started cache in recovery mode [name=MLP_CATALOG_PRICE_CACHE, id=673411800, dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=1, mvcc=false]
[15:57:51,977][INFO][main][GridCacheProcessor] Started cache in recovery mode [name=MLP_SUPPLIER_CACHE, id=-1469961571, dataRegionName=default, mode=PARTITIONED, atomicity=ATOMIC, backups=1, mvcc=false]
[15:57:51,978][INFO][main][GridCacheDatabaseSharedManager] Binary recovery performed in 626 ms.
[15:57:51,978][INFO][main][CheckpointMarkersStorage] Read checkpoint status [startMarker=C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\cp\1626439591774-bb49af05-20a3-4cb0-911e-86c58307c84f-START.bin, endMarker=C:\Tools\apache_ignite\storage\node00-12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2\cp\1626439591774-bb49af05-20a3-4cb0-911e-86c58307c84f-END.bin]
[15:57:51,979][INFO][main][GridCacheDatabaseSharedManager] Applying lost cache updates since last checkpoint record [lastMarked=WALPointer [idx=6839, fileOff=46078161, len=99273], lastCheckpointId=bb49af05-20a3-4cb0-911e-86c58307c84f]
[15:57:51,995][INFO][main][GridCacheDatabaseSharedManager] Finished applying WAL changes [updatesApplied=0, time=16 ms]
[15:57:51,995][INFO][main][GridCacheProcessor] Restoring partition state for local groups.
[15:57:53,235][INFO][main][GridCacheProcessor] Finished restoring partition state for local groups [groupsProcessed=5, partitionsProcessed=4196, time=1237ms]
[15:57:53,263][WARNING][main][TcpDiscoveryMulticastIpFinder] TcpDiscoveryMulticastIpFinder has no pre-configured addresses (it is recommended in production to specify at least one address in TcpDiscoveryMulticastIpFinder.getAddresses() configuration property)
[15:57:55,836][INFO][disco-notifier-worker-#80][MvccProcessorImpl] Assigned mvcc coordinator [crd=MvccCoordinator [topVer=AffinityTopologyVersion [topVer=1, minorTopVer=0], nodeId=b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91, ver=1626440275830, local=true, initialized=false]]
[15:57:55,844][INFO][disco-notifier-worker-#80][GridClusterStateProcessor] Sending activate cluster request with BaselineTopology BaselineTopology [id=0, branchingHash=590006204, branchingType='Cluster activation', baselineNodes=[12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2]]
[15:57:55,850][INFO][sys-#91][ClusterProcessor] Writing cluster ID and tag to metastorage on ready for write ClusterIdAndTag [id=78aedd53-ce03-4b85-af1a-b108a79b969e, tag=upbeat_tesla]
[15:57:55,852][INFO][disco-notifier-worker-#80][GridClusterStateProcessor] Received activate cluster request with BaselineTopology[id=0] initiator node ID: b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91
[15:57:55,854][INFO][disco-notifier-worker-#80][GridClusterStateProcessor] Started state transition: activate cluster
[15:57:55,919][INFO][main][IgniteKernal] Performance suggestions for grid (fix if possible)
[15:57:55,920][INFO][main][IgniteKernal] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true
[15:57:55,920][INFO][main][IgniteKernal] ^-- Enable G1 Garbage Collector (add '-XX:+UseG1GC' to JVM options)
[15:57:55,920][INFO][main][IgniteKernal] ^-- Specify JVM heap max size (add '-Xmx<size>[g|G|m|M|k|K]' to JVM options)
[15:57:55,920][INFO][main][IgniteKernal] ^-- Set max direct memory size if getting 'OOME: Direct buffer memory' (add '-XX:MaxDirectMemorySize=<size>[g|G|m|M|k|K]' to JVM options)
[15:57:55,920][INFO][main][IgniteKernal] ^-- Disable processing of calls to System.gc() (add '-XX:+DisableExplicitGC' to JVM options)
[15:57:55,920][INFO][main][IgniteKernal] Refer to this page for more performance suggestions: https://apacheignite.readme.io/docs/jvm-and-system-tuning
[15:57:55,920][INFO][main][IgniteKernal]
[15:57:55,920][INFO][main][IgniteKernal] To start Console Management & Monitoring run ignitevisorcmd.\{sh|bat}
[15:57:55,920][INFO][exchange-worker-#92][time] Started exchange init [topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1], crd=true, evt=DISCOVERY_CUSTOM_EVT, evtNode=b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91, customEvt=ChangeGlobalStateMessage [id=7b1056faa71-fcf4ef10-3c66-48ab-9074-2424dc3aebdd, reqId=79430283-f001-44af-83d1-14c2191d70b0, initiatingNodeId=b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91, state=ACTIVE, baselineTopology=BaselineTopology [id=0, branchingHash=590006204, branchingType='Cluster activation', baselineNodes=[12f939d1-ecdf-4fd5-83e0-9c5a5eec2de2]], forceChangeBaselineTopology=false, timestamp=1626440275843, forceDeactivation=true], allowMerge=false, exchangeFreeSwitch=false]
[15:57:55,921][INFO][exchange-worker-#92][GridDhtPartitionsExchangeFuture] Start activation process [nodeId=b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91, client=false, topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1]]. New state: ACTIVE
[15:57:55,921][INFO][main][IgniteKernal]
Reporter: Juris Pipurs
Attachments: ignite_config.xml
I'm testing ignite on my local machine. I have created 2 caches for catalogs and respective prices.
When I try to select the data after a couple of seconds I get this exception:
[19:23:08,731][SEVERE][client-connector-#135][ClientListenerNioListener] Failed to process client request [req=o.a.i.i.processors.platform.client.cache.ClientCacheSqlFieldsQueryRequest@7020c6d1][19:23:08,731][SEVERE][client-connector-#135][ClientListenerNioListener] Failed to process client request [req=o.a.i.i.processors.platform.client.cache.ClientCacheSqlFieldsQueryRequest@7020c6d1]javax.cache.CacheException: Failed to run reduce query locally. The query was cancelled while executing. at org.apache.ignite.internal.processors.query.h2.twostep.GridReduceQueryExecutor.query(GridReduceQueryExecutor.java:578) at org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing$7.iterator(IgniteH2Indexing.java:1776) at org.apache.ignite.internal.processors.cache.QueryCursorImpl.iter(QueryCursorImpl.java:102) at org.apache.ignite.internal.processors.cache.query.RegisteredQueryCursor.iter(RegisteredQueryCursor.java:91) at org.apache.ignite.internal.processors.cache.QueryCursorImpl.iterator(QueryCursorImpl.java:92) at org.apache.ignite.internal.processors.platform.client.cache.ClientCacheQueryCursor.iterator(ClientCacheQueryCursor.java:136) at org.apache.ignite.internal.processors.platform.client.cache.ClientCacheQueryCursor.writePage(ClientCacheQueryCursor.java:71) at org.apache.ignite.internal.processors.platform.client.cache.ClientCacheSqlFieldsQueryResponse.encode(ClientCacheSqlFieldsQueryResponse.java:73) at org.apache.ignite.internal.processors.platform.client.ClientMessageParser.encode(ClientMessageParser.java:487) at org.apache.ignite.internal.processors.odbc.ClientListenerNioListener.onMessage(ClientListenerNioListener.java:217) at org.apache.ignite.internal.processors.odbc.ClientListenerNioListener.onMessage(ClientListenerNioListener.java:56) at org.apache.ignite.internal.util.nio.GridNioFilterChain$TailFilter.onMessageReceived(GridNioFilterChain.java:279) at org.apache.ignite.internal.util.nio.GridNioFilterAdapter.proceedMessageReceived(GridNioFilterAdapter.java:109) at org.apache.ignite.internal.util.nio.GridNioAsyncNotifyFilter$3.body(GridNioAsyncNotifyFilter.java:97) at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120) at org.apache.ignite.internal.util.worker.GridWorkerPool$1.run(GridWorkerPool.java:70) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source)Caused by: class org.apache.ignite.cache.query.QueryCancelledException: The query was cancelled while executing. at org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeSqlQuery(IgniteH2Indexing.java:857) at org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeSqlQueryWithTimer(IgniteH2Indexing.java:949) at org.apache.ignite.internal.processors.query.h2.twostep.GridReduceQueryExecutor.query(GridReduceQueryExecutor.java:529) ... 18 more
Before that I get this warning.
[19:23:08,485][WARNING][long-qry-#55][LongRunningQueryManager] Query execution is too long [duration=3277ms, type=REDUCE, distributedJoin=false, enforceJoinOrder=false, lazy=false, schema=MLP_CATALOG_CACHE, sql='select count (distinct _T0.ID) from "MLP_CATALOG_CACHE".DSTCATALOG as _T0 , "MLP_CATALOG_PRICE_CACHE".DSTCATALOGPRICE as _T1 where ((((((_T0.MOLECULEID >= ?) and (_T0.MOLECULEID < ?)) and (_T0.ID IS NOT DISTINCT FROM _T1.CatalogId)) and _T1.ISSTOCKACCEPTABLE) and (_T1.SELLUNITMG >= ?)) and (_T1.PRICE < ?))', plan=SELECT
COUNT(DISTINCT __C0_0) AS __C0_0
FROM PUBLIC.__T0
/* MLP_CATALOG_CACHE."merge_scan" */
/* scanCount: 9217 */, reqId=755]
[19:23:08,730][INFO][client-connector-#135][GridMapQueryExecutor] Failed to run cancelled map query on local node: [localNodeId=b9e8ddca-e09a-4c1d-9a3a-bdb7264bfd91, reqId=755]
[19:23:08,731][WARNING][client-connector-#135][IgniteH2Indexing] Long running query is finished with error: The query was cancelled while executing. [duration=3512ms, type=REDUCE, distributedJoin=false, enforceJoinOrder=false, lazy=false, schema=MLP_CATALOG_CACHE, sql='select count (distinct _T0.ID) from "MLP_CATALOG_CACHE".DSTCATALOG as _T0 , "MLP_CATALOG_PRICE_CACHE".DSTCATALOGPRICE as _T1 where ((((((_T0.MOLECULEID >= ?) and (_T0.MOLECULEID < ?)) and (_T0.ID IS NOT DISTINCT FROM _T1.CatalogId)) and _T1.ISSTOCKACCEPTABLE) and (_T1.SELLUNITMG >= ?)) and (_T1.PRICE < ?))', plan=SELECT
COUNT(DISTINCT __C0_0) AS __C0_0
FROM PUBLIC.__T0
/* MLP_CATALOG_CACHE."merge_scan" */
/* scanCount: 9343 */, reqId=755]
Here is the status log:
[19:23:58,311][INFO][grid-timeout-worker-#38][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=b9e8ddca, uptime=03:26:02.404]
^-- Cluster [hosts=1, CPUs=16, servers=1, clients=0, topVer=1, minorTopVer=6]
^-- Network [addrs=[0:0:0:0:0:0:0:1, 127.0.0.1, 192.168.8.106, fd74:ae1:c5e4:9700:124:2911:c031:bdbc, fd74:ae1:c5e4:9700:5064:5148:c025:3716], discoPort=47500, commPort=47100]
^-- CPU [CPUs=16, curLoad=0%, avgLoad=1.9%, GC=0%]
^-- Heap [used=1441MB, free=80.05%, comm=1987MB]
^-- Off-heap memory [used=4157MB, free=62.2%, allocated=10798MB]
^-- Page memory [pages=1051927]
^-- sysMemPlc region [type=internal, persistence=true, lazyAlloc=false,
... initCfg=40MB, maxCfg=100MB, usedRam=0MB, freeRam=99.99%, allocRam=100MB, allocTotal=0MB]
^-- default region [type=default, persistence=true, lazyAlloc=true,
... initCfg=256MB, maxCfg=6502MB, usedRam=140MB, freeRam=97.84%, allocRam=6502MB, allocTotal=1554MB]
^-- metastoreMemPlc region [type=internal, persistence=true, lazyAlloc=false,
... initCfg=40MB, maxCfg=100MB, usedRam=0MB, freeRam=99.91%, allocRam=0MB, allocTotal=0MB]
^-- 4GB_Region region [type=user, persistence=true, lazyAlloc=true,
... initCfg=500MB, maxCfg=4096MB, usedRam=4016MB, freeRam=1.93%, allocRam=4096MB, allocTotal=6458MB]
^-- TxLog region [type=internal, persistence=true, lazyAlloc=false,
... initCfg=40MB, maxCfg=100MB, usedRam=0MB, freeRam=100%, allocRam=100MB, allocTotal=0MB]
^-- volatileDsMemPlc region [type=user, persistence=false, lazyAlloc=true,
... initCfg=40MB, maxCfg=100MB, usedRam=0MB, freeRam=100%, allocRam=0MB]
^-- Ignite persistence [used=8012MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=7, qSize=0]
I have attreched my startup config file.
And here is my configurations for both caches:
public static CacheClientConfiguration getCacheConfiguration()
{
return new CacheClientConfiguration()
{
Name = "MLP_CATALOG_CACHE",
Backups = 1,
CacheMode = CacheMode.Partitioned,
RebalanceMode = CacheRebalanceMode.Async,
WriteSynchronizationMode = CacheWriteSynchronizationMode.PrimarySync,
PartitionLossPolicy = PartitionLossPolicy.ReadOnlySafe,
DataRegionName = "4GB_Region",
QueryEntities = new[]
{
new QueryEntity(typeof(DstCatalogKey), typeof(DstCatalog))
}
};
return new CacheClientConfiguration
{
Name = "MLP_CATALOG_PRICE_CACHE",
Backups = 1,
CacheMode = CacheMode.Partitioned,
RebalanceMode = CacheRebalanceMode.Async,
WriteSynchronizationMode = CacheWriteSynchronizationMode.PrimarySync,
PartitionLossPolicy = PartitionLossPolicy.ReadOnlySafe,
DataRegionName = "4GB_Region",
QueryEntities = new[]
{
new QueryEntity(typeof(DstCatalogPriceKey), typeof(DstCatalogPrice))
}
};
--
This message was sent by Atlassian Jira
(v8.3.4#803005)