LoginSignup
16
9

Delete article

Deleted articles cannot be recovered.

Draft of this article would be also deleted.

Are you sure you want to delete this article?

More than 5 years have passed since last update.

MySQL CasualAdvent Calendar 2017

Day 10
@hmatsu47

MySQL Connector/Jでプロパティをあれこれ変えてベンチマークその1：バッチ編

Last updated at Posted at 2017-12-09

**MySQL Casual Advent Calendar**初参戦記事、その1です。

昨日は@atsuizoさんの SELECT文をタイムアウト強制終了させる「MAX_EXECUTION_TIME」使ってる？ でした。

私の記事は内容のわりに長くなりそうなので、読むのにツラくならないよう、3週に分けてお送りします。

0. 検証環境

Casualにやる、ということで、サーバに持って行くのをやめて、PCのIDE上でさらっと実行してみます。
そのため、環境が変わると結果がかなり変わるはずです。

  • PCのスペックなど

pc_spec.png

  • IDE : Eclipse 4.7.1a（pleiades日本語化）
  • MySQL Community Server : Windows（x64）版 5.7.20
  • MySQL Connector/J : 5.1.44（Mavenリポジトリより取得）

　※執筆時点で1つ古いヤツですが…。

  • my.ini
my.ini
[mysqld]
basedir = C:\\dev\\mysql-5.7.20-winx64
datadir = C:\\dev\\mysql-5.7.20-winx64\\data
tmpdir = C:\\dev\\mysql-5.7.20-winx64\\tmp

max_connections = 30

explicit_defaults_for_timestamp = 1

innodb_buffer_pool_size = 4G
innodb_log_file_size = 1G
innodb_status_output = 1
innodb_status_output_locks = 1

character_set_server = utf8
collation_server = utf8_general_ci

general_log = 1
general_log_file = C:\\dev\\mysql-5.7.20-winx64\\logs\\general_query_all.log
log_error = C:\\dev\\mysql-5.7.20-winx64\\logs\\mysqld_error.log
log_queries_not_using_indexes = 1
log_slow_admin_statements = 1
log_syslog = 0
log_timestamps = SYSTEM
long_query_time = 3
slow_query_log = 1
slow_query_log_file = C:\\dev\\mysql-5.7.20-winx64\\logs\\slow_query.log

[mysql]
default-character-set = utf8
show-warnings

prompt = "\u@\h [\d] > "

　※Windows版に慣れていないので、MySQL Community Server 5.7.19 - Installing on Windows 10（@rubytomato@githubさん） を参考にしました。ありがとうございます。

1. 検証内容

MySQL Connector/Jで、性能に関わるプロパティにはいくつかあります。

　※主に、ページ中盤あたりからの Performance Extensions. を参照。

このうち、複数レコードを一括INSERTする処理がある場合に真っ先に思い浮かぶのが rewriteBatchedStatements だと思います。

でも取り上げられている定番項目ですが、

  • rewriteBatchedStatements を有効にし忘れた場合、どうなる？
  • 先のSH2さんの記事では**「100レコードずつINSERTし1,000レコード毎にCOMMIT」**しているが、この単位を変えるとどうなる？
  • 複数スレッドで実行した場合の変化は？

の3点について検証してみます。

2. 検証結果

検証コードやDB・テーブル定義等は最後のほうに掲載します。
Java検証コード実行時の引数に、

  • 非バッチINSERT（VALUES連結なし）スレッドの実行数
  • バッチINSERT（VALUES連結あり）スレッドの実行数
  • INSERT行数
  • バッチINSERT時のVALUES連結単位（行数）
  • COMMIT単位（行数）
  • MySQL Connector/J URLオプションパラメータ（プロパティを指定）

を指定する形で、「非バッチINSERTスレッド→バッチINSERTスレッド」の順に、開始を1秒ずつ遅らせながらスレッドを実行していき、各スレッドの所要時間を計測しました。

※表中の値（ミリ秒）は「5回実行したうちの最大値と最小値を除いた平均値」を示しています。

2-1. rewriteBatchedStatements を有効にし忘れた場合、どうなる？

これ、身近なところで実際にあった（やっちまった）事例の1つです。

  • バッチINSERT（addBatch→executeBatch）で rewriteBatchedStatements 無効時

を、

  • 非バッチINSERT（execute()）時
  • バッチINSERT（addBatch()→executeBatch()）で rewriteBatchedStatements 有効時

と比較します。

なお、INSERT行数は20万行、バッチINSERTの単位は100行、COMMIT単位は1,000行で固定します。1スレッドでの実行です。

検証パターン 所要時間 (ms)
バッチ・rewriteBatchedStatements 無効 49,115
非バッチ 84,231
バッチ・rewriteBatchedStatements 有効 19,845

rewriteBatchedStatements 有効の倍以上の時間が掛かる一方、非バッチよりは速いため、**「rewriteBatchedStatements の指定忘れに気づかない」**という落とし穴があります（体験談）。気を付けましょう。

2-2. INSERT・COMMIT単位を変えるとどうなる？

  • バッチ**INSERT100行単位／COMMIT1,000行**単位
  • バッチ**INSERT1,000行単位／COMMIT1,000行**単位
  • バッチ**INSERT1,000行単位／COMMIT10,000行**単位

の3パターンで実行します（INSERT行数は20万行、rewriteBatchedStatements は有効）。

検証パターン 所要時間 (ms)
バッチ100／COMMIT1,000 19,845
バッチ1,000／COMMIT1,000 4,464
バッチ1,000／COMMIT10,000 3,385

SH2さんの記事では**「増やしても伸びない」旨の記述がありましたが、さすがに時代が変わったせいか、もっと伸びるようです（INSERTする内容にもよると思いますが）。
但し、書き替え行数の単位が大きくなるとHeapメモリ不足**がちらついてきますので、その点についてはご注意を。

また、INSERTだけでなくUPDATEやDELETEが絡む場合はロックによる速度低下やデッドロックにも注意、です。

2-3. 複数スレッドで実行した場合の変化は？

1スレッドだけ高速になっても、他のスレッドに影響が出ては…ということで試してみました。
まずは4スレッド並行で、バッチ：非バッチの比率を変えながら検証してみます。

※スレッドあたりINSERT行数20万行／バッチINSERT1,000行単位／COMMIT10,000行単位／rewriteBatchedStatements 有効で。

検証パターン バッチ所要時間 (ms) 非バッチ所要時間 (ms)
バッチ1：非バッチ3 6,974 38,753
バッチ2：非バッチ2 5,814 35,005
バッチ3：非バッチ1 5,131 42,453

バッチINSERTスレッドについては、バッチが増える＝非バッチが減るほど高速化しています。
一方、非バッチINSERTスレッドは、スレッド数がバッチ＞非バッチとなった時に遅くなっています。

次に、8スレッド並行で検証してみます（INSERT行数等は先ほどと同じ）。

検証パターン バッチ所要時間 (ms) 非バッチ所要時間 (ms)
バッチ1：非バッチ7 22,398 85,643
バッチ3：非バッチ5 15,025 64,833
バッチ5：非バッチ3 11,508 45,332
バッチ7：非バッチ1 7,926 47,137

バッチINSERTスレッドについては、先ほどと同様にバッチが増える＝非バッチが減るほど高速化しています。
非バッチINSERTスレッドは、スレッド数が1のときだけ遅くなっています。
但し、落ち込みはわずかです。

3. まとめ

  • addBatch()＋executeBatch() するときは rewriteBatchedStatements=true を忘れずに
  • メモリ不足などに気を付ける必要はあるが、addBatch()＋executeBatch() は（更新系がINSERTのみの場合）ある程度他のスレッドのことを気にせずに積極的に使ってしまってもいいかもしれない

次回（12/17）の記事では、その他のプロパティ（主に接続時の環境確認などのために発行されるクエリの数を抑制するためのもの）を検証してみます。

なお、ちょっとだけフライングで結果を示すと、

  • rewriteBatchedStatements=true&characterEncoding=utf8&characterSetResults=utf8&alwaysSendSetIsolation=false&elideSetAutoCommits=true&useLocalSessionState=true&cacheServerConfiguration=true

としたときの結果（8スレッド／バッチ7：非バッチ1、プロパティ以外は最後のテストケースと同条件）は、**「バッチ7,581ms／非バッチ33,145ms」**でした。

明日は@tom--boさんの MySQL(innodb)の分離レベルごとのanomalyについて実験した です。

4. 検証に使ったコードなど

pom.xml
<project xmlns="http://maven.apache.org/POM/4.0.0" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://maven.apache.org/POM/4.0.0 http://maven.apache.org/xsd/maven-4.0.0.xsd">
  <modelVersion>4.0.0</modelVersion>
  <groupId>ConJTest</groupId>
  <artifactId>ConJTest</artifactId>
  <version>0.0.1-SNAPSHOT</version>
  <build>
    <sourceDirectory>src</sourceDirectory>
    <plugins>
      <plugin>
        <artifactId>maven-compiler-plugin</artifactId>
        <version>3.7.0</version>
        <configuration>
          <source>1.8</source>
          <target>1.8</target>
        </configuration>
      </plugin>
    </plugins>
  </build>
  <dependencies>
  	<dependency>
  		<groupId>mysql</groupId>
  		<artifactId>mysql-connector-java</artifactId>
  		<version>5.1.44</version>
  	</dependency>
  </dependencies>
</project>
DbConnection.java
package site.hmatsu47.conjtest;
import java.sql.Connection;
import java.sql.DriverManager;
import java.sql.SQLException;

public class DbConnection {

	private static final String DRIVER_NAME	= "com.mysql.jdbc.Driver";
	private static final String JDBC_USER	= "testuser";
	private static final String JDBC_PASS	= "T35+U53r";

	public Connection getConnectionForTest(String url) throws ClassNotFoundException, SQLException {
		Class.forName(DRIVER_NAME);
		return DriverManager.getConnection(url, JDBC_USER, JDBC_PASS);
	}
}
DbInsert.java
package site.hmatsu47.conjtest;
import java.sql.Connection;
import java.sql.PreparedStatement;
import java.sql.SQLException;

public class DbInsert {

	private static final String JDBC_URL	= "jdbc:mysql://testdb:3306/insert_test";
	private static final String TEST_MEMO	= "1234567890１２３４５６７８９０1234567890１２３４５６７８９０1234567890１２３４５６７８９０1234567890１２３４５６７８９０1234567890１２３４５６７８９０";

	public void batchInsert(int totalline, int batchline, int commitline, String option) {

		try (
			Connection 			con		= new DbConnection().getConnectionForTest(JDBC_URL + option);
			PreparedStatement	psmt	= con.prepareStatement("INSERT INTO insert_test.insert_test (memo) VALUES (?)")
		) {
			con.setAutoCommit(false);
			psmt.clearBatch();

			for (int i = 1; i <= totalline; i++) {
				psmt.setString(1, TEST_MEMO);
				psmt.addBatch();

				if ((i % batchline == 0) || (i == totalline)) {
					psmt.executeBatch();
					psmt.clearBatch();

					if ((i % commitline == 0) || (i == totalline)) {
						con.commit();
					}
				}
			}
		} catch (ClassNotFoundException e) {
			System.out.println("[Error] Driver not found.");
			e.printStackTrace();
		} catch (SQLException e) {
			System.out.println("[Error] Invalid DB access.");
			e.printStackTrace();
		}
	}

	public void simpleInsert(int totalline, int commitline, String option) {

		try (
			Connection 			con		= new DbConnection().getConnectionForTest(JDBC_URL + option);
			PreparedStatement	psmt	= con.prepareStatement("INSERT INTO insert_test.insert_test (memo) VALUES (?)")
		) {
			con.setAutoCommit(false);

			for (int i = 1; i <= totalline; i++) {
				psmt.setString(1, TEST_MEMO);
				psmt.execute();

				if ((i % commitline == 0) || (i == totalline)) {
					con.commit();
				}
			}
		} catch (ClassNotFoundException e) {
			System.out.println("[Error] Driver not found.");
			e.printStackTrace();
		} catch (SQLException e) {
			System.out.println("[Error] Invalid DB access.");
			e.printStackTrace();
		}
	}
}
SimpleInsert.java
package site.hmatsu47.conjtest;

public class SimpleInsert extends Thread {

	private int 	totalline	= 0;
	private int 	commitline	= 0;
	private String option;
	private String title;

	public SimpleInsert(int totalline, int commitline, String option, String title) {
		this.totalline	= totalline;
		this.commitline	= commitline;
		this.option		= option;
		this.title		= title;
	}

	public void run() {
		long	starttime	= System.nanoTime();

		new DbInsert().simpleInsert(totalline, commitline, option);

		long	endtime	= System.nanoTime();

		System.out.println("[Simple] " + title + " : " + String.valueOf((endtime - starttime) / (1000 * 1000)) + " msec.");
	}
}
BatchInsert.java
package site.hmatsu47.conjtest;

public class BatchInsert extends Thread {

	private int 	totalline	= 0;
	private int 	batchline	= 0;
	private int 	commitline	= 0;
	private String option;
	private String title;

	public BatchInsert(int totalline, int batchline, int commitline, String option, String title) {
		this.totalline	= totalline;
		this.batchline	= batchline;
		this.commitline	= commitline;
		this.option		= option;
		this.title		= title;
	}

	public void run() {
		long	starttime	= System.nanoTime();

		new DbInsert().batchInsert(totalline, batchline, commitline, option);

		long	endtime	= System.nanoTime();

		System.out.println("[Batch]  " + title + " : " + String.valueOf((endtime - starttime) / (1000 * 1000)) + " msec.");
	}
}
Main.java
package site.hmatsu47.conjtest;

public class Main {

	private static final int		DEFAULT_SIMPLE_THREAD	= 0;
	private static final int		DEFAULT_BATCH_THREAD		= 1;
	private static final int		DEFAULT_TOTAL_LINE		= 1000000;
	private static final int		DEFAULT_BATCH_LINE		= 100;
	private static final int		DEFAULT_COMMITL_LINE		= 1000;
	private static final String	DEFAULT_JDBC_OPTION		= "";

	public static void main(String args[]) {
		try {
			final int		simplethread	= (args.length < 1 ? DEFAULT_SIMPLE_THREAD : Integer.valueOf(args[0]).intValue());
			final int		batchthread	= (args.length < 2 ? DEFAULT_BATCH_THREAD : Integer.valueOf(args[1]).intValue());
			final int		totalline		= (args.length < 3 ? DEFAULT_TOTAL_LINE : Integer.valueOf(args[2]).intValue());
			final int		batchline		= (args.length < 4 ? DEFAULT_BATCH_LINE : Integer.valueOf(args[3]).intValue());
			final int		commitline		= (args.length < 5 ? DEFAULT_COMMITL_LINE : Integer.valueOf(args[4]).intValue());
			final String	option			= (args.length < 6 ? DEFAULT_JDBC_OPTION : args[5]);

			for (int i = 1; i <= simplethread; i++) {
				SimpleInsert si = new SimpleInsert(totalline, commitline, option, String.valueOf(i));
				si.start();
				Thread.sleep(1000);
			}

			for (int i = 1; i <= batchthread; i++) {
				BatchInsert bi = new BatchInsert(totalline, batchline, commitline, option, String.valueOf(i));
				bi.start();
				Thread.sleep(1000);
			}
		}
		catch (Exception e) {
			System.out.println("[Error]");
			e.printStackTrace();
		}
	}
}
ユーザ・DB・テーブル定義
root@localhost [(none)] > CREATE USER 'testuser'@'localhost' IDENTIFIED BY 'T35+U53r';
Query OK, 0 rows affected (0.00 sec)

root@localhost [(none)] > GRANT SELECT, INSERT, UPDATE, DELETE ON *.* TO 'testuser'@'localhost';
Query OK, 0 rows affected (0.00 sec)

root@localhost [(none)] > CREATE DATABASE insert_test;
Query OK, 1 row affected (0.01 sec)

root@localhost [(none)] > USE insert_test;
Database changed

root@localhost [insert_test] > CREATE TABLE insert_test (id INT UNSIGNED PRIMARY KEY AUTO_INCREMENT, memo VARCHAR(200)) ENGINE InnoDB DEFAULT CHARSET=utf8mb4;
Query OK, 0 rows affected (0.13 sec)
16
9
0

Register as a new user and use Qiita more conveniently

  1. You get articles that match your needs
  2. You can efficiently read back useful information
  3. You can use dark theme
What you can do with signing up
Sign upLogin
16
9

Delete article

Deleted articles cannot be recovered.

Draft of this article would be also deleted.

Are you sure you want to delete this article?