040426 12:25:14 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 111000616 040426 12:25:24 Slave I/O thread exiting, read up to log 'jimbo-bin.009', position 111000727 040426 12:25:24 create_table_from_dump: failed in handler::net_read_dump() 040426 12:25:43 create_table_from_dump: failed in handler::net_read_dump() 040426 12:25:58 create_table_from_dump: failed in handler::net_read_dump() 040426 13:55:36 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 111000787 InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 241.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:04:01 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 11 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 242.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23332 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23216, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7654 localhost root ---TRANSACTION 0 23331, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7769 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7833 OS file writes, 7758 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.18 writes/s, 0.18 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.18 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1860737 Log flushed up to 0 1860675 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7715 log i/o's done, 0.18 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7658, updated 0, deleted 1, read 1792 0.18 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:04:17 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 258.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23336 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23333, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7771 localhost root ---TRANSACTION 0 23335, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7773 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7836 OS file writes, 7761 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1860923 Log flushed up to 0 1860861 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7718 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7661, updated 0, deleted 1, read 2429 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 39.81 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 273.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:04:33 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 274.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23340 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23333, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7771 localhost root ---TRANSACTION 0 23339, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7777 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7840 OS file writes, 7765 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.25 writes/s, 0.25 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.25 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1861187 Log flushed up to 0 1861109 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7722 log i/o's done, 0.25 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7665, updated 0, deleted 1, read 2429 0.25 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:04:49 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 290.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23343 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23333, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7771 localhost root ---TRANSACTION 0 23342, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7780 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7843 OS file writes, 7768 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1861373 Log flushed up to 0 1861311 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7725 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7668, updated 0, deleted 1, read 2429 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 305.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:05:05 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 306.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23346 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23333, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7771 localhost root ---TRANSACTION 0 23345, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7783 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7846 OS file writes, 7771 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1861559 Log flushed up to 0 1861497 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7728 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7671, updated 0, deleted 1, read 2429 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:05:21 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 322.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23349 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23333, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7771 localhost root ---TRANSACTION 0 23348, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7786 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7849 OS file writes, 7774 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1861761 Log flushed up to 0 1861699 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7731 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7674, updated 0, deleted 1, read 2429 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 337.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:05:37 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 338.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23358 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23333, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7771 localhost root ---TRANSACTION 0 23357, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7795 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7858 OS file writes, 7783 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.56 writes/s, 0.56 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.56 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1862335 Log flushed up to 0 1862273 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7740 log i/o's done, 0.56 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7683, updated 0, deleted 1, read 2429 0.56 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:05:53 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 354.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23361 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23333, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7771 localhost root ---TRANSACTION 0 23360, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7798 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7861 OS file writes, 7786 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1862521 Log flushed up to 0 1862459 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7743 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7686, updated 0, deleted 1, read 2429 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 369.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:06:10 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 17 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 371.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23366 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23364, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7803 localhost root ---TRANSACTION 0 23365, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7804 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7865 OS file writes, 7790 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.24 writes/s, 0.24 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.24 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1862785 Log flushed up to 0 1862723 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7747 log i/o's done, 0.24 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7690, updated 0, deleted 1, read 3096 0.24 inserts/s, 0.00 updates/s, 0.00 deletes/s, 39.23 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:06:26 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 387.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23369 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23364, not started, process nInnoDB: ###### Diagnostic info printed to the standard output o 7758, OS thread id 2549476272 MySQL thread id 3, query id 7803 localhost root ---TRANSACTION 0 23368, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7807 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7868 OS file writes, 7793 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1862971 Log flushed up to 0 1862909 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7750 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7693, updated 0, deleted 1, read 3096 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 402.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:06:42 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 403.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23373 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23364, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7803 localhost root ---TRANSACTION 0 23372, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7811 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7872 OS file writes, 7797 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.25 writes/s, 0.25 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.25 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1863235 Log flushed up to 0 1863157 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7754 log i/o's done, 0.25 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7697, updated 0, deleted 1, read 3096 0.25 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:06:58 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 419.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23380 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23364, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7803 localhost root ---TRANSACTION 0 23379, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7818 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7879 OS file writes, 7804 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.44 writes/s, 0.44 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.44 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1863669 Log flushed up to 0 1863607 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7761 log i/o's done, 0.44 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7704, updated 0, deleted 1, read 3096 0.44 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 434.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:07:14 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 435.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23387 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23386, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7825 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7885 OS file writes, 7810 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.37 writes/s, 0.37 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.37 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1864057 Log flushed up to 0 1863995 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7767 log i/o's done, 0.37 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7710, updated 0, deleted 1, read 3782 0.37 inserts/s, 0.00 updates/s, 0.00 deletes/s, 42.87 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:07:30 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 451.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23394 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process nInnoDB: ###### Diagnostic info printed to the standard output o 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23393, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7832 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7892 OS file writes, 7817 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.44 writes/s, 0.44 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.44 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1864507 Log flushed up to 0 1864445 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7774 log i/o's done, 0.44 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 3 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7717, updated 0, deleted 1, read 3782 0.44 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 466.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:07:46 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 467.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23405 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23404, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7843 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7903 OS file writes, 7828 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.69 writes/s, 0.69 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.50 hash searches/s, 0.31 non-hash searches/s --- LOG --- Log sequence number 0 1865336 Log flushed up to 0 1865274 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7785 log i/o's done, 0.69 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7728, updated 0, deleted 1, read 3782 0.69 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:08:02 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 483.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23410 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23409, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7848 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7908 OS file writes, 7833 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.31 writes/s, 0.31 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.31 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1865646 Log flushed up to 0 1865584 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7790 log i/o's done, 0.31 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7733, updated 0, deleted 1, read 3782 0.31 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 498.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:08:18 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 499.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23413 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23412, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7851 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7911 OS file writes, 7836 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1865848 Log flushed up to 0 1865786 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7793 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7736, updated 0, deleted 1, read 3782 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:08:34 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 515.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23417 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23416, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7855 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7915 OS file writes, 7840 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.25 writes/s, 0.25 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.25 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1866096 Log flushed up to 0 1866034 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7797 log i/o's done, 0.25 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7740, updated 0, deleted 1, read 3782 0.25 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 530.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:08:50 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 531.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23423 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23422, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7861 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7921 OS file writes, 7846 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.37 writes/s, 0.37 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.37 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1866484 Log flushed up to 0 1866422 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7803 log i/o's done, 0.37 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7746, updated 0, deleted 1, read 3782 0.37 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:09:06 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 547.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23427 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23426, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7865 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7925 OS file writes, 7850 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.25 writes/s, 0.25 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.25 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1866732 Log flushed up to 0 1866670 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7807 log i/o's done, 0.25 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7750, updated 0, deleted 1, read 3782 0.25 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 562.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:09:22 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 563.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23432 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23431, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7870 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7930 OS file writes, 7855 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.31 writes/s, 0.31 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.31 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1867058 Log flushed up to 0 1866996 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7812 log i/o's done, 0.31 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7755, updated 0, deleted 1, read 3782 0.31 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:09:38 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 579.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23435 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23434, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7873 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7933 OS file writes, 7858 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.19 writes/s, 0.19 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.19 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1867244 Log flushed up to 0 1867182 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7815 log i/o's done, 0.19 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7758, updated 0, deleted 1, read 3782 0.19 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 594.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:09:54 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 595.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23444 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process no 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23443, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7882 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7942 OS file writes, 7867 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.56 writes/s, 0.56 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.56 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1867834 Log flushed up to 0 1867756 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7824 log i/o's done, 0.56 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7767, updated 0, deleted 1, read 3782 0.56 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:10:10 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 25, signal count 24 --Thread 2963024816 has waited at log0log.c line 1876 for 611.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 8, rounds 140, OS waits 1 RW-shared spins 39, OS waits 20; RW-excl spins 5, OS waits 4 ------------ TRANSACTIONS ------------ Trx id counter 0 23452 Purge done for trx's n:o < 0 22692 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 23384, not started, process noInnoDB: ###### Diagnostic info printed to the standard output 7758, OS thread id 2549476272 MySQL thread id 3, query id 7823 localhost root ---TRANSACTION 0 23451, COMMITTED IN MEMORY, process no 7758, OS thread id 2549676976 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 12, query id 7890 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 69 OS file reads, 7950 OS file writes, 7875 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.50 writes/s, 0.50 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.50 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1868346 Log flushed up to 0 1868268 Last checkpoint at 0 1805221 1 pending log writes, 1 pending chkp writes 7832 log i/o's done, 0.50 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 477 Database pages 34 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 20, created 14, written 192 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7758, id 2963024816, state: making checkpoint Number of rows inserted 7775, updated 0, deleted 1, read 3782 0.50 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 626.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: Error: semaphore wait has lasted > 600 seconds InnoDB: We intentionally crash the server, because it appears to be hung. 040426 14:10:25 InnoDB: Assertion failure in thread 2973514672 in file sync0arr.c line 934 InnoDB: Failing assertion: 0 InnoDB: We intentionally generate a memory trap. InnoDB: Send a detailed bug report to mysql@lists.mysql.com mysqld got signal 11; This could be because you hit a bug. It is also possible that this binary or one of the libraries it was linked against is corrupt, improperly built, or misconfigured. This error can also be caused by malfunctioning hardware. We will try our best to scrape up some info that will hopefully help diagnose the problem, but since we have already crashed, something is definitely wrong and this may fail. key_buffer_size=402653184 read_buffer_size=2093056 max_used_connections=0 max_connections=100 threads_connected=1 It is possible that mysqld could use up to key_buffer_size + (read_buffer_size + sort_buffer_size)*max_connections = 802415 K bytes of memory Hope that's ok; if not, decrease some variables in the equation. thd=(nil) Attempting backtrace. You can use the following information to find out where mysqld died. If you see no messages after this, something went terribly wrong... Cannot determine thread, fp=0xb13c355c, backtrace may not be correct. Stack range sanity check OK, backtrace follows: 0x80f7893 0xb756ae48 (nil) 0xb7564dac 0xb7424a8a New value of fp=(nil) failed sanity check, terminating stack trace! Please read http://www.mysql.com/doc/en/Using_stack_trace.html and follow instructions on how to resolve the stack trace. Resolved stack trace is much more helpful in diagnosing the problem, so please do resolve it The manual page at http://www.mysql.com/doc/en/Crashing.html contains information that should help you find out what is causing the crash. Number of processes running now: 0 040426 14:10:27 mysqld restarted 040426 14:10:27 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 1853497 InnoDB: Doing recovery: scanned up to log sequence number 0 1868920 040426 14:10:28 InnoDB: Starting an apply batch of log records to the database... InnoDB: Progress in percents: 98 99 InnoDB: Apply batch completed InnoDB: In a MySQL replication slave the last master binlog file InnoDB: position 0 111085674, file name jimbo-bin.009 InnoDB: Last MySQL binlog file position 0 512, file name ./wonko-bin.020 040426 14:10:30 InnoDB: Flushing modified pages from the buffer pool... 040426 14:10:32 InnoDB: Started /usr/local/libexec/mysqld: ready for connections. Version: '4.0.18-log' socket: '/tmp/mysql.sock' port: 3306 ERROR: 1062 Duplicate entry '763' for key 1 040426 14:10:32 Slave: Error 'Duplicate entry '763' for key 1' on query 'insert into davetest1(name) values('hello')'. Default database: 'repmaster', Error_code: 1062 040426 14:10:32 Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'jimbo-bin.009' position 111085563 040426 14:10:32 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 127630474 040426 14:26:44 Slave I/O thread exiting, read up to log 'jimbo-bin.009', position 158930337 ERROR: 1146 Table 'repmaster.davetest1' doesn't exist 040426 14:32:07 Slave: Error 'Table 'repmaster.davetest1' doesn't exist' on query 'insert into davetest1(name) values('hello')'. Default database: 'repmaster', Error_code: 1146 040426 14:32:07 Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'jimbo-bin.009' position 111085563 040426 14:32:07 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 158930337 040426 14:32:10 Slave I/O thread exiting, read up to log 'jimbo-bin.009', position 158930458 040426 14:32:10 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 158930458 040426 14:33:11 Slave I/O thread exiting, read up to log 'jimbo-bin.009', position 158930651 040426 14:33:11 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 158930651 040426 14:33:15 Slave I/O thread exiting, read up to log 'jimbo-bin.009', position 158930651 040426 14:33:33 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 158930651 InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 241.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:39:48 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 23 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 242.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24488 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24465, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 718 localhost root ---TRANSACTION 0 24487, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 740 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 703 OS file writes, 669 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.78 writes/s, 0.78 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.78 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1989186 Log flushed up to 0 1989108 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 650 log i/o's done, 0.78 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 624, updated 0, deleted 765, read 26776 0.78 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:40:04 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 258.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24501 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24495, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 750 localhost root ---TRANSACTION 0 24500, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 755 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 715 OS file writes, 681 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.75 writes/s, 0.75 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.75 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1989946 Log flushed up to 0 1989884 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 662 log i/o's done, 0.75 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 636, updated 0, deleted 765, read 27404 0.75 inserts/s, 0.00 updates/s, 0.00 deletes/s, 39.25 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 273.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:40:20 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 274.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24514 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24495, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 750 localhost root ---TRANSACTION 0 24513, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 768 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 728 OS file writes, 694 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.81 writes/s, 0.81 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.81 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1990784 Log flushed up to 0 1990722 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 675 log i/o's done, 0.81 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 649, updated 0, deleted 765, read 27404 0.81 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:40:36 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 290.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24526 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24495, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 750 localhost root ---TRANSACTION 0 24525, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 780 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 740 OS file writes, 706 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.75 writes/s, 0.75 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.75 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1991544 Log flushed up to 0 1991482 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 687 log i/o's done, 0.75 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 661, updated 0, deleted 765, read 27404 0.75 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 305.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:40:52 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 306.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24540 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24495, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 750 localhost root ---TRANSACTION 0 24539, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 794 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 754 OS file writes, 720 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.87 writes/s, 0.87 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.87 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1992444 Log flushed up to 0 1992382 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 701 log i/o's done, 0.87 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 675, updated 0, deleted 765, read 27404 0.87 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:41:08 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 322.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24550 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24495, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 750 localhost root ---TRANSACTION 0 24549, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 804 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 764 OS file writes, 730 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.62 writes/s, 0.62 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.62 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1993080 Log flushed up to 0 1993018 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 711 log i/o's done, 0.62 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 685, updated 0, deleted 765, read 27404 0.62 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 337.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:41:24 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 338.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24561 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24558, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 813 localhost root ---TRANSACTION 0 24560, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 815 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 774 OS file writes, 740 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.62 writes/s, 0.62 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.62 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1993716 Log flushed up to 0 1993654 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 721 log i/o's done, 0.62 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 695, updated 0, deleted 765, read 28094 0.62 inserts/s, 0.00 updates/s, 0.00 deletes/s, 43.12 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:41:40 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 354.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24571 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24558, not started, process no 7996, InnoDB: ###### Diagnostic info printed to the standard output OS thread id 2549476272 MySQL thread id 3, query id 813 localhost root ---TRANSACTION 0 24570, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 825 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 784 OS file writes, 750 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.62 writes/s, 0.62 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.62 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1994368 Log flushed up to 0 1994306 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 731 log i/o's done, 0.62 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 7 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 705, updated 0, deleted 765, read 28094 0.62 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 369.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:41:56 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 370.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24582 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24558, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 813 localhost root ---TRANSACTION 0 24581, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 836 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 795 OS file writes, 761 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.69 writes/s, 0.69 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.50 hash searches/s, 0.31 non-hash searches/s --- LOG --- Log sequence number 0 1995191 Log flushed up to 0 1995129 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 742 log i/o's done, 0.69 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 716, updated 0, deleted 765, read 28094 0.69 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:42:12 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 386.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24597 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24558, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 813 localhost root ---TRANSACTION 0 24596, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 851 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 810 OS file writes, 776 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.94 writes/s, 0.94 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.94 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1996153 Log flushed up to 0 1996091 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 757 log i/o's done, 0.94 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 731, updated 0, deleted 765, read 28094 0.94 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 401.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:42:28 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 402.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24610 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24607, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 862 localhost root ---TRANSACTION 0 24609, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 864 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 822 OS file writes, 788 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.75 writes/s, 0.75 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.75 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1996929 Log flushed up to 0 1996867 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 769 log i/o's done, 0.75 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 743, updated 0, deleted 765, read 28832 0.75 inserts/s, 0.00 updates/s, 0.00 deletes/s, 46.12 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:42:44 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 418.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24620 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24607, not started, process no 7996, InnoDB: ###### Diagnostic info printed to the standard output OS thread id 2549476272 MySQL thread id 3, query id 862 localhost root ---TRANSACTION 0 24619, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 874 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 832 OS file writes, 798 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.62 writes/s, 0.62 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.62 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1997565 Log flushed up to 0 1997503 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 779 log i/o's done, 0.62 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 753, updated 0, deleted 765, read 28832 0.62 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 433.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:43:00 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 434.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24634 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24607, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 862 localhost root ---TRANSACTION 0 24633, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 888 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 846 OS file writes, 812 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.87 writes/s, 0.87 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.87 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1998465 Log flushed up to 0 1998403 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 793 log i/o's done, 0.87 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 767, updated 0, deleted 765, read 28832 0.87 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:43:16 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 450.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24652 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24650, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 906 localhost root ---TRANSACTION 0 24651, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 907 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 860 OS file writes, 826 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.87 writes/s, 0.87 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.87 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 1999350 Log flushed up to 0 1999288 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 807 log i/o's done, 0.87 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 781, updated 0, deleted 765, read 31934 0.87 inserts/s, 0.00 updates/s, 0.00 deletes/s, 193.86 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 465.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:43:32 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 466.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24664 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24663, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 920 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 871 OS file writes, 837 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.69 writes/s, 0.69 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.69 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2000064 Log flushed up to 0 2000002 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 818 log i/o's done, 0.69 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 792, updated 0, deleted 765, read 32712 0.69 inserts/s, 0.00 updates/s, 0.00 deletes/s, 48.62 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:43:48 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 482.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24677 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, InnoDB: ###### Diagnostic info printed to the standard output OS thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24676, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 933 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 884 OS file writes, 850 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.81 writes/s, 0.81 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.81 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2000886 Log flushed up to 0 2000824 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 831 log i/o's done, 0.81 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 805, updated 0, deleted 765, read 32712 0.81 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 497.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:44:04 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 498.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24687 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24686, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 943 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 894 OS file writes, 860 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.62 writes/s, 0.62 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.62 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2001538 Log flushed up to 0 2001476 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 841 log i/o's done, 0.62 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 815, updated 0, deleted 765, read 32712 0.62 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:44:20 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 514.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24694 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24693, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 950 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 901 OS file writes, 867 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.44 writes/s, 0.44 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.44 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2001988 Log flushed up to 0 2001910 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 848 log i/o's done, 0.44 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 822, updated 0, deleted 765, read 32712 0.44 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 529.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:44:36 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 530.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24705 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24704, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 961 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 912 OS file writes, 878 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.69 writes/s, 0.69 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.69 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2002686 Log flushed up to 0 2002624 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 859 log i/o's done, 0.69 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 833, updated 0, deleted 765, read 32712 0.69 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:44:52 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 546.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24718 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24717, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 974 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 925 OS file writes, 891 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.81 writes/s, 0.81 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.81 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2003524 Log flushed up to 0 2003446 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 872 log i/o's done, 0.81 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 846, updated 0, deleted 765, read 32712 0.81 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 561.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:45:08 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 562.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24729 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24728, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 985 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 936 OS file writes, 902 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.69 writes/s, 0.69 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.69 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2004222 Log flushed up to 0 2004160 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 883 log i/o's done, 0.69 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 857, updated 0, deleted 765, read 32712 0.69 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:45:24 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 578.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24741 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OInnoDB: ###### Diagnostic info printed to the standard output S thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24740, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 997 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 948 OS file writes, 914 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.75 writes/s, 0.75 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.75 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2004982 Log flushed up to 0 2004920 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 895 log i/o's done, 0.75 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 869, updated 0, deleted 765, read 32712 0.75 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 593.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info: ===================================== 040426 14:45:40 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 594.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24754 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, OS thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24753, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 1010 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 961 OS file writes, 927 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.81 writes/s, 0.81 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.81 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2005820 Log flushed up to 0 2005758 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 908 log i/o's done, 0.81 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 882, updated 0, deleted 765, read 32712 0.81 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ ===================================== 040426 14:45:56 INNODB MONITOR OUTPUT ===================================== Per second averages calculated from the last 16 seconds ---------- SEMAPHORES ---------- OS WAIT ARRAY INFO: reservation count 14, signal count 13 --Thread 2963024816 has waited at log0log.c line 1876 for 610.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 Mutex spin waits 2, rounds 40, OS waits 0 RW-shared spins 23, OS waits 12; RW-excl spins 3, OS waits 2 ------------ TRANSACTIONS ------------ Trx id counter 0 24767 Purge done for trx's n:o < 0 23824 undo n:o < 0 0 Total number of lock structs in row lock hash table 0 LIST OF TRANSACTIONS FOR EACH SESSION: ---TRANSACTION 0 24652, not started, process no 7996, InnoDB: ###### Diagnostic info printed to the standard output OS thread id 2549476272 MySQL thread id 3, query id 909 localhost root ---TRANSACTION 0 24766, COMMITTED IN MEMORY, process no 7996, OS thread id 2549271472 committing mysql tables in use 1, locked 1 , undo log entries 1 MySQL thread id 9, query id 1023 update insert into davetest1(name) values('hello') -------- FILE I/O -------- I/O thread 0 state: waiting for i/o request (insert buffer thread) I/O thread 1 state: doing file i/o (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: 1, sync i/o's: 0 Pending flushes (fsync) log: 1; buffer pool: 0 70 OS file reads, 974 OS file writes, 940 OS fsyncs 0 pending preads, 1 pending pwrites 0.00 reads/s, 0 avg bytes/read, 0.81 writes/s, 0.81 fsyncs/s ------------------------------------- INSERT BUFFER AND ADAPTIVE HASH INDEX ------------------------------------- Ibuf for space 0: size 1, free list len 0, seg size 2, 0 inserts, 0 merged recs, 0 merges Hash table size 34679, used cells 1, node heap has 1 buffer(s) 0.81 hash searches/s, 0.00 non-hash searches/s --- LOG --- Log sequence number 0 2006658 Log flushed up to 0 2006596 Last checkpoint at 0 1934642 1 pending log writes, 1 pending chkp writes 921 log i/o's done, 0.81 log i/o's/second ---------------------- BUFFER POOL AND MEMORY ---------------------- Total memory allocated 18867600; in additional pool allocated 957056 Buffer pool size 512 Free buffers 486 Database pages 25 Modified db pages 8 Pending reads 0 Pending writes: LRU 0, flush list 0, single page 0 Pages read 24, created 1, written 69 0.00 reads/s, 0.00 creates/s, 0.00 writes/s Buffer pool hit rate 1000 / 1000 -------------- ROW OPERATIONS -------------- 0 queries inside InnoDB, 0 queries in queue Main thread process no. 7996, id 2963024816, state: making checkpoint Number of rows inserted 895, updated 0, deleted 765, read 32712 0.81 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s ---------------------------- END OF INNODB MONITOR OUTPUT ============================ InnoDB: Warning: a long semaphore wait: --Thread 2963024816 has waited at log0log.c line 1876 for 625.00 seconds the semaphore: S-lock on RW-latch at b51d19a0 created in file log0log.c line 749 a writer (thread id 2963024816) has reserved it in mode exclusive number of readers 0, waiters flag 1 Last time read locked in file log0log.c line 1876 Last time write locked in file log0log.c line 1678 InnoDB: Error: semaphore wait has lasted > 600 seconds InnoDB: We intentionally crash the server, because it appears to be hung. 040426 14:46:11 InnoDB: Assertion failure in thread 2973514672 in file sync0arr.c line 934 InnoDB: Failing assertion: 0 InnoDB: We intentionally generate a memory trap. InnoDB: Send a detailed bug report to mysql@lists.mysql.com mysqld got signal 11; This could be because you hit a bug. It is also possible that this binary or one of the libraries it was linked against is corrupt, improperly built, or misconfigured. This error can also be caused by malfunctioning hardware. We will try our best to scrape up some info that will hopefully help diagnose the problem, but since we have already crashed, something is definitely wrong and this may fail. key_buffer_size=402653184 read_buffer_size=2093056 max_used_connections=0 max_connections=100 threads_connected=1 It is possible that mysqld could use up to key_buffer_size + (read_buffer_size + sort_buffer_size)*max_connections = 802415 K bytes of memory Hope that's ok; if not, decrease some variables in the equation. thd=(nil) Attempting backtrace. You can use the following information to find out where mysqld died. If you see no messages after this, something went terribly wrong... Cannot determine thread, fp=0xb13c355c, backtrace may not be correct. Stack range sanity check OK, backtrace follows: 0x80f7893 0xb756ae48 (nil) 0xb7564dac 0xb7424a8a New value of fp=(nil) failed sanity check, terminating stack trace! Please read http://www.mysql.com/doc/en/Using_stack_trace.html and follow instructions on how to resolve the stack trace. Resolved stack trace is much more helpful in diagnosing the problem, so please do resolve it The manual page at http://www.mysql.com/doc/en/Crashing.html contains information that should help you find out what is causing the crash. Number of processes running now: 0 040426 14:46:11 mysqld restarted 040426 14:46:11 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 1964179 InnoDB: Doing recovery: scanned up to log sequence number 0 2007620 040426 14:46:11 InnoDB: Starting an apply batch of log records to the database... InnoDB: Progress in percents: 98 99 InnoDB: Apply batch completed InnoDB: In a MySQL replication slave the last master binlog file InnoDB: position 0 159031633, file name jimbo-bin.009 InnoDB: Last MySQL binlog file position 0 362, file name ./wonko-bin.022 040426 14:46:12 InnoDB: Flushing modified pages from the buffer pool... 040426 14:46:13 InnoDB: Started 040426 14:46:13 Slave I/O thread: connected to master 'repl@10.10.190.26:3306', replication started in log 'jimbo-bin.009' at position 195395122 /usr/local/libexec/mysqld: ready for connections. Version: '4.0.18-log' socket: '/tmp/mysql.sock' port: 3306 ERROR: 1062 Duplicate entry '908' for key 1 040426 14:46:13 Slave: Error 'Duplicate entry '908' for key 1' on query 'insert into davetest1(name) values('hello')'. Default database: 'repmaster', Error_code: 1062 040426 14:46:13 Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'jimbo-bin.009' position 159031522