网站间歇性变慢，贴出来 Mysql show engine innodb status 命令结果，大家帮忙分析下
hellohacker 2014-05-07 18:18:22 +08:00 3765 次点击这是一个创建于 4258 天前的主题，其中的信息可能已经有所发展或是发生改变。
140507 16:18:44 INNODB MONITOR OUTPUT
=====================================
Per second averages calculated from the last 7 seconds
-----------------
BACKGROUND THREAD
-----------------
srv_master_thread loops: 91 1_second, 91 sleeps, 5 10_second, 42 background, 42 flush
srv_master_thread log flush and writes: 92
----------
SEMAPHORES
----------
OS WAIT ARRAY INFO: reservation count 17, signal count 17
Mutex spin waits 14, rounds 240, OS waits 6
RW-shared spins 5, rounds 150, OS waits 5
RW-excl spins 7, rounds 181, OS waits 6
Spin rounds per wait: 17.14 mutex, 30.00 RW-shared, 25.86 RW-excl
------------
TRANSACTIONS
------------
Trx id counter 2193D
Purge done for trx's n:o < 2193B undo n:o < 0
History list length 127
LIST OF TRANSACTIONS FOR EACH SESSION:
---TRANSACTION 0, not started
MySQL thread id 4265199, OS thread handle 0x1bc, query id 78970246 127.0.0.1 root
show engine innodb status
--------
FILE I/O
--------
I/O thread 0 state: wait Windows aio (insert buffer thread)
I/O thread 1 state: wait Windows aio (log thread)
I/O thread 2 state: wait Windows aio (read thread)
I/O thread 3 state: wait Windows aio (read thread)
I/O thread 4 state: wait Windows aio (read thread)
I/O thread 5 state: wait Windows aio (read thread)
I/O thread 6 state: wait Windows aio (write thread)
I/O thread 7 state: wait Windows aio (write thread)
I/O thread 8 state: wait Windows aio (write thread)
I/O thread 9 state: wait Windows aio (write thread)
Pending normal aio reads: 0 [0, 0, 0, 0] , aio writes: 0 [0, 0, 0, 0] ,
ibuf aio reads: 0, log i/o's: 0, sync i/o's: 0
Pending flushes (fsync) log: 0; buffer pool: 0
364 OS file reads, 55 OS file writes, 23 OS fsyncs
0.00 reads/s, 0 avg bytes/read, 0.00 writes/s, 0.00 fsyncs/s
-------------------------------------
INSERT BUFFER AND ADAPTIVE HASH INDEX
-------------------------------------
Ibuf: size 1, free list len 0, seg size 2, 0 merges
merged operations:
insert 0, delete mark 0, delete 0
discarded operations:
insert 0, delete mark 0, delete 0
Hash table size 1168451, node heap has 1 buffer(s)
0.00 hash searches/s, 0.00 non-hash searches/s
---
LOG
---
Log sequence number 1963408
Log flushed up to 1963408
Last checkpoint at 1963408
0 pending log writes, 0 pending chkp writes
18 log i/o's done, 0.00 log i/o's/second
----------------------
BUFFER POOL AND MEMORY
----------------------
Total memory allocated 604192768; in additional pool allocated 0
Dictionary memory allocated 62427
Buffer pool size 36032
Free buffers 35678
Database pages 353
Old database pages 0
Modified db pages 0
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 0, not young 0
0.00 youngs/s, 0.00 non-youngs/s
Pages read 353, created 0, written 37
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
No buffer pool page gets since the last printout
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 353, unzip_LRU len: 0
I/O sum[0]:cur[0], unzip sum[0]:cur[0]
--------------
ROW OPERATIONS
--------------
0 queries inside InnoDB, 0 queries in queue
1 read views open inside InnoDB
Main thread id 4600, state: waiting for server activity
Number of rows inserted 0, updated 0, deleted 0, read 151
0.00 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s
----------------------------
END OF INNODB MONITOR OUTPUT
另外再贴下 慢查询日志：
# Time: 140507 16:05:39
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 7.172012 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399449939;
DESC fanwe_youhui_log;
# Time: 140507 16:06:04
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 9.172051 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399449964;
DESC fanwe_youhui_log;
# Time: 140507 16:07:13
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 28.125540 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450033;
DESC fanwe_youhui_log;
# Time: 140507 16:07:35
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 17.515961 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450055;
DESC fanwe_youhui_log;
# Time: 140507 16:08:37
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 9.890815 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450117;
DESC fanwe_youhui_log;
# Time: 140507 16:09:14
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 9.937691 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450154;
DESC fanwe_youhui_log;
# Time: 140507 16:09:37
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 11.593972 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450177;
DESC fanwe_youhui_log;
# Time: 140507 16:11:04
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.734581 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450264;
DESC fanwe_youhui_log;
# Time: 140507 16:11:17
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.047068 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450277;
DESC fanwe_youhui_log;
# Time: 140507 16:11:47
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 23.922334 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450307;
DESC fanwe_youhui_log;
# Time: 140507 16:11:51
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 2.531299 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450311;
DESC fanwe_youhui_log;
# Time: 140507 16:12:30
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 3.250062 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450350;
DESC fanwe_youhui_log;
# Time: 140507 16:12:44
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.703331 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450364;
DESC fanwe_youhui_log;
# Time: 140507 16:13:02
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 14.687782 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450382;
DESC fanwe_youhui_log;
# Time: 140507 16:14:12
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 26.313006 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450452;
DESC fanwe_youhui_log;
# Time: 140507 16:14:29
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 2.531299 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450469;
DESC fanwe_youhui_log;
# Time: 140507 16:14:48
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 14.906536 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450488;
DESC fanwe_youhui_log;
# Time: 140507 16:15:05
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 13.406507 Lock_time: 0.000000 Rows_sent: 11 Rows_exained: 11
SET timestamp=1399450505;
DESC fanwe_youhui_log;
# Time: 140507 16:15:14
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 5.937614 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450514;
DESC fanwe_youhui_log;
# Time: 140507 16:15:19
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 4.765716 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450519;
DESC fanwe_youhui_log;
# Time: 140507 16:15:25
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 5.187600 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450525;
DESC fanwe_youhui_log;
# Time: 140507 16:15:49
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 17.031577 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450549;
DESC fanwe_youhui_log;
# Time: 140507 16:15:54
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 3.109435 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450554;
DESC fanwe_youhui_log;
# Time: 140507 16:17:14
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 2.312545 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 28795
SET timestamp=1399450634;
select group_concat(id) from fanwe_deal_order_item where order_id = 28206;
# Time: 140507 16:17:20
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 4.468836 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450640;
DESC fanwe_youhui_log;
# Time: 140507 16:18:10
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.937710 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450690;
DESC fanwe_youhui_log;
# Time: 140507 16:18:35
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 7.515769 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450715;
DESC fanwe_youhui_log;
谢谢大家了！
=====================================
Per second averages calculated from the last 7 seconds
-----------------
BACKGROUND THREAD
-----------------
srv_master_thread loops: 91 1_second, 91 sleeps, 5 10_second, 42 background, 42 flush
srv_master_thread log flush and writes: 92
----------
SEMAPHORES
----------
OS WAIT ARRAY INFO: reservation count 17, signal count 17
Mutex spin waits 14, rounds 240, OS waits 6
RW-shared spins 5, rounds 150, OS waits 5
RW-excl spins 7, rounds 181, OS waits 6
Spin rounds per wait: 17.14 mutex, 30.00 RW-shared, 25.86 RW-excl
------------
TRANSACTIONS
------------
Trx id counter 2193D
Purge done for trx's n:o < 2193B undo n:o < 0
History list length 127
LIST OF TRANSACTIONS FOR EACH SESSION:
---TRANSACTION 0, not started
MySQL thread id 4265199, OS thread handle 0x1bc, query id 78970246 127.0.0.1 root
show engine innodb status
--------
FILE I/O
--------
I/O thread 0 state: wait Windows aio (insert buffer thread)
I/O thread 1 state: wait Windows aio (log thread)
I/O thread 2 state: wait Windows aio (read thread)
I/O thread 3 state: wait Windows aio (read thread)
I/O thread 4 state: wait Windows aio (read thread)
I/O thread 5 state: wait Windows aio (read thread)
I/O thread 6 state: wait Windows aio (write thread)
I/O thread 7 state: wait Windows aio (write thread)
I/O thread 8 state: wait Windows aio (write thread)
I/O thread 9 state: wait Windows aio (write thread)
Pending normal aio reads: 0 [0, 0, 0, 0] , aio writes: 0 [0, 0, 0, 0] ,
ibuf aio reads: 0, log i/o's: 0, sync i/o's: 0
Pending flushes (fsync) log: 0; buffer pool: 0
364 OS file reads, 55 OS file writes, 23 OS fsyncs
0.00 reads/s, 0 avg bytes/read, 0.00 writes/s, 0.00 fsyncs/s
-------------------------------------
INSERT BUFFER AND ADAPTIVE HASH INDEX
-------------------------------------
Ibuf: size 1, free list len 0, seg size 2, 0 merges
merged operations:
insert 0, delete mark 0, delete 0
discarded operations:
insert 0, delete mark 0, delete 0
Hash table size 1168451, node heap has 1 buffer(s)
0.00 hash searches/s, 0.00 non-hash searches/s
---
LOG
---
Log sequence number 1963408
Log flushed up to 1963408
Last checkpoint at 1963408
0 pending log writes, 0 pending chkp writes
18 log i/o's done, 0.00 log i/o's/second
----------------------
BUFFER POOL AND MEMORY
----------------------
Total memory allocated 604192768; in additional pool allocated 0
Dictionary memory allocated 62427
Buffer pool size 36032
Free buffers 35678
Database pages 353
Old database pages 0
Modified db pages 0
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 0, not young 0
0.00 youngs/s, 0.00 non-youngs/s
Pages read 353, created 0, written 37
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
No buffer pool page gets since the last printout
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 353, unzip_LRU len: 0
I/O sum[0]:cur[0], unzip sum[0]:cur[0]
--------------
ROW OPERATIONS
--------------
0 queries inside InnoDB, 0 queries in queue
1 read views open inside InnoDB
Main thread id 4600, state: waiting for server activity
Number of rows inserted 0, updated 0, deleted 0, read 151
0.00 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s
----------------------------
END OF INNODB MONITOR OUTPUT
另外再贴下 慢查询日志：
# Time: 140507 16:05:39
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 7.172012 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399449939;
DESC fanwe_youhui_log;
# Time: 140507 16:06:04
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 9.172051 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399449964;
DESC fanwe_youhui_log;
# Time: 140507 16:07:13
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 28.125540 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450033;
DESC fanwe_youhui_log;
# Time: 140507 16:07:35
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 17.515961 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450055;
DESC fanwe_youhui_log;
# Time: 140507 16:08:37
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 9.890815 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450117;
DESC fanwe_youhui_log;
# Time: 140507 16:09:14
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 9.937691 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450154;
DESC fanwe_youhui_log;
# Time: 140507 16:09:37
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 11.593972 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450177;
DESC fanwe_youhui_log;
# Time: 140507 16:11:04
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.734581 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450264;
DESC fanwe_youhui_log;
# Time: 140507 16:11:17
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.047068 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450277;
DESC fanwe_youhui_log;
# Time: 140507 16:11:47
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 23.922334 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450307;
DESC fanwe_youhui_log;
# Time: 140507 16:11:51
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 2.531299 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450311;
DESC fanwe_youhui_log;
# Time: 140507 16:12:30
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 3.250062 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450350;
DESC fanwe_youhui_log;
# Time: 140507 16:12:44
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.703331 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450364;
DESC fanwe_youhui_log;
# Time: 140507 16:13:02
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 14.687782 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450382;
DESC fanwe_youhui_log;
# Time: 140507 16:14:12
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 26.313006 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450452;
DESC fanwe_youhui_log;
# Time: 140507 16:14:29
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 2.531299 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450469;
DESC fanwe_youhui_log;
# Time: 140507 16:14:48
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 14.906536 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450488;
DESC fanwe_youhui_log;
# Time: 140507 16:15:05
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 13.406507 Lock_time: 0.000000 Rows_sent: 11 Rows_exained: 11
SET timestamp=1399450505;
DESC fanwe_youhui_log;
# Time: 140507 16:15:14
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 5.937614 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450514;
DESC fanwe_youhui_log;
# Time: 140507 16:15:19
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 4.765716 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450519;
DESC fanwe_youhui_log;
# Time: 140507 16:15:25
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 5.187600 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450525;
DESC fanwe_youhui_log;
# Time: 140507 16:15:49
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 17.031577 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450549;
DESC fanwe_youhui_log;
# Time: 140507 16:15:54
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 3.109435 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450554;
DESC fanwe_youhui_log;
# Time: 140507 16:17:14
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 2.312545 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 28795
SET timestamp=1399450634;
select group_concat(id) from fanwe_deal_order_item where order_id = 28206;
# Time: 140507 16:17:20
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 4.468836 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450640;
DESC fanwe_youhui_log;
# Time: 140507 16:18:10
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 10.937710 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450690;
DESC fanwe_youhui_log;
# Time: 140507 16:18:35
# User@Host: root[root] @ [127.0.0.1]
# Query_time: 7.515769 Lock_time: 0.000000 Rows_sent: 11 Rows_examined: 11
SET timestamp=1399450715;
DESC fanwe_youhui_log;
谢谢大家了！
目前尚无回复
Select Language