Uploaded image for project: 'Ignite'
  1. Ignite
  2. IGNITE-11307

SqlNative benchmarks failed with more than 1 client

    XMLWordPrintableJSON

Details

    • Task
    • Status: Open
    • Major
    • Resolution: Unresolved
    • 2.7
    • None
    • yardstick
    • None
    • Docs Required

    Description

      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"

      <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)
      
      

      Attachments

        Activity

          People

            Unassigned Unassigned
            ustas Ilya Suntsov
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

            Dates

              Created:
              Updated: