http://cafe.rootcenter.com/130078704068

mysql replication 진행도중 slave서버의  error-log 파일내용을 확인한 후 대처법

 에러유형-4) - 2010년 5월 3일 추가

100503  8:41:05 [ERROR] Error reading packet from server: log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master ( server_errno=1236)
100503  8:41:05 [ERROR] Got fatal error 1236: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master' from master when reading data from binary log
100503  8:41:05 [Note] Slave I/O thread exiting, read up to log 'binlog.021419', position 196980

 

해결1)  master서버의 max_allowed_packet 값이 작을 경우 문제가 발생할수 있다.

          - my.cnf파일을 수정해 준후 데몬을 재시작한다.

          - 데몬 재시작이 힘들경우 쉘 상에서 값을 변경한다.

[root@db11 slave_log]# mysql -u root -p
Enter password:
Welcome to the MySQL monitor.  Commands end with ; or g.
Your MySQL connection id is 11443092
Server version: 5.0.45-log MySQL Community Server (GPL)

Type 'help;' or 'h' for help. Type 'c' to clear the buffer.

mysql> SET GLOBAL max_allowed_packet = 1024*1024*100;

mysql> SET SESSION max_allowed_packet = 1024*1024*100;

해결2) master서버의 max_allowed_packet값이 충분히 큰데 위와 같은 오류가 발생할 경우..

        - 먼저 master서버의 binlog파일을 확인해본다.

[root@db11 slave_log]#mysqlbinlog  binlog.021419 | more

--중략--
# at 196800
#100503  7:16:31 server id 1  end_log_pos 196827  Xid = 3282913394
COMMIT/*!*/;
# at 196827
#100503  7:16:31 server id 1  end_log_pos 138  Query thread_id=235254463 exec_time=0 error_code=0
SET TIMESTAMP=1272838591/*!*/;
Update tablename Set count0/*!*/;
# at 196965
#100503  7:16:31 server id 1  end_log_pos 196992  Xid = 3282913395
COMMIT/*!*/;
# at 196992
#100503  7:16:31 server id 1  end_log_pos 133  Query thread_id=232937265 exec_time=0 error_code=0
SET TIMESTAMP=1272838591/*!*/;
Update tablename Set size = 295976960/*!*/;
# at 197125

        - 확인 결과 196980 위치가 존재하지 않는다.

        - master_log_pos를 다음값으로 변경하면 끝.

 

[root@db11 slave_log]# mysql -u root -p
Enter password:
Welcome to the MySQL monitor.  Commands end with ; or g.
Your MySQL connection id is 11443092
Server version: 5.0.45-log MySQL Community Server (GPL)

Type 'help;' or 'h' for help. Type 'c' to clear the buffer.

mysql> stop slave;

mysql> change master to master_log_pos = 196992;

mysql> start slave;

 

에러유형-3) 2010.01.30 추가

100130 12:57:07 [ERROR] Slave: Error ''SQL 구문에 오류가 있습니다.' 에러 같읍니다. ('' 명령어 라인 1)' on query. Default database: 'dbname'. Query: 'UPDATE PARTNER_CNT SET .... WHERE ...', Error_code: 1064
100130 12:57:07 [ERROR] Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'binlog.007704' position 70234030

해결) 키값이 중복되어 슬레이브에서 쿼리가 실행되지 못하고 있는것이다.

        정상적인 리플리케이션에서는 문제가 없지만 갑작스런 서버 다운, 네트워크 단절이 발생했을 경우 위와  같은 오류가 발생한다.

        아래 명령을 입력하여 해결 할 수 있다.      

[root@db11 slave_log]# mysql -u root -p
Enter password:
Welcome to the MySQL monitor.  Commands end with ; or g.
Your MySQL connection id is 11443092
Server version: 5.0.45-log MySQL Community Server (GPL)

Type 'help;' or 'h' for help. Type 'c' to clear the buffer.

mysql> stop slave;

mysql> SET GLOBAL SQL_SLAVE_SKIP_COUNTER=1;

mysql> start slave; 

 

 

 에러유형-1)

100122  0:22:44 [ERROR] Error reading packet from server: Lost connection to MySQL server during query ( server_errno=2013)
100122  0:22:44 [Note] Slave I/O thread: Failed reading log event, reconnecting to retry, log 'binlog.017528' position 90025355
100122  0:22:48 [Note] Slave: connected to master 'xxxxxx@아이피',replication resumed in log 'binlog.017528' at position 90025355

 

 

해결) master서버와의 연결이 끊어지면 위와 같은 오류 메세지를 출력하고 리플리케이션이 중지된다.

        master서버가 정상적으로 돌아왔다면 slave 서버에 mysql을 접속한후 아래 명령을 입력한다.

[root@db11 slave_log]# mysql -u root -p
Enter password:
Welcome to the MySQL monitor.  Commands end with ; or g.
Your MySQL connection id is 11443092
Server version: 5.0.45-log MySQL Community Server (GPL)

Type 'help;' or 'h' for help. Type 'c' to clear the buffer.

mysql> stop slave;

mysql> start slave;

 

에러유형-2)

 100122  1:35:56 [Note] Slave I/O thread killed while reading event
100122  1:35:56 [Note] Slave I/O thread exiting, read up to log 'binlog.017530', position 33231841
100122  1:36:05 [Note] Slave SQL thread initialized, starting replication in log 'binlog.017528' at position 195, relay log '/db/slave_log/relay_log.002684' position: 90005865
100122  1:36:05 [ERROR] Error in Log_event::read_log_event(): 'Event too big', data_len: 811157539, event_type: 0
100122  1:36:05 [ERROR] Error reading relay log event: slave SQL thread aborted because of I/O error
100122  1:36:05 [ERROR] Slave: Could not parse relay log event entry. The possible reasons are: the master's binary log is corrupted (you can check this by running 'mysqlbinlog' on the binary log), the slave's relay log is corrupted (you can check this by running 'mysqlbinlog' on the relay log), a network problem, or a bug in the master's or slave's MySQL code. If you want to check the master's binary log or slave's relay log, you will be able to know their names by issuing 'SHOW SLAVE STATUS' on this slave. Error_code: 0
100122  1:36:05 [ERROR] Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'binlog.017528' position 195
100122  1:36:05 [Note] Slave I/O thread: connected to master 'xxxx@아이피',  replication started in log 'binlog.017530' at position 33231841

 

해결) slave서버가 갑작스럽게 다운 되었을때 로그 파일이 깨져서 위와 같은 오류가 발생한다.

        위 에러로그에서는 relay_log.002684 로그에서 중지 되었다.

        slave_log가 쌓이는 디렉토리( /db/slave_log/)에  relay_log.002685 파일이 있는지 확인후 아래 명령을 입력한다.

 

[root@db11 slave_log]# mysql -u root -p
Enter password:
Welcome to the MySQL monitor.  Commands end with ; or g.
Your MySQL connection id is 11443092
Server version: 5.0.45-log MySQL Community Server (GPL)

Type 'help;' or 'h' for help. Type 'c' to clear the buffer.

mysql> stop slave;

mysql> CHANGE MASTER TO relay_log_file='/db/slave_log/relay_log.002685', relay_log_pos=0;

mysql> start slave;

'Database > Mysql' 카테고리의 다른 글

프로시저의 예  (0) 2011.01.26
MySQL DUMP 백업 및 복원  (0) 2010.09.20
load data  (0) 2010.07.19
LOAD XML Syntax [v5.5]  (0) 2010.04.07
MySQL 사용 중 발생할 수 있는 대기 현상 장애의 원인과 대처  (0) 2009.12.30

호~ 괜찮은데... xml loader...

LOAD XML [LOW_PRIORITY | CONCURRENT] [LOCAL] INFILE 'file_name'
[REPLACE | IGNORE]
INTO TABLE [db_name.]tbl_name
[CHARACTER SET charset_name]
[ROWS IDENTIFIED BY '<tagname>']
[IGNORE number [LINES | ROWS]]
[(column_or_user_var,...)]
[SET col_name = expr,...]

* 명령어들
shell> mysql --xml -e 'SELECT * FROM mytable' > file.xml

mysql> LOAD XML LOCAL INFILE 'person-dump.xml' INTO TABLE person2;

mysql> LOAD XML LOCAL INFILE 'address.xml'
    ->   INTO TABLE person
    ->   ROWS IDENTIFIED BY '<person>';

http://dev.mysql.com/doc/refman/5.5/en/load-xml.html

'Database > Mysql' 카테고리의 다른 글

프로시저의 예  (0) 2011.01.26
MySQL DUMP 백업 및 복원  (0) 2010.09.20
load data  (0) 2010.07.19
mysql replication error  (0) 2010.06.28
MySQL 사용 중 발생할 수 있는 대기 현상 장애의 원인과 대처  (0) 2009.12.30

MySQL 사용 중 발생할 수 있는 대기 현상 장애의 원인과 대처

 다음의 경우는 필자가 몇 년 동안 MySQL을 운영하면서 겪었던 장애 현상 중 대기 현상을 유발해 장애를 발생시키는 원인들을 정리한 것이다.

 - DDL 작업 진행 시
- Table Flush 진행 시
- mysqldump를 이용해 백업이 진행되는 경우
- Table Lock을 사용하는 경우
- Serializable Isolation Level을 사용하는 경우

 위의 다섯 가지 경우를 하나씩 살펴보자. 참고로 테스트에 사용된 MySQL 버전은 MySQL Community 버전 5.0.67로, MyISAM과 InnoDB를 대부분 사용했기 때문에 여기 나온 내용은 그 사항에 특화된 것임을 밝힌다.

 DDL 작업 진행 시 

사실 MySQL을 오랫동안 운영해본 사람이라면 MySQL에서의 DDL 작업이 쉬운 작업이 아님을 대부분 알고 있을 것이라 생각한다. MySQL에서 DDL 작업은 온라인 작업이 아니기 때문이다. 특히 alter 작업의 경우 서비스에 많은 영향을 미치기 때문에 서비스 중인 서버에 대해 작업을 시도하고자 하는 경우 서비스를 중단해야 할 수도 있다.  

● DDL 작업이란?
DDL은 Data Definition Language의 약자로서 데이터 정의 언어를 뜻한다. 이것은 데이터를 저장하는 데이터베이스의 스키마를 기술하는 언어를 말하는 것으로서 흔히 create table, alter table, create index와 같은 명령어를 의미한다.

특히 MySQL에서 이와 같은 DDL 작업은 온라인 작업이 불가능하다. 즉, 간단히 얘기해서 alter table 작업 시 그 테이블에 대한 데이터 변경 작업이 불가능하다. 그렇기 때문에 DDL 작업을 진행 중인 동안에는 다른 Connection에서 실행하는 데이터 변경 작업은 진행되지 않고 대기하게 된다.  

● 대기 유발 상황
그럼 어떤 경우에 대기가 유발되는지 확인해 보자.  

shrtcmt 테이블에 다음과 같은 alter table 명령을 실행한다.
mysql> alter table shrtcmt engine = MyISAM;

다른 커넥션을 통해 shrtcmt 테이블에 데이터 변경을 시도하는 sql 문을 실행한다.
mysql> delete from shrtcmt where shrtcmtseq < 6;

show processlist 구문을 통해 현재 상태를 확인한다.
mysql> show processlist; 

● 대기 유발 예제
위와 같은 예제는 다음과 같이 확인할 수 있다.  

<화면 1> DDL 실행에 따른 Locked 현상 1 

<그림 1> DDL 실행에 따른 Locked 현상 2 

<화면 1>에서 보면 69번 Connection에서 진행하고 있는 DDL 작업(alter 작업) 이후 실행된 delete 문(Connection 70번 작업)이 Locked되어 진행을 못하고 있음을 확인할 수 있다.  

● 해결 방법
먼저 현재 상황을 해결하기 위해서는 DDL 작업이 끝나기를 기다리는 수밖에 없다. 만약 70번 Connection이 select 문을 실행하는 경우라면 실행에 문제가 없었을 것이다. DDL 문은 데이터 변경만 불가능하기 때문이다.

원래 이와 같은 작업은 작업 전에 작업시간 및 작업 일정에 대한 논의가 충분히 이루어졌어야 하는 부분이다. alter table과 같은 DDL 작업 시에는 먼저 테스트를 통해 어느 정도의 시간이 필요한지 확인하고, 그 시간을 토대로 서비스 Down Time을 가져야 하는 것인지에 대한 충분한 논의 후에 작업에 들어가도록 해야 한다. 

Table Flush 진행 시  

Table Flush는 일반적으로 생각하기에 대기 현상과 전혀 관계없다고 생각할 수 있다. 하지만 실제 DB의 운영 시 대기 현상을 발생시킬 수도 있다. 그러면 왜 대기 현상이 Table Flush로 인해 발생할 수 있는 것인가? 이것은 Table Flush가 어떤 일을 하는지 파악하면 확인할 수 있다.  

● Table Flush의 작업 내용
Table Flush는 현재 MySQL에 의해 사용 중인 해당 테이블을 닫고, 다시 여는 작업을 진행하는 명령어로서 메모리에 로딩된 테이블에 관련된 cache 영역을 clear하고, reload할 때 사용된다. 그리고 추가적으로 query cache에 있는 데이터를 전부 reset하는 기능을 수행한다. 다시 정리하면 다음과 같다.

- Flush 해당 테이블의 reopen 작업
- query cache의 reset 작업 

● 대기 유발 상황
그러면 어떠한 경우에 이러한 대기 현상이 유발되는지 확인해 보자.

shrtcmt 테이블에 다음과 같은 쿼리를 실행한다.
mysql> select * from shrtcmt;

다른 커넥션을 통해 Table Flush를 시도한다.
mysql> flush tables;

shrtcmt를 select하는 다른 쿼리를 또 다른 커넥션을 통해 실행한다.
mysql> select * from shrtcmt limit 0,2;

show processlist;를 실행해 상태를 확인한다.
mysql> show processlist; 

● 대기 유발 예제
그럼 어떠한 경우에 발생되는지 다음의 예제를 통해 다시 확인해 보자.  

<화면 2> Table Flush로 인한 대기 현상 1 

<그림 2> Table Flush로 인한 대기 현상 2
우선 맨 처음 실행된 7번 Connection을 보자. 이 Connection에서는 현재 1분 이상 진행되는 select 쿼리가 진행 중이다. 이 쿼리로 인해 현재 shrtcmt 테이블은 계속 사용 중 상태가 되는 것이다. 그 다음 실행된 8번 Connection에서는 “flush tables” 명령이 실행 중임을 확인할 수 있다. 그리고 마지막으로 실행된 2번 Connection에서도 select 문이 실행 중인데 이 쿼리는 현재 상태가 “waiting for table”로 테이블을 사용하기 위해 기다리는 중이라고 나타난다. 2번 Connection에서 실행 중인 쿼리는 현재 7번 Connection에서 사용 중인 테이블과 같은 테이블에서 데이터를 추출하기를 기다리고 있는 상태이다.  

여기에서 “show processlist”를 통해 확인해 보면 State 항목에 Locked라는 구문은 보이지 않는다. 하지만 <화면 2>와 같이 7번 Connection의 select 쿼리로 인해, 8번 Connection의 “flush tables”가 대기되고, 그로 인해 그 이후에 실행된 2번 Connection도 대기되어 장시간 대기 현상을 보이는 것을 확인할 수 있다.  

이와 같이 대기 현상이 유발되는 이유는 Table Flush의 작업 성격에 있다. Table Flush 작업은 해당 테이블에 아무런 작업이 일어나지 않을 그 순간에 진행된다. 또한 Table Flush 이후에 들어온 쿼리들은 Flush가 끝날 때까지 기다린 다음에 진행되는데, Flush 이전에 실행되어 있던 쿼리가 끝나지 않으면 Table Flush 작업도 대기 상태가 되기 때문에 연쇄적으로 대기 현상이 발생하게 되는 것이다. 

● 해결 방법
이와 같은 대기 발생 시, 상황을 해결하기 위해서는 유발에 원인이 되는 쿼리를 실행하는 Connection(즉, 7번 Connection)을 kill하여 처리하던가, “flush tables”를 시도하는 Connection을 kill해 처리해야 한다. 하지만 어느 Connection을 kill해야 하는가에 대한 문제는 서비스의 상황에 따라 달라지게 된다. 데이터를 select하는 쿼리인 경우에는 대부분 큰 문제가 되지 않기 때문에 대기 유발의 원인이 되는 Connection, 즉 7번 Connection을 kill하여 해결하지만, 만약 데이터를 변경하려는 쿼리일 경우에는 다른 선택을 해야 할 수도 있다.  

즉, 서비스 성격에 따라 데이터 변경을 시도하는 Connection을 kill하던가 아니면 flush tables를 시도하는 Connection을 kill하면 되는데, 둘 중 어느 Connection을 kill해야 하는가에 대한 문제는 연결되어 있는 Connection이 어떤 성격의 것이냐에 따라 선택되어야 하는 것이므로 어떤 것을 선택해야 옳은지는 단정 지어 말하기 어렵다. 

mysqldump를 이용해 백업이 진행되는 경우 

mysqldump는 MySQL에 기본 제공되는 백업 툴로서 이 툴을 이용하면 데이터에 대한 논리적인 백업을 진행할 수 있다. 이 툴을 사용해 서비스 중인 DB에 대해 백업을 진행하는 경우, 서비스에 관계없이 백업할 수 있다. 하지만 특이한 상황의 경우 위와 같은 대기 현상을 발견할 수 있다(백업은 일반적으로 백업 타입에 따라 물리적인 백업과 논리적인 백업으로 나뉜다. 물리적인 백업은 실제 데이터가 저장된 저장 파일을 백업 받는 것을 의미하고, 논리적인 백업은 실제 데이터와 스키마를 추출해 논리적인 형태로 바이너리 파일이나 txt 파일로 저장하는 것을 의미).  

● mysqldump의 작업 내용
mysqldump는 논리적으로 데이터의 내용을 백업 받을 수 있는 툴이다. 이 툴을 사용해 백업을 받는 경우에도 앞 예제와 같은 현상이 발생할 수 있다. 그것은 바로 다음과 같은 mysqldump의 특징 때문이다.

- Snapshot을 위해 mysqldump는 작업 시작 전 “flush tables” 명령을 수행한다.

IT에서 SnapShot은 어느 한 순간의 메모리 상태를 말하는 것으로, 일반적으로 DB에서의 SnapShot은 어느 한 순간의 데이터 집합을 의미한다. mysqldump를 이용하면 SnapShot 상태의 데이터를 백업 받을 수 있는데, 이러한 목적으로 백업을 받을 경우 대기 현상이 유발될 수 있다.  

● 대기 유발 상황
그러면 어떠한 경우에 대기 현상이 발생하게 되는 것인가? 다음과 같은 상황에 확인할 수 있다.  

shrtcmt 테이블에 select를 실행한다.
mysql> select * from shrtcmt;

mysqldump를 이용해 백업되도록 실행한다.
[mysql bin] ./mysqldump -ptest -u root --master-data --single-transaction --all-databases >> /db/dump/test.sql

shrtcmt 테이블에 다른 커넥션을 연결해 다른 select를 실행한다.
mysql> select * from shrtcmt limit 0,2;
show processlist;를 통해 확인한다.
mysql> show processlist; 

● 대기 유발 예제
그럼 위에 설명한 방식대로 이루어지는지 예제를 통해 확인해 본다.  

<화면 3> mysqldump 실행문 

<화면 4> mysqldump로 인한 대기 현상 1 

<그림 3> mysqldump로 인한 대기 현상 2 

<화면 3>은 mysqldump 실행 구문으로 --master-data, --single-transaction, --all-databases 옵션을 함께 사용해 SnapShot을 받을 수 있게 실행한 것이다. 복구를 위해 백업 받아야 하는 경우라면 <화면 3>의 예처럼 백업 받아야 한다. <화면 4>는 앞서의 시나리오대로 실행한 경우에 나타나는 Connection 상태이다. Connection 60번은 SnapShot의 목적으로 백업을 받는 mysqldump가 실행되는 Connection으로, 본격적인 백업 작업 전에 내부적으로 “flush tables”가 일어나고 있는 것을 확인할 수 있다. 그리고 Table Flush 진행의 경우와 마찬가지로, 장시간 실행되는 select 문(Connection 2번)으로 인해 Table Flush 실행 시(Connection 60번) 대기 현상이 나타나게 된다. 결국, 이 대기 현상으로 인해 Connection 49번이 영향을 받게 되고, Connection 49번은 waiting for table 상태로 Connection 60번에 실행 중인 “flush tables”가 끝나기를 기다리게 된다.  

만약 mysqldump 실행 시 <화면 3>과 같은 SnapShot을 위한 옵션(--master-data, --single-transaction, --all-databases)을 사용하지 않는다면 이와 같은 대기 현상은 나타나지 않는다. 이와 같은 현상은 mysqldump를 이용해 SnapShot을 원할 경우에만 발생하게 된다.  

● 해결 방법
이와 같은 경우, 대기 유발 상황을 해결하는 방법은 Table Flush 진행의 경우와 다르지 않지만, 이 상황의 경우 Table Flush가 백업의 SnapShot을 위해 진행하는 작업이기 때문에 대기를 유발한 Connection, 즉 위의 예제처럼 2번 Connection을 kill하던지 아니면 백업을 포기하던지 선택해야 한다. 여기서도 마찬가지로 어느 Connection을 kill할지에 대한 선택의 문제는 그 Connection의 성격에 따라 달라진다.  

http://dev.mysql.com/doc/refman/5.0/en/mysqldump.html에 기술된 내용을 확인하면, 실제 프로세스를 통해 보이는 현상과 다르게 설명되어 있음을 확인할 수 있다. 링크의 문서 내용을 확인하면 다음과 같은 내용을 확인할 수 있다.

--all-databases, --single-transaction 옵션을 사용하여 백업을 진행하면 “FLUSH TABLES WITH READ LOCK”을 사용하는 global read lock이 발생한다.

하지만, --all-databases, --single-transaction 옵션만 사용하게 되면, global read lock은 발생하지 않는다. --master-data 옵션도 같이 사용해야만 발생하게 되고 세 가지의 옵션을 사용하는 경우에 “FLUSH TABLES WITH READ LOCK”이 아닌 “FLUSH TABLES”가 실행됨을 <화면 4>를 통해 확인할 수 있다.

Table Lock을 사용하는 경우 

서비스를 운영할 때 데이터에 대한 일관성을 보장하기 위해 Table Lock을 사용하는 경우 Table Lock의 특성에 맞지 않게 Application을 구성하면 Lock으로 인한 대기 현상으로 인해 서비스에 영향을 미칠 수 있다. 

● Table Lock을 사용하는 경우
먼저 어느 경우에 Table Lock을 사용하는지 확인해 보자.

- Table Lock을 사용하는 Storage Engine으로 테이블을 생성한 경우
- 명시적으로 Table Lock을 사용한 경우  

위의 두 가지 경우 중 주로 원인이 되는 경우는 첫 번째의 경우, 즉 Table Lock을 사용하는 Storage Engine을 사용하는 경우이다. 이 예제에 가장 대표적인 예로는 MyISAM Storage Engine이 있다. 그럼 대기 유발 상황을 확인하기 전에 Table Lock에 대해 간단히 알아보자. Table Lock은 다음과 같은 원칙에 따라 Lock을 실행한다.  

- 데이터 일관성을 보호하기 위해 Read Lock과 Write Lock을 사용한다.
- Read Lock은 하나의 테이블에 동시에 접근할 수 있다.
- Read Lock과 Write Lock은 하나의 테이블에 동시 접근할 수 없다.

 ● 대기 유발 상황
그러면 MyISAM과 같이 Table Lock을 사용하는 스토리지 엔진으로 테이블을 생성한 경우 어떻게 Lock이 유발되는지 확인해 보자.  

먼저 테스트하려는 shrtcmt 테이블이 MyISAM 스토리지 엔진인지 확인한다.
mysql> show create table shrtcmt;

shrtcmt 테이블에 select를 한다.
mysql> select * from shrtcmt;

다른 커넥션을 열어서 shrtcmt 테이블에 데이터 변경문(ex>insert, update, delete)을 실행한다.
mysql> delete from shrtcmt where userid = ’’;

또 다른 커넥션을 열어서 shrtcmt 테이블에 select 쿼리를 실행한다.
mysql> select * from shrtcmt limit 0,2;

show processlist를 통해 현재 상태를 확인한다.
mysql> show processlist;

 ● 대기 유발 예제
위와 같은 예제는 다음과 같이 확인할 수 있다.  

<화면 5> Table lock 사용으로 인한 대기 현상 1 

<그림 4> Table Lock 사용으로 인한 대기 현상 2 

<화면 5>는 앞서 설명한 유발 상황을 만들어서 “show processlist” 명령어로 확인한 결과이다. 69번 Connection에서 실행 중인 쿼리로 인해 70번 Connection의 delete 문이 Lock 경합으로 인한 대기 현상이 발생했고, 이 대기 현상으로 인해 그 후로 들어온 71번 Connection까지 Lock 경합으로 인한 대기 현상이 발생함을 확인할 수 있다.

 ● 해결 방법
앞 예제의 경우에는 오랜 시간 동안 실행되어 언제 끝날지 모르는 69번 Connection을 kill하여 처리해야 한다. 이 예제에서의 문제점은 오랜 시간 동안 실행되는 select SQL 문으로 인해 같이 실행될 수 없는 데이터 변경 문이 실행되어 Lock 경합을 유발한다는 데에 있다.  

중요한 것은 MyISAM과 같은 Storage Engine에서 read는 같은 테이블에 동시에 여러 건이 실행될 수 있지만, read와 write는 같은 테이블에 동시에 일어날 수 없다는 것이다. 만약 1시간 동안 실행되는 update 문이 있었다면, 그 테이블은 1시간 동안 어떤 select도 발생할 수 없게 된다.  

그러므로 MyISAM과 같이 Table Lock을 사용하는 Storage Engine을 사용하려면, SQL 문이 되도록 짧은 시간에 끝날 수 있도록 작성해야 한다. 하지만 그럼에도 불구하고 이런 대기 현상이 자주 발생한다면, 현재 운영하는 서비스에 MyISAM과 같은 Storage Engine은 맞지 않는 것이다. 이런 경우에는 서비스 특성에 따라 다른 Lock mode를 사용하는 Storage Engine으로 교체하는 것을 고려해 보는 게 좋다.  

Serializable Isolation Level을 사용하는 경우 

InnoDB에서 Serializable Isolation Level을 사용하는 경우 Read Lock 사용으로 인한 대기 현상을 확인할 수 있다.  

● Serializable Isolation Level이란?
Serializable Isolation Level은 select 수행 시 Read Lock, 즉 Shared Lock을 사용하는 Transaction Isolation Level로서 표준 SQL에서 지원하는 4개의 Isolation Level 중 가장 Static한 Level이다. Serializable Isolation Level의 특징은 다음과 같다.  

- select 수행 시 Read Lock을 사용한다.
- 데이터의 일관성 및 동시성을 위해 MVCC(MultiVersion Concurrency Control)를 사용하지 않는다(MVCC는 다중 사용자 데이터베이스 성능을 위한 기술로서 데이터 조회 시 lock을 사용하지 않고 데이터의 버전을 관리해 데이터의 일관성 및 동시성을 높이는 기술을 말한다). 

● 대기 유발 상황
그러면 Serializable Isolation Level 사용 시 어떤 경우에 대기 유발 상황이 발생하는지 그 상황을 확인해 보자.  

현재 MySQL 서버의 Isolation Level이 Serializable인지 확인한다.
mysql> select @@tx_isolation;

shrtcmt 테이블이 InnoDB Storage Engine인지 확인한다.
mysql> show create table shrtcmt;

shrtcmt 테이블에 select 쿼리를 실행한다.
mysql> select * from shrtcmt;

다른 커넥션을 열어서 데이터 변경 작업을 실행한다.
mysql> delete from shrtcmt where userid=’’;
show processlist; 구문을 통해 상태를 확인한다.
mysql> show processlist; 

<화면 6> Serializable Isolation에 따른 Lock 상태 확인 1 

<그림 5> Serializable Isolation에 따른 Lock 상태 확인 2 

<화면 7> timeout에 따른 작업 실패 예제 

● 대기 유발 예제
위와 같은 예제는 다음과 같이 확인할 수 있다.
<화면 6>에서 보면 69번 Connection에서 실행 중인 select 쿼리로 인해 70번 Connection에서 실행 중인 delete 문이 지연됨을 확인할 수 있다. 명시적으로 Locked로 표시되지는 않기 때문에 Lock으로 인한 대기 현상이라고 확인하기는 어렵지만, 현재 실행하는 delete 문에서는 innodb_lock_wait_timeout 시간이 지나면 <화면 7>과 같은 에러를 보여주기 때문에 Lock으로 인한 대기 현상임을 확인할 수 있다.  

● 해결 방법
먼저 앞과 같은 상황을 해결하기 위해서는 문제가 되는 69번 Connection을 kill 명령을 사용해 중단시킨다. 또는 innodb_ lock_wait_timeout 시간을 짧게 하여 해결할 수도 있다. 하지만 Lock 경합으로 인해 실질적으로 commit되는 트랜잭션의 수는 적을 것이고, rollback되는 트랜잭션의 수는 많아질 것이다. 그래서 서비스에 사용할 경우에는 이 Isolation Level을 사용하는 게 맞는 것인지 먼저 파악해 보는 것이 중요하다. 데이터의 접근 동시성을 확보하기 위해서는 다른 Isolation Level을 사용하도록 권장한다. 일반적으로 많이 사용하는 Isolation Level은 Read Uncommitted와 Read Committed이다.  

이제껏 운영하면서 일반적으로 이해하기 힘든 대기 상황 및 자주 발생하는 대기 상황에 대한 내용을 정리했다. 일반적으로 예상할 수 있는 대기 현상도 있고, 전혀 예상치 못한 대기 현상도 있었을 것이다. 몇 년간 MySQL을 운영하면서 겪은 내용을 정리한 이와 같은 내용이 필자 이외의 다른 분들에게도 많은 도움이 되었으면 좋겠다. 

참고자료
1. http://dev.mysql.com/doc/refman/5.0/en/flush.html
2. http://dev.mysql.com/doc/refman/5.0/en/mysqldump.html
3. http://dev.mysql.com/doc/refman/5.0/en/set-transaction.html
4. MySQL 5 Cetification Study Guide by Paul DuBois, Stefan Hinz, Carsten Pedersen

필자소개

 윤은경 joanney1@daum.net |다음커뮤니케이션 DB기술팀 MySQL Cell 소속. 대학 졸업 후 5년 동안 자바 개발자로 살다가, 갑자기 맘이 바뀌어 그 후 5년간 DBA로 살고 있는, 10년 차 직장인이 되었다는 게 여전히 믿겨지지 않는 IT 종사자이다.

 출처 : 한국 마이크로 소프트웨어 [2009년 4월호]

제공 : DB포탈사이트 DBguide.net

'Database > Mysql' 카테고리의 다른 글

프로시저의 예  (0) 2011.01.26
MySQL DUMP 백업 및 복원  (0) 2010.09.20
load data  (0) 2010.07.19
mysql replication error  (0) 2010.06.28
LOAD XML Syntax [v5.5]  (0) 2010.04.07

+ Recent posts