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)
