You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@ignite.apache.org by "Ilya Suntsov (JIRA)" <ji...@apache.org> on 2019/02/13 10:10:00 UTC

[jira] [Created] (IGNITE-11307) SqlNative benchmarks failed with more than 1 client

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)