Skip to content

@omega8cc /gist:3486469
Created

Embed URL

HTTPS clone URL

Subversion checkout URL

You can clone with
or
.
Download ZIP
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
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Something went wrong with that request. Please try again.