public
Created

  • Download Gist
gistfile1.txt
1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113 114 115 116 117 118 119 120 121 122 123 124 125 126 127 128 129 130 131 132 133 134 135 136 137 138 139 140 141 142 143 144 145 146 147 148 149 150 151 152 153 154 155 156 157 158 159 160 161 162 163 164 165 166 167 168 169 170 171 172 173 174 175 176 177 178 179 180 181 182 183 184 185 186 187 188 189 190 191 192 193 194 195 196
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

Please sign in to comment on this gist.

Something went wrong with that request. Please try again.