Bug #71739 Bad perfromance and warning: InnoDB: Warning: purge reached the head of the hist
Submitted: 16 Feb 2014 18:52 Modified: 24 Aug 2014 19:58
Reporter: Volker Scholz Email Updates:
Status: No Feedback Impact on me:
None 
Category:MySQL Server Severity:S3 (Non-critical)
Version:5.0.95 OS:Linux (CENTOS)
Assigned to: CPU Architecture:Any
Tags: InnoDB Warning end of history list reached, very bad perfroming mySQL

[16 Feb 2014 18:52] Volker Scholz
Description:
InnoDB: report, and post it to bugs.mysql.com
140216 19:38:18  InnoDB: Warning: purge reached the head of the history list,
InnoDB: but its length is still reported as 8770342! Make a detailed bug
InnoDB: report, and post it to bugs.mysql.com
140216 19:41:39  InnoDB: Warning: purge reached the head of the history list,
InnoDB: but its length is still reported as 8770614! Make a detailed bug
InnoDB: report, and post it to bugs.mysql.com
140216 19:45:00  InnoDB: Warning: purge reached the head of the history list,
InnoDB: but its length is still reported as 8770824! Make a detailed bug
InnoDB: report, and post it to bugs.mysql.com

Very bad performance and mysql use the most of the CPU performance

[mysqld]
local-infile=0
datadir=/var/lib/mysql
socket=/var/lib/mysql/mysql.sock
user=mysql
# Default to using old password format for compatibility with mysql 3.x
# clients (those using the mysqlclient10 compatibility package).
old_passwords=1
skip-networking

# Disabling symbolic-links is recommended to prevent assorted security risks;
# to do so, uncomment this line:
# symbolic-links=0

skip-bdb

innodb_buffer_pool_size=2M
innodb_additional_mem_pool_size=500K
innodb_log_buffer_size=500K
innodb_thread_concurrency=2
[mysqld_safe]
log-error=/var/log/mysqld.log
pid-file=/var/run/mysqld/mysqld.pid
skip-bdb

innodb_buffer_pool_size=2M
innodb_additional_mem_pool_size=500K
innodb_log_buffer_size=500K
innodb_thread_concurrency=2

Innodb stats:
=====================================
140216 19:22:10 INNODB MONITOR OUTPUT
=====================================
Per second averages calculated from the last 29 seconds
----------
SEMAPHORES
----------
OS WAIT ARRAY INFO: reservation count 818861, signal count 821963
Mutex spin waits 0, rounds 77393111, OS waits 150001
RW-shared spins 480227, OS waits 192784; RW-excl spins 518723, OS waits 252613
------------
TRANSACTIONS
------------
Trx id counter 0 545906736
Purge done for trx's n:o < 0 419503999 undo n:o < 0 0
History list length 8769277
Total number of lock structs in row lock hash table 0
LIST OF TRANSACTIONS FOR EACH SESSION:
---TRANSACTION 0 0, not started, process no 18744, OS thread id 140068589975872
MySQL thread id 3603, query id 368300 localhost scholker
SHOW INNODB STATUS
---TRANSACTION 0 545906735, not started, process no 18744, OS thread id 140068689426752
MySQL thread id 3611, query id 368299 localhost MagentoDB
--------
FILE I/O
--------
I/O thread 0 state: waiting for i/o request (insert buffer thread)
I/O thread 1 state: waiting for i/o request (log thread)
I/O thread 2 state: waiting for i/o request (read thread)
I/O thread 3 state: waiting for i/o request (write thread)
Pending normal aio reads: 0, aio writes: 0,
 ibuf aio reads: 0, log i/o's: 0, sync i/o's: 0
Pending flushes (fsync) log: 0; buffer pool: 0
26947761 OS file reads, 61703 OS file writes, 23208 OS fsyncs
3348.02 reads/s, 16755 avg bytes/read, 11.17 writes/s, 4.28 fsyncs/s
-------------------------------------
INSERT BUFFER AND ADAPTIVE HASH INDEX
-------------------------------------
Ibuf: size 1, free list len 11, seg size 13,
4114 inserts, 4198 merged recs, 556 merges
Hash table size 4441, used cells 1709, node heap has 4 buffer(s)
51686.39 hash searches/s, 60847.97 non-hash searches/s
---
LOG
---
Log sequence number 2 3460421735
Log flushed up to   2 3460421735
Last checkpoint at  2 3460359053
0 pending log writes, 0 pending chkp writes
10816 log i/o's done, 2.07 log i/o's/second
----------------------
BUFFER POOL AND MEMORY
----------------------
Total memory allocated 12876928; in additional pool allocated 524032
Buffer pool size   128
Free buffers       0
Database pages     124
Modified db pages  3
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages read 28867989, created 229, written 46619
3423.99 reads/s, 0.07 creates/s, 8.34 writes/s
Buffer pool hit rate 995 / 1000
--------------
ROW OPERATIONS
--------------
0 queries inside InnoDB, 0 queries in queue
1 read views open inside InnoDB
Main thread process no. 18744, id 140068554537280, state: purging
Number of rows inserted 13199, updated 6346, deleted 1, read 44704410
2.55 inserts/s, 1.28 updates/s, 0.00 deletes/s, 7672.18 reads/s
----------------------------
END OF INNODB MONITOR OUTPUT
============================

How to repeat:
occurs ongoing so could be reproduced
[24 Jul 2014 19:58] Sveta Smirnova
Thank you for the report.

But version 5.0.95 is not supported anymore. Please try one of current GA versions 5.5.38 or 5.6.19 and, if problem still exists, provide full error log file.
[25 Aug 2014 1:00] Bugs System
No feedback was provided for this bug for over a month, so it is
being suspended automatically. If you are able to provide the
information that was originally requested, please do so and change
the status of the bug back to "Open".