Ilya Suntsov created IGNITE-11307: ------------------------------------- Summary: SqlNative benchmarks failed with more than 1 client Key: IGNITE-11307 URL: https://issues.apache.org/jira/browse/IGNITE-11307 Project: Ignite Issue Type: Task Components: yardstick Affects Versions: 2.7 Reporter: Ilya Suntsov
I saw the exception below when tried to run NativeSqlInsertDeleteBenchmark, NativeSqlQueryRangeBenchmark, NativeSqlUpdateRangeBenchmark with 4 servers and 8 clients. Looks like we need to use "*create* *table* *if* *not* *exists*" instead of "*create* *table*" {noformat} <19:55:12><main><yardstick> Create table... <19:55:12><main><yardstick> Creating table with schema: CREATE TABLE test_long (id LONG PRIMARY KEY, val LONG) WITH "wrap_value=true"; [2019-02-12 19:55:12,876][INFO ][exchange-worker-#58][GridDhtPartitionsExchangeFuture] Finish exchange future [startVer=AffinityTopologyVersion [topVer=12, minorTopVer=0], resVer=AffinityTopologyVersion [topVer=12, minorTopVer=0], err=null] [2019-02-12 19:55:12,881][INFO ][exchange-worker-#58][GridDhtPartitionsExchangeFuture] Completed partition exchange [localNode=f5594085-054c-492f-9112-301b196ff8b3, exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion [topVer=12, minorTopVer=0],. evt=NODE_JOINED, evtNode=TcpDiscoveryNode [id=0a475055-ad4c-46e4-88e9-ebeba9c846ce, addrs=ArrayList [127.0.0.1, 172.17.0.1, 172.25.1.26], sockAddrs=HashSet [/172.17.0.1:0, /127.0.0.1:0, lab26.gridgain.local/172.25.1.26:0], discPort=0, order=12, intOrder=12, lastExchangeTime=1549990512845, loc=false, ver=2.8.0#20190211-sha1:e59aa879, isClient=true], done=true], topVer=AffinityTopologyVersion [topVer=12, minorTopVer=0]] [2019-02-12 19:55:12,881][INFO ][exchange-worker-#58][GridDhtPartitionsExchangeFuture] Exchange timings [startVer=AffinityTopologyVersion [topVer=12, minorTopVer=0], resVer=AffinityTopologyVersion [topVer=12, minorTopVer=0], stage="Waiting in exchange queue" (0 ms),. stage="Exchange parameters initialization" (0 ms), stage="Determine exchange type" (5 ms), stage="Exchange done" (4 ms), stage="Total time" (9 ms)] [2019-02-12 19:55:12,881][INFO ][exchange-worker-#58][GridDhtPartitionsExchangeFuture] Exchange longest local stages [startVer=AffinityTopologyVersion [topVer=12, minorTopVer=0], resVer=AffinityTopologyVersion [topVer=12, minorTopVer=0]] [2019-02-12 19:55:12,881][INFO ][exchange-worker-#58][time] Finished exchange init [topVer=AffinityTopologyVersion [topVer=12, minorTopVer=0], crd=false] [2019-02-12 19:55:12,882][INFO ][exchange-worker-#58][GridCachePartitionExchangeManager] Skipping rebalancing (no affinity changes) [top=AffinityTopologyVersion [topVer=12, minorTopVer=0], rebTopVer=AffinityTopologyVersion [topVer=-1, minorTopVer=0], evt=NODE_JOINED, e vtNode=0a475055-ad4c-46e4-88e9-ebeba9c846ce, client=true] [2019-02-12 19:55:12,972][INFO ][exchange-worker-#58][time] Started exchange init [topVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], crd=false, evt=DISCOVERY_CUSTOM_EVT, evtNode=da386c99-7b45-4c5b-913b-95eb5b70118f, customEvt=DynamicCacheChangeBatch. [id=5ac22a2e861-fb1a5341-6a6e-4ef8-8a74-072c95cb2e08, reqs=ArrayList [DynamicCacheChangeRequest [cacheName=SQL_PUBLIC_TEST_LONG, hasCfg=true, nodeId=da386c99-7b45-4c5b-913b-95eb5b70118f, clientStartOnly=false, stop=false, destroy=false, disabledAfterStartfalse]], exchangeActions=ExchangeActions [startCaches=[SQL_PUBLIC_TEST_LONG], stopCaches=null, startGrps=[SQL_PUBLIC_TEST_LONG], stopGrps=[], resetParts=null, stateChangeRequest=null], startCaches=false], allowMerge=false] [2019-02-12 19:55:12,986][INFO ][exchange-worker-#58][time] Finished exchange init [topVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], crd=false] [2019-02-12 19:55:13,063][INFO ][sys-#66][GridDhtPartitionsExchangeFuture] Received full message, will finish exchange [node=e301e555-2a31-4bdd-a574-9ec412f4c435, resVer=AffinityTopologyVersion [topVer=12, minorTopVer=1]] [2019-02-12 19:55:13,064][INFO ][sys-#66][GridDhtPartitionsExchangeFuture] Finish exchange future [startVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], resVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], err=null] [2019-02-12 19:55:13,075][INFO ][sys-#66][GridDhtPartitionsExchangeFuture] Completed partition exchange [localNode=f5594085-054c-492f-9112-301b196ff8b3, exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], evt=DISCOVERY_CUSTOM_EVT, evtNode=TcpDiscoveryNode. [id=da386c99-7b45-4c5b-913b-95eb5b70118f, addrs=ArrayList [127.0.0.1, 172.17.0.1, 172.25.1.13], sockAddrs=HashSet [/172.17.0.1:0, lab13.gridgain.local/172.25.1.13:0, /127.0.0.1:0], discPort=0, order=7, intOrder=7, lastExchangeTime=1549990507545, loc=false, ver=2.8.0#20190211-sha1:e59aa879, isClient=true], done=true], topVer=AffinityTopologyVersion [topVer=12, minorTopVer=1]] [2019-02-12 19:55:13,075][INFO ][sys-#66][GridDhtPartitionsExchangeFuture] Exchange timings [startVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], resVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters initialization" (1 ms),. stage="Update caches registry" (7 ms), stage="Start caches" (4 ms), stage="Affinity initialization on cache group start" (2 ms), stage="Determine exchange type" (0 ms), stage="Waiting for Full message" (76 ms), stage="Affinity recalculation" (0 ms), stage="Full map updating" (1 ms), stage="Exchange done". (10 ms), stage="Total time" (101 ms)] [2019-02-12 19:55:13,075][INFO ][sys-#66][GridDhtPartitionsExchangeFuture] Exchange longest local stages [startVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], resVer=AffinityTopologyVersion [topVer=12, minorTopVer=1], stage="Affinity initialization on cache group start [grp=SQL_PUBLIC_TEST_LONG]" (2 ms) (parent=Affinity initialization on cache group start)] [2019-02-12 19:55:13,075][INFO ][exchange-worker-#58][GridCachePartitionExchangeManager] Skipping rebalancing (no affinity changes) [top=AffinityTopologyVersion [topVer=12, minorTopVer=1], rebTopVer=AffinityTopologyVersion [topVer=-1, minorTopVer=0], evt=DISCOVERY_CUSTOM_EVT,. evtNode=da386c99-7b45-4c5b-913b-95eb5b70118f, client=true] [2019-02-12 19:55:13,083][INFO ][exchange-worker-#58][GridCacheProcessor] Started cache [name=SQL_PUBLIC_TEST_LONG, id=109160996, dataRegionName=null, mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false] [2019-02-12 19:55:13,090][INFO ][grid-nio-worker-tcp-comm-1-#41][TcpCommunicationSpi] Established outgoing communication connection [locAddr=/172.25.1.11:45096, rmtAddr=lab27.gridgain.local/172.25.1.27:47100] [2019-02-12 19:55:13,117][INFO ][exchange-worker-#58][GridCacheProcessor] Finish proxy initialization, cacheName=SQL_PUBLIC_TEST_LONG, localNodeId=f5594085-054c-492f-9112-301b196ff8b3 [19:55:13] (err) DDL operation failureSchemaOperationException [code=3, msg=Table already exists: TEST_LONG] <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.dynamicTableCreate(GridQueryProcessor.java:1589) <------>at org.apache.ignite.internal.processors.query.h2.CommandProcessor.runCommandH2(CommandProcessor.java:438) <------>at org.apache.ignite.internal.processors.query.h2.CommandProcessor.runCommand(CommandProcessor.java:196) <------>at org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeCommand(IgniteH2Indexing.java:1378) <------>at org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.querySqlFields(IgniteH2Indexing.java:1442) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor$3.applyx(GridQueryProcessor.java:2234) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor$3.applyx(GridQueryProcessor.java:2229) <------>at org.apache.ignite.internal.util.lang.IgniteOutClosureX.apply(IgniteOutClosureX.java:36) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.executeQuery(GridQueryProcessor.java:2747) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.lambda$querySqlFields$1(GridQueryProcessor.java:2252) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.executeQuerySafe(GridQueryProcessor.java:2272) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2224) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2151) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2124) <------>at org.apache.ignite.yardstick.jdbc.JdbcUtils.fillData(JdbcUtils.java:55) <------>at org.apache.ignite.yardstick.jdbc.AbstractNativeBenchmark.setUp(AbstractNativeBenchmark.java:35) <------>at org.apache.ignite.yardstick.jdbc.NativeSqlQueryRangeBenchmark.setUp(NativeSqlQueryRangeBenchmark.java:37) <------>at org.yardstickframework.BenchmarkDriverStartUp.main(BenchmarkDriverStartUp.java:130) <19:55:13><main><yardstick> Failed to set up benchmark drivers (will shutdown and exit). class org.apache.ignite.internal.processors.query.IgniteSQLException: Table already exists: TEST_LONG <------>at org.apache.ignite.internal.processors.query.h2.CommandProcessor.convert(CommandProcessor.java:678) <------>at org.apache.ignite.internal.processors.query.h2.CommandProcessor.runCommandH2(CommandProcessor.java:582) <------>at org.apache.ignite.internal.processors.query.h2.CommandProcessor.runCommand(CommandProcessor.java:196) <------>at org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.executeCommand(IgniteH2Indexing.java:1378) <------>at org.apache.ignite.internal.processors.query.h2.IgniteH2Indexing.querySqlFields(IgniteH2Indexing.java:1442) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor$3.applyx(GridQueryProcessor.java:2234) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor$3.applyx(GridQueryProcessor.java:2229) <------>at org.apache.ignite.internal.util.lang.IgniteOutClosureX.apply(IgniteOutClosureX.java:36) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.executeQuery(GridQueryProcessor.java:2747) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.lambda$querySqlFields$1(GridQueryProcessor.java:2252) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.executeQuerySafe(GridQueryProcessor.java:2272) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2224) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2151) <------>at org.apache.ignite.internal.processors.query.GridQueryProcessor.querySqlFields(GridQueryProcessor.java:2124) <------>at org.apache.ignite.yardstick.jdbc.JdbcUtils.fillData(JdbcUtils.java:55) <------>at org.apache.ignite.yardstick.jdbc.AbstractNativeBenchmark.setUp(AbstractNativeBenchmark.java:35) <------>at org.apache.ignite.yardstick.jdbc.NativeSqlQueryRangeBenchmark.setUp(NativeSqlQueryRangeBenchmark.java:37) <------>at org.yardstickframework.BenchmarkDriverStartUp.main(BenchmarkDriverStartUp.java:130) {noformat} -- This message was sent by Atlassian JIRA (v7.6.3#76005)