gistfile1.txt

Aug 27 06:44:32 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:44:32 v242a mysqld: --Thread 140447361210112 has waited at buf0lru.c line 1102 for 241.00 seconds the semaphore:
Aug 27 06:44:32 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:32 v242a mysqld: waiters flag 1
Aug 27 06:44:32 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:44:32 v242a mysqld: --Thread 140447397070592 has waited at ha_innodb.cc line 7986 for 241.00 seconds the semaphore:
Aug 27 06:44:32 v242a mysqld: X-lock (wait_ex) on RW-latch at 0x1099c00 '&dict_operation_lock'
Aug 27 06:44:32 v242a mysqld: a writer (thread id 140447397070592) has reserved it in mode  wait exclusive
Aug 27 06:44:32 v242a mysqld: number of readers 1, waiters flag 0, lock_word: ffffffffffffffff
Aug 27 06:44:32 v242a mysqld: Last time read locked in file row0purge.c line 680
Aug 27 06:44:32 v242a mysqld: Last time write locked in file /home/jenkins/workspace/percona-server-5.5-debs/label_exp/debian6-64/target/Percona-Server-5.5.27-rel28.0/storage/innobase/row/row0mysql.c line 3270
Aug 27 06:44:32 v242a mysqld: InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
Aug 27 06:44:32 v242a mysqld: InnoDB: Pending preads 0, pwrites 0
Aug 27 06:44:38 v242a mysqld:
Aug 27 06:44:38 v242a mysqld: =====================================
Aug 27 06:44:38 v242a mysqld: 120827  6:44:38 INNODB MONITOR OUTPUT
Aug 27 06:44:38 v242a mysqld: =====================================
Aug 27 06:44:38 v242a mysqld: Per second averages calculated from the last 21 seconds
Aug 27 06:44:38 v242a mysqld: -----------------
Aug 27 06:44:38 v242a mysqld: BACKGROUND THREAD
Aug 27 06:44:38 v242a mysqld: -----------------
Aug 27 06:44:38 v242a mysqld: srv_master_thread loops: 71305 1_second, 71304 sleeps, 6607 10_second, 5618 background, 5618 flush
Aug 27 06:44:38 v242a mysqld: srv_master_thread log flush and writes: 110776
Aug 27 06:44:38 v242a mysqld: ----------
Aug 27 06:44:38 v242a mysqld: SEMAPHORES
Aug 27 06:44:38 v242a mysqld: ----------
Aug 27 06:44:38 v242a mysqld: OS WAIT ARRAY INFO: reservation count 8130, signal count 8660
Aug 27 06:44:38 v242a mysqld: --Thread 140447361210112 has waited at buf0lru.c line 1102 for 247.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447397070592 has waited at ha_innodb.cc line 7986 for 247.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: X-lock (wait_ex) on RW-latch at 0x1099c00 '&dict_operation_lock'
Aug 27 06:44:38 v242a mysqld: a writer (thread id 140447397070592) has reserved it in mode  wait exclusive
Aug 27 06:44:38 v242a mysqld: number of readers 1, waiters flag 0, lock_word: ffffffffffffffff
Aug 27 06:44:38 v242a mysqld: Last time read locked in file row0purge.c line 680
Aug 27 06:44:38 v242a mysqld: Last time write locked in file /home/jenkins/workspace/percona-server-5.5-debs/label_exp/debian6-64/target/Percona-Server-5.5.27-rel28.0/storage/innobase/row/row0mysql.c line 3270
Aug 27 06:44:38 v242a mysqld: --Thread 140447369602816 has waited at buf0flu.c line 1887 for 246.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447396804352 has waited at buf0lru.c line 1102 for 218.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447351482112 has waited at buf0lru.c line 1102 for 218.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447352551168 has waited at buf0lru.c line 1102 for 217.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447352014592 has waited at buf0lru.c line 1102 for 217.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447396538112 has waited at buf0lru.c line 1102 for 217.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447352280832 has waited at buf0lru.c line 1102 for 158.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447351748352 has waited at buf0lru.c line 1102 for 158.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: --Thread 140447395739392 has waited at buf0lru.c line 1102 for 158.00 seconds the semaphore:
Aug 27 06:44:38 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:44:38 v242a mysqld: waiters flag 1
Aug 27 06:44:38 v242a mysqld: Mutex spin waits 31022, rounds 124453, OS waits 933
Aug 27 06:44:38 v242a mysqld: RW-shared spins 7758, rounds 210908, OS waits 6745
Aug 27 06:44:38 v242a mysqld: RW-excl spins 881, rounds 21481, OS waits 396
Aug 27 06:44:38 v242a mysqld: Spin rounds per wait: 4.01 mutex, 27.19 RW-shared, 24.38 RW-excl
Aug 27 06:44:38 v242a mysqld: --------
Aug 27 06:44:38 v242a mysqld: FILE I/O
Aug 27 06:44:38 v242a mysqld: --------
Aug 27 06:44:38 v242a mysqld: I/O thread 0 state: waiting for completed aio requests (insert buffer thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 1 state: waiting for completed aio requests (log thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 2 state: waiting for completed aio requests (read thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 3 state: waiting for completed aio requests (read thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 4 state: waiting for completed aio requests (read thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 5 state: waiting for completed aio requests (read thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 6 state: waiting for completed aio requests (write thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 7 state: waiting for completed aio requests (write thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 8 state: waiting for completed aio requests (write thread)
Aug 27 06:44:38 v242a mysqld: I/O thread 9 state: waiting for completed aio requests (write thread)
Aug 27 06:44:38 v242a mysqld: Pending normal aio reads: 0 [0, 0, 0, 0] , aio writes: 0 [0, 0, 0, 0] ,
Aug 27 06:44:38 v242a mysqld:  ibuf aio reads: 0, log i/o's: 0, sync i/o's: 0
Aug 27 06:44:38 v242a mysqld: Pending flushes (fsync) log: 0; buffer pool: 0
Aug 27 06:44:38 v242a mysqld: 7268 OS file reads, 2388084 OS file writes, 177723 OS fsyncs
Aug 27 06:44:38 v242a mysqld: 0.00 reads/s, 0 avg bytes/read, 0.00 writes/s, 0.00 fsyncs/s
Aug 27 06:44:38 v242a mysqld: -------------------------------------
Aug 27 06:44:38 v242a mysqld: INSERT BUFFER AND ADAPTIVE HASH INDEX
Aug 27 06:44:38 v242a mysqld: -------------------------------------
Aug 27 06:44:38 v242a mysqld: Ibuf: size 1, free list len 8, seg size 10, 16 merges
Aug 27 06:44:38 v242a mysqld: merged operations:
Aug 27 06:44:38 v242a mysqld:  insert 15, delete mark 709, delete 93
Aug 27 06:44:38 v242a mysqld: discarded operations:
Aug 27 06:44:38 v242a mysqld:  insert 0, delete mark 0, delete 0
Aug 27 06:44:38 v242a mysqld: Hash table size 2323939, node heap has 171 buffer(s)
Aug 27 06:44:38 v242a mysqld: 0.00 hash searches/s, 0.00 non-hash searches/s
Aug 27 06:44:38 v242a mysqld: ---
Aug 27 06:44:38 v242a mysqld: LOG
Aug 27 06:44:38 v242a mysqld: ---
Aug 27 06:44:38 v242a mysqld: Log sequence number 5309029709
Aug 27 06:44:38 v242a mysqld: Log flushed up to   5308355653
Aug 27 06:44:38 v242a mysqld: Last checkpoint at  5306459525
Aug 27 06:44:38 v242a mysqld: Max checkpoint age    7310501
Aug 27 06:44:38 v242a mysqld: Checkpoint age target 7082048
Aug 27 06:44:38 v242a mysqld: Modified age          2570184
Aug 27 06:44:38 v242a mysqld: Checkpoint age        2570184
Aug 27 06:44:38 v242a mysqld: 0 pending log writes, 0 pending chkp writes
Aug 27 06:44:38 v242a mysqld: 1278391 log i/o's done, 0.00 log i/o's/second
Aug 27 06:44:38 v242a mysqld: ----------------------
Aug 27 06:44:38 v242a mysqld: BUFFER POOL AND MEMORY
Aug 27 06:44:38 v242a mysqld: ----------------------
Aug 27 06:44:38 v242a mysqld: Total memory allocated 1083719680; in additional pool allocated 0
Aug 27 06:44:38 v242a mysqld: Internal hash tables (constant factor + variable factor)
Aug 27 06:44:38 v242a mysqld:     Adaptive hash index 21397392 #011(18591512 + 2805880)
Aug 27 06:44:38 v242a mysqld:     Page hash           1163048 (buffer pool 0 only)
Aug 27 06:44:38 v242a mysqld:     Dictionary cache    13254145 #011(4649872 + 8604273)
Aug 27 06:44:38 v242a mysqld:     File system         511232 #011(82672 + 428560)
Aug 27 06:44:38 v242a mysqld:     Lock system         2617616 #011(2610472 + 7144)
Aug 27 06:44:38 v242a mysqld:     Recovery system     0 #011(0 + 0)
Aug 27 06:44:38 v242a mysqld: Dictionary memory allocated 8604273
Aug 27 06:45:02 v242a mysqld: InnoDB: ###### Diagnostic info printed to the standard error stream
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447361210112 has waited at buf0lru.c line 1102 for 272.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:03 v242a mysqld: waiters flag 1
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447397070592 has waited at ha_innodb.cc line 7986 for 272.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: X-lock (wait_ex) on RW-latch at 0x1099c00 '&dict_operation_lock'
Aug 27 06:45:03 v242a mysqld: a writer (thread id 140447397070592) has reserved it in mode  wait exclusive
Aug 27 06:45:03 v242a mysqld: number of readers 1, waiters flag 0, lock_word: ffffffffffffffff
Aug 27 06:45:03 v242a mysqld: Last time read locked in file row0purge.c line 680
Aug 27 06:45:03 v242a mysqld: Last time write locked in file /home/jenkins/workspace/percona-server-5.5-debs/label_exp/debian6-64/target/Percona-Server-5.5.27-rel28.0/storage/innobase/row/row0mysql.c line 3270
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447369602816 has waited at buf0flu.c line 1887 for 271.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:03 v242a mysqld: waiters flag 1
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447396804352 has waited at buf0lru.c line 1102 for 243.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:03 v242a mysqld: waiters flag 1
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447351482112 has waited at buf0lru.c line 1102 for 243.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:03 v242a mysqld: waiters flag 1
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447352551168 has waited at buf0lru.c line 1102 for 242.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:03 v242a mysqld: waiters flag 1
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447352014592 has waited at buf0lru.c line 1102 for 242.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:03 v242a mysqld: waiters flag 1
Aug 27 06:45:03 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:03 v242a mysqld: --Thread 140447396538112 has waited at buf0lru.c line 1102 for 242.00 seconds the semaphore:
Aug 27 06:45:03 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:03 v242a mysqld: waiters flag 1
Aug 27 06:45:03 v242a mysqld: InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
Aug 27 06:45:03 v242a mysqld: InnoDB: Pending preads 0, pwrites 0
Aug 27 06:45:33 v242a mysqld: InnoDB: ###### Diagnostic info printed to the standard error stream
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447361210112 has waited at buf0lru.c line 1102 for 303.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:34 v242a mysqld: waiters flag 1
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447397070592 has waited at ha_innodb.cc line 7986 for 303.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: X-lock (wait_ex) on RW-latch at 0x1099c00 '&dict_operation_lock'
Aug 27 06:45:34 v242a mysqld: a writer (thread id 140447397070592) has reserved it in mode  wait exclusive
Aug 27 06:45:34 v242a mysqld: number of readers 1, waiters flag 0, lock_word: ffffffffffffffff
Aug 27 06:45:34 v242a mysqld: Last time read locked in file row0purge.c line 680
Aug 27 06:45:34 v242a mysqld: Last time write locked in file /home/jenkins/workspace/percona-server-5.5-debs/label_exp/debian6-64/target/Percona-Server-5.5.27-rel28.0/storage/innobase/row/row0mysql.c line 3270
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447369602816 has waited at buf0flu.c line 1887 for 302.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:34 v242a mysqld: waiters flag 1
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447396804352 has waited at buf0lru.c line 1102 for 274.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:34 v242a mysqld: waiters flag 1
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447351482112 has waited at buf0lru.c line 1102 for 274.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:34 v242a mysqld: waiters flag 1
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447352551168 has waited at buf0lru.c line 1102 for 273.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:34 v242a mysqld: waiters flag 1
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447352014592 has waited at buf0lru.c line 1102 for 273.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:34 v242a mysqld: waiters flag 1
Aug 27 06:45:34 v242a mysqld: InnoDB: Warning: a long semaphore wait:
Aug 27 06:45:34 v242a mysqld: --Thread 140447396538112 has waited at buf0lru.c line 1102 for 273.00 seconds the semaphore:
Aug 27 06:45:34 v242a mysqld: Mutex at 0x356ae08 '&buf_pool->mutex', lock var 1
Aug 27 06:45:34 v242a mysqld: waiters flag 1
Aug 27 06:45:34 v242a mysqld: InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
Aug 27 06:45:34 v242a mysqld: InnoDB: Pending preads 0, pwrites 0
Aug 27 06:46:04 v242a mysqld: InnoDB: ###### Diagnostic info printed to the standard error stream