| <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN" "http://www.w3.org/TR/html4/loose.dtd"> |
| <html lang="en"> |
| <head> |
| <title>Source code</title> |
| <link rel="stylesheet" type="text/css" href="../../../../../../stylesheet.css" title="Style"> |
| </head> |
| <body> |
| <div class="sourceContainer"> |
| <pre><span class="sourceLineNo">001</span>/*<a name="line.1"></a> |
| <span class="sourceLineNo">002</span> * Licensed to the Apache Software Foundation (ASF) under one<a name="line.2"></a> |
| <span class="sourceLineNo">003</span> * or more contributor license agreements. See the NOTICE file<a name="line.3"></a> |
| <span class="sourceLineNo">004</span> * distributed with this work for additional information<a name="line.4"></a> |
| <span class="sourceLineNo">005</span> * regarding copyright ownership. The ASF licenses this file<a name="line.5"></a> |
| <span class="sourceLineNo">006</span> * to you under the Apache License, Version 2.0 (the<a name="line.6"></a> |
| <span class="sourceLineNo">007</span> * "License"); you may not use this file except in compliance<a name="line.7"></a> |
| <span class="sourceLineNo">008</span> * with the License. You may obtain a copy of the License at<a name="line.8"></a> |
| <span class="sourceLineNo">009</span> *<a name="line.9"></a> |
| <span class="sourceLineNo">010</span> * http://www.apache.org/licenses/LICENSE-2.0<a name="line.10"></a> |
| <span class="sourceLineNo">011</span> *<a name="line.11"></a> |
| <span class="sourceLineNo">012</span> * Unless required by applicable law or agreed to in writing, software<a name="line.12"></a> |
| <span class="sourceLineNo">013</span> * distributed under the License is distributed on an "AS IS" BASIS,<a name="line.13"></a> |
| <span class="sourceLineNo">014</span> * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.<a name="line.14"></a> |
| <span class="sourceLineNo">015</span> * See the License for the specific language governing permissions and<a name="line.15"></a> |
| <span class="sourceLineNo">016</span> * limitations under the License.<a name="line.16"></a> |
| <span class="sourceLineNo">017</span> */<a name="line.17"></a> |
| <span class="sourceLineNo">018</span>package org.apache.hadoop.hbase.regionserver;<a name="line.18"></a> |
| <span class="sourceLineNo">019</span><a name="line.19"></a> |
| <span class="sourceLineNo">020</span>import static org.junit.Assert.assertTrue;<a name="line.20"></a> |
| <span class="sourceLineNo">021</span><a name="line.21"></a> |
| <span class="sourceLineNo">022</span>import java.io.IOException;<a name="line.22"></a> |
| <span class="sourceLineNo">023</span>import java.util.NavigableMap;<a name="line.23"></a> |
| <span class="sourceLineNo">024</span>import java.util.TreeMap;<a name="line.24"></a> |
| <span class="sourceLineNo">025</span>import java.util.concurrent.CountDownLatch;<a name="line.25"></a> |
| <span class="sourceLineNo">026</span>import java.util.concurrent.TimeUnit;<a name="line.26"></a> |
| <span class="sourceLineNo">027</span>import org.apache.hadoop.conf.Configuration;<a name="line.27"></a> |
| <span class="sourceLineNo">028</span>import org.apache.hadoop.fs.FileSystem;<a name="line.28"></a> |
| <span class="sourceLineNo">029</span>import org.apache.hadoop.fs.Path;<a name="line.29"></a> |
| <span class="sourceLineNo">030</span>import org.apache.hadoop.hbase.CellScanner;<a name="line.30"></a> |
| <span class="sourceLineNo">031</span>import org.apache.hadoop.hbase.HBaseClassTestRule;<a name="line.31"></a> |
| <span class="sourceLineNo">032</span>import org.apache.hadoop.hbase.HBaseTestingUtil;<a name="line.32"></a> |
| <span class="sourceLineNo">033</span>import org.apache.hadoop.hbase.HConstants;<a name="line.33"></a> |
| <span class="sourceLineNo">034</span>import org.apache.hadoop.hbase.TableName;<a name="line.34"></a> |
| <span class="sourceLineNo">035</span>import org.apache.hadoop.hbase.client.Durability;<a name="line.35"></a> |
| <span class="sourceLineNo">036</span>import org.apache.hadoop.hbase.client.Put;<a name="line.36"></a> |
| <span class="sourceLineNo">037</span>import org.apache.hadoop.hbase.regionserver.wal.FSHLog;<a name="line.37"></a> |
| <span class="sourceLineNo">038</span>import org.apache.hadoop.hbase.testclassification.MediumTests;<a name="line.38"></a> |
| <span class="sourceLineNo">039</span>import org.apache.hadoop.hbase.testclassification.RegionServerTests;<a name="line.39"></a> |
| <span class="sourceLineNo">040</span>import org.apache.hadoop.hbase.util.Bytes;<a name="line.40"></a> |
| <span class="sourceLineNo">041</span>import org.apache.hadoop.hbase.util.EnvironmentEdgeManager;<a name="line.41"></a> |
| <span class="sourceLineNo">042</span>import org.apache.hadoop.hbase.util.EnvironmentEdgeManagerTestHelper;<a name="line.42"></a> |
| <span class="sourceLineNo">043</span>import org.apache.hadoop.hbase.util.Threads;<a name="line.43"></a> |
| <span class="sourceLineNo">044</span>import org.apache.hadoop.hbase.wal.WAL;<a name="line.44"></a> |
| <span class="sourceLineNo">045</span>import org.apache.hadoop.hbase.wal.WALEdit;<a name="line.45"></a> |
| <span class="sourceLineNo">046</span>import org.apache.hadoop.hbase.wal.WALKeyImpl;<a name="line.46"></a> |
| <span class="sourceLineNo">047</span>import org.apache.hadoop.hbase.wal.WALProvider.Writer;<a name="line.47"></a> |
| <span class="sourceLineNo">048</span>import org.junit.After;<a name="line.48"></a> |
| <span class="sourceLineNo">049</span>import org.junit.Assert;<a name="line.49"></a> |
| <span class="sourceLineNo">050</span>import org.junit.Before;<a name="line.50"></a> |
| <span class="sourceLineNo">051</span>import org.junit.ClassRule;<a name="line.51"></a> |
| <span class="sourceLineNo">052</span>import org.junit.Rule;<a name="line.52"></a> |
| <span class="sourceLineNo">053</span>import org.junit.Test;<a name="line.53"></a> |
| <span class="sourceLineNo">054</span>import org.junit.experimental.categories.Category;<a name="line.54"></a> |
| <span class="sourceLineNo">055</span>import org.junit.rules.TestName;<a name="line.55"></a> |
| <span class="sourceLineNo">056</span>import org.mockito.Mockito;<a name="line.56"></a> |
| <span class="sourceLineNo">057</span>import org.slf4j.Logger;<a name="line.57"></a> |
| <span class="sourceLineNo">058</span>import org.slf4j.LoggerFactory;<a name="line.58"></a> |
| <span class="sourceLineNo">059</span><a name="line.59"></a> |
| <span class="sourceLineNo">060</span>import org.apache.hbase.thirdparty.com.google.common.io.Closeables;<a name="line.60"></a> |
| <span class="sourceLineNo">061</span><a name="line.61"></a> |
| <span class="sourceLineNo">062</span>/**<a name="line.62"></a> |
| <span class="sourceLineNo">063</span> * Testing for lock up of FSHLog.<a name="line.63"></a> |
| <span class="sourceLineNo">064</span> */<a name="line.64"></a> |
| <span class="sourceLineNo">065</span>@Category({ RegionServerTests.class, MediumTests.class })<a name="line.65"></a> |
| <span class="sourceLineNo">066</span>public class TestWALLockup {<a name="line.66"></a> |
| <span class="sourceLineNo">067</span><a name="line.67"></a> |
| <span class="sourceLineNo">068</span> @ClassRule<a name="line.68"></a> |
| <span class="sourceLineNo">069</span> public static final HBaseClassTestRule CLASS_RULE =<a name="line.69"></a> |
| <span class="sourceLineNo">070</span> HBaseClassTestRule.forClass(TestWALLockup.class);<a name="line.70"></a> |
| <span class="sourceLineNo">071</span><a name="line.71"></a> |
| <span class="sourceLineNo">072</span> private static final Logger LOG = LoggerFactory.getLogger(TestWALLockup.class);<a name="line.72"></a> |
| <span class="sourceLineNo">073</span><a name="line.73"></a> |
| <span class="sourceLineNo">074</span> @Rule<a name="line.74"></a> |
| <span class="sourceLineNo">075</span> public TestName name = new TestName();<a name="line.75"></a> |
| <span class="sourceLineNo">076</span><a name="line.76"></a> |
| <span class="sourceLineNo">077</span> private static final String COLUMN_FAMILY = "MyCF";<a name="line.77"></a> |
| <span class="sourceLineNo">078</span> private static final byte[] COLUMN_FAMILY_BYTES = Bytes.toBytes(COLUMN_FAMILY);<a name="line.78"></a> |
| <span class="sourceLineNo">079</span><a name="line.79"></a> |
| <span class="sourceLineNo">080</span> private static HBaseTestingUtil TEST_UTIL = new HBaseTestingUtil();<a name="line.80"></a> |
| <span class="sourceLineNo">081</span> private static Configuration CONF;<a name="line.81"></a> |
| <span class="sourceLineNo">082</span> private String dir;<a name="line.82"></a> |
| <span class="sourceLineNo">083</span><a name="line.83"></a> |
| <span class="sourceLineNo">084</span> // Test names<a name="line.84"></a> |
| <span class="sourceLineNo">085</span> protected TableName tableName;<a name="line.85"></a> |
| <span class="sourceLineNo">086</span><a name="line.86"></a> |
| <span class="sourceLineNo">087</span> @Before<a name="line.87"></a> |
| <span class="sourceLineNo">088</span> public void setup() throws IOException {<a name="line.88"></a> |
| <span class="sourceLineNo">089</span> CONF = TEST_UTIL.getConfiguration();<a name="line.89"></a> |
| <span class="sourceLineNo">090</span> // Disable block cache.<a name="line.90"></a> |
| <span class="sourceLineNo">091</span> CONF.setFloat(HConstants.HFILE_BLOCK_CACHE_SIZE_KEY, 0f);<a name="line.91"></a> |
| <span class="sourceLineNo">092</span> dir = TEST_UTIL.getDataTestDir("TestHRegion").toString();<a name="line.92"></a> |
| <span class="sourceLineNo">093</span> tableName = TableName.valueOf(name.getMethodName());<a name="line.93"></a> |
| <span class="sourceLineNo">094</span> }<a name="line.94"></a> |
| <span class="sourceLineNo">095</span><a name="line.95"></a> |
| <span class="sourceLineNo">096</span> @After<a name="line.96"></a> |
| <span class="sourceLineNo">097</span> public void tearDown() throws Exception {<a name="line.97"></a> |
| <span class="sourceLineNo">098</span> EnvironmentEdgeManagerTestHelper.reset();<a name="line.98"></a> |
| <span class="sourceLineNo">099</span> LOG.info("Cleaning test directory: " + TEST_UTIL.getDataTestDir());<a name="line.99"></a> |
| <span class="sourceLineNo">100</span> TEST_UTIL.cleanupTestDir();<a name="line.100"></a> |
| <span class="sourceLineNo">101</span> }<a name="line.101"></a> |
| <span class="sourceLineNo">102</span><a name="line.102"></a> |
| <span class="sourceLineNo">103</span> private String getName() {<a name="line.103"></a> |
| <span class="sourceLineNo">104</span> return name.getMethodName();<a name="line.104"></a> |
| <span class="sourceLineNo">105</span> }<a name="line.105"></a> |
| <span class="sourceLineNo">106</span><a name="line.106"></a> |
| <span class="sourceLineNo">107</span> // A WAL that we can have throw exceptions when a flag is set.<a name="line.107"></a> |
| <span class="sourceLineNo">108</span> private static final class DodgyFSLog extends FSHLog {<a name="line.108"></a> |
| <span class="sourceLineNo">109</span> // Set this when want the WAL to start throwing exceptions.<a name="line.109"></a> |
| <span class="sourceLineNo">110</span> volatile boolean throwException = false;<a name="line.110"></a> |
| <span class="sourceLineNo">111</span><a name="line.111"></a> |
| <span class="sourceLineNo">112</span> // Latch to hold up processing until after another operation has had time to run.<a name="line.112"></a> |
| <span class="sourceLineNo">113</span> CountDownLatch latch = new CountDownLatch(1);<a name="line.113"></a> |
| <span class="sourceLineNo">114</span><a name="line.114"></a> |
| <span class="sourceLineNo">115</span> public DodgyFSLog(FileSystem fs, Path root, String logDir, Configuration conf)<a name="line.115"></a> |
| <span class="sourceLineNo">116</span> throws IOException {<a name="line.116"></a> |
| <span class="sourceLineNo">117</span> super(fs, root, logDir, conf);<a name="line.117"></a> |
| <span class="sourceLineNo">118</span> }<a name="line.118"></a> |
| <span class="sourceLineNo">119</span><a name="line.119"></a> |
| <span class="sourceLineNo">120</span> @Override<a name="line.120"></a> |
| <span class="sourceLineNo">121</span> protected void afterCreatingZigZagLatch() {<a name="line.121"></a> |
| <span class="sourceLineNo">122</span> // If throwException set, then append will throw an exception causing the WAL to be<a name="line.122"></a> |
| <span class="sourceLineNo">123</span> // rolled. We'll come in here. Hold up processing until a sync can get in before<a name="line.123"></a> |
| <span class="sourceLineNo">124</span> // the zigzag has time to complete its setup and get its own sync in. This is what causes<a name="line.124"></a> |
| <span class="sourceLineNo">125</span> // the lock up we've seen in production.<a name="line.125"></a> |
| <span class="sourceLineNo">126</span> if (throwException) {<a name="line.126"></a> |
| <span class="sourceLineNo">127</span> try {<a name="line.127"></a> |
| <span class="sourceLineNo">128</span> LOG.info("LATCHED");<a name="line.128"></a> |
| <span class="sourceLineNo">129</span> // So, timing can have it that the test can run and the bad flush below happens<a name="line.129"></a> |
| <span class="sourceLineNo">130</span> // before we get here. In this case, we'll be stuck waiting on this latch but there<a name="line.130"></a> |
| <span class="sourceLineNo">131</span> // is nothing in the WAL pipeline to get us to the below beforeWaitOnSafePoint...<a name="line.131"></a> |
| <span class="sourceLineNo">132</span> // because all WALs have rolled. In this case, just give up on test.<a name="line.132"></a> |
| <span class="sourceLineNo">133</span> if (!this.latch.await(5, TimeUnit.SECONDS)) {<a name="line.133"></a> |
| <span class="sourceLineNo">134</span> LOG.warn("GIVE UP! Failed waiting on latch...Test is ABORTED!");<a name="line.134"></a> |
| <span class="sourceLineNo">135</span> }<a name="line.135"></a> |
| <span class="sourceLineNo">136</span> } catch (InterruptedException e) {<a name="line.136"></a> |
| <span class="sourceLineNo">137</span> }<a name="line.137"></a> |
| <span class="sourceLineNo">138</span> }<a name="line.138"></a> |
| <span class="sourceLineNo">139</span> }<a name="line.139"></a> |
| <span class="sourceLineNo">140</span><a name="line.140"></a> |
| <span class="sourceLineNo">141</span> @Override<a name="line.141"></a> |
| <span class="sourceLineNo">142</span> protected void beforeWaitOnSafePoint() {<a name="line.142"></a> |
| <span class="sourceLineNo">143</span> if (throwException) {<a name="line.143"></a> |
| <span class="sourceLineNo">144</span> LOG.info("COUNTDOWN");<a name="line.144"></a> |
| <span class="sourceLineNo">145</span> // Don't countdown latch until someone waiting on it otherwise, the above<a name="line.145"></a> |
| <span class="sourceLineNo">146</span> // afterCreatingZigZagLatch will get to the latch and no one will ever free it and we'll<a name="line.146"></a> |
| <span class="sourceLineNo">147</span> // be stuck; test won't go down<a name="line.147"></a> |
| <span class="sourceLineNo">148</span> while (this.latch.getCount() <= 0)<a name="line.148"></a> |
| <span class="sourceLineNo">149</span> Threads.sleep(1);<a name="line.149"></a> |
| <span class="sourceLineNo">150</span> this.latch.countDown();<a name="line.150"></a> |
| <span class="sourceLineNo">151</span> }<a name="line.151"></a> |
| <span class="sourceLineNo">152</span> }<a name="line.152"></a> |
| <span class="sourceLineNo">153</span><a name="line.153"></a> |
| <span class="sourceLineNo">154</span> @Override<a name="line.154"></a> |
| <span class="sourceLineNo">155</span> protected Writer createWriterInstance(Path path) throws IOException {<a name="line.155"></a> |
| <span class="sourceLineNo">156</span> final Writer w = super.createWriterInstance(path);<a name="line.156"></a> |
| <span class="sourceLineNo">157</span> return new Writer() {<a name="line.157"></a> |
| <span class="sourceLineNo">158</span> @Override<a name="line.158"></a> |
| <span class="sourceLineNo">159</span> public void close() throws IOException {<a name="line.159"></a> |
| <span class="sourceLineNo">160</span> w.close();<a name="line.160"></a> |
| <span class="sourceLineNo">161</span> }<a name="line.161"></a> |
| <span class="sourceLineNo">162</span><a name="line.162"></a> |
| <span class="sourceLineNo">163</span> @Override<a name="line.163"></a> |
| <span class="sourceLineNo">164</span> public void sync(boolean forceSync) throws IOException {<a name="line.164"></a> |
| <span class="sourceLineNo">165</span> if (throwException) {<a name="line.165"></a> |
| <span class="sourceLineNo">166</span> throw new IOException("FAKE! Failed to replace a bad datanode...SYNC");<a name="line.166"></a> |
| <span class="sourceLineNo">167</span> }<a name="line.167"></a> |
| <span class="sourceLineNo">168</span> w.sync(forceSync);<a name="line.168"></a> |
| <span class="sourceLineNo">169</span> }<a name="line.169"></a> |
| <span class="sourceLineNo">170</span><a name="line.170"></a> |
| <span class="sourceLineNo">171</span> @Override<a name="line.171"></a> |
| <span class="sourceLineNo">172</span> public void append(Entry entry) throws IOException {<a name="line.172"></a> |
| <span class="sourceLineNo">173</span> if (throwException) {<a name="line.173"></a> |
| <span class="sourceLineNo">174</span> throw new IOException("FAKE! Failed to replace a bad datanode...APPEND");<a name="line.174"></a> |
| <span class="sourceLineNo">175</span> }<a name="line.175"></a> |
| <span class="sourceLineNo">176</span> w.append(entry);<a name="line.176"></a> |
| <span class="sourceLineNo">177</span> }<a name="line.177"></a> |
| <span class="sourceLineNo">178</span><a name="line.178"></a> |
| <span class="sourceLineNo">179</span> @Override<a name="line.179"></a> |
| <span class="sourceLineNo">180</span> public long getLength() {<a name="line.180"></a> |
| <span class="sourceLineNo">181</span> return w.getLength();<a name="line.181"></a> |
| <span class="sourceLineNo">182</span> }<a name="line.182"></a> |
| <span class="sourceLineNo">183</span><a name="line.183"></a> |
| <span class="sourceLineNo">184</span> @Override<a name="line.184"></a> |
| <span class="sourceLineNo">185</span> public long getSyncedLength() {<a name="line.185"></a> |
| <span class="sourceLineNo">186</span> return w.getSyncedLength();<a name="line.186"></a> |
| <span class="sourceLineNo">187</span> }<a name="line.187"></a> |
| <span class="sourceLineNo">188</span> };<a name="line.188"></a> |
| <span class="sourceLineNo">189</span> }<a name="line.189"></a> |
| <span class="sourceLineNo">190</span> }<a name="line.190"></a> |
| <span class="sourceLineNo">191</span><a name="line.191"></a> |
| <span class="sourceLineNo">192</span> /**<a name="line.192"></a> |
| <span class="sourceLineNo">193</span> * Reproduce locking up that happens when we get an inopportune sync during setup for zigzaglatch<a name="line.193"></a> |
| <span class="sourceLineNo">194</span> * wait. See HBASE-14317. If below is broken, we will see this test timeout because it is locked<a name="line.194"></a> |
| <span class="sourceLineNo">195</span> * up.<a name="line.195"></a> |
| <span class="sourceLineNo">196</span> * <p><a name="line.196"></a> |
| <span class="sourceLineNo">197</span> * First I need to set up some mocks for Server and RegionServerServices. I also need to set up a<a name="line.197"></a> |
| <span class="sourceLineNo">198</span> * dodgy WAL that will throw an exception when we go to append to it.<a name="line.198"></a> |
| <span class="sourceLineNo">199</span> */<a name="line.199"></a> |
| <span class="sourceLineNo">200</span> @Test<a name="line.200"></a> |
| <span class="sourceLineNo">201</span> public void testLockupWhenSyncInMiddleOfZigZagSetup() throws IOException {<a name="line.201"></a> |
| <span class="sourceLineNo">202</span> // Mocked up server and regionserver services. Needed below.<a name="line.202"></a> |
| <span class="sourceLineNo">203</span> RegionServerServices services = Mockito.mock(RegionServerServices.class);<a name="line.203"></a> |
| <span class="sourceLineNo">204</span> Mockito.when(services.getConfiguration()).thenReturn(CONF);<a name="line.204"></a> |
| <span class="sourceLineNo">205</span> Mockito.when(services.isStopped()).thenReturn(false);<a name="line.205"></a> |
| <span class="sourceLineNo">206</span> Mockito.when(services.isAborted()).thenReturn(false);<a name="line.206"></a> |
| <span class="sourceLineNo">207</span><a name="line.207"></a> |
| <span class="sourceLineNo">208</span> // OK. Now I have my mocked up Server & RegionServerServices and dodgy WAL, go ahead with test.<a name="line.208"></a> |
| <span class="sourceLineNo">209</span> FileSystem fs = FileSystem.get(CONF);<a name="line.209"></a> |
| <span class="sourceLineNo">210</span> Path rootDir = new Path(dir + getName());<a name="line.210"></a> |
| <span class="sourceLineNo">211</span> DodgyFSLog dodgyWAL = new DodgyFSLog(fs, rootDir, getName(), CONF);<a name="line.211"></a> |
| <span class="sourceLineNo">212</span> dodgyWAL.init();<a name="line.212"></a> |
| <span class="sourceLineNo">213</span> Path originalWAL = dodgyWAL.getCurrentFileName();<a name="line.213"></a> |
| <span class="sourceLineNo">214</span> // I need a log roller running.<a name="line.214"></a> |
| <span class="sourceLineNo">215</span> LogRoller logRoller = new LogRoller(services);<a name="line.215"></a> |
| <span class="sourceLineNo">216</span> logRoller.addWAL(dodgyWAL);<a name="line.216"></a> |
| <span class="sourceLineNo">217</span> // There is no 'stop' once a logRoller is running.. it just dies.<a name="line.217"></a> |
| <span class="sourceLineNo">218</span> logRoller.start();<a name="line.218"></a> |
| <span class="sourceLineNo">219</span> // Now get a region and start adding in edits.<a name="line.219"></a> |
| <span class="sourceLineNo">220</span> final HRegion region = initHRegion(tableName, null, null, CONF, dodgyWAL);<a name="line.220"></a> |
| <span class="sourceLineNo">221</span> byte[] bytes = Bytes.toBytes(getName());<a name="line.221"></a> |
| <span class="sourceLineNo">222</span> NavigableMap<byte[], Integer> scopes = new TreeMap<>(Bytes.BYTES_COMPARATOR);<a name="line.222"></a> |
| <span class="sourceLineNo">223</span> scopes.put(COLUMN_FAMILY_BYTES, 0);<a name="line.223"></a> |
| <span class="sourceLineNo">224</span> MultiVersionConcurrencyControl mvcc = new MultiVersionConcurrencyControl();<a name="line.224"></a> |
| <span class="sourceLineNo">225</span> try {<a name="line.225"></a> |
| <span class="sourceLineNo">226</span> // First get something into memstore. Make a Put and then pull the Cell out of it. Will<a name="line.226"></a> |
| <span class="sourceLineNo">227</span> // manage append and sync carefully in below to manufacture hang. We keep adding same<a name="line.227"></a> |
| <span class="sourceLineNo">228</span> // edit. WAL subsystem doesn't care.<a name="line.228"></a> |
| <span class="sourceLineNo">229</span> Put put = new Put(bytes);<a name="line.229"></a> |
| <span class="sourceLineNo">230</span> put.addColumn(COLUMN_FAMILY_BYTES, Bytes.toBytes("1"), bytes);<a name="line.230"></a> |
| <span class="sourceLineNo">231</span> WALKeyImpl key = new WALKeyImpl(region.getRegionInfo().getEncodedNameAsBytes(),<a name="line.231"></a> |
| <span class="sourceLineNo">232</span> TableName.META_TABLE_NAME, EnvironmentEdgeManager.currentTime(), mvcc, scopes);<a name="line.232"></a> |
| <span class="sourceLineNo">233</span> WALEdit edit = new WALEdit();<a name="line.233"></a> |
| <span class="sourceLineNo">234</span> CellScanner CellScanner = put.cellScanner();<a name="line.234"></a> |
| <span class="sourceLineNo">235</span> assertTrue(CellScanner.advance());<a name="line.235"></a> |
| <span class="sourceLineNo">236</span> edit.add(CellScanner.current());<a name="line.236"></a> |
| <span class="sourceLineNo">237</span> // Put something in memstore and out in the WAL. Do a big number of appends so we push<a name="line.237"></a> |
| <span class="sourceLineNo">238</span> // out other side of the ringbuffer. If small numbers, stuff doesn't make it to WAL<a name="line.238"></a> |
| <span class="sourceLineNo">239</span> for (int i = 0; i < 1000; i++) {<a name="line.239"></a> |
| <span class="sourceLineNo">240</span> region.put(put);<a name="line.240"></a> |
| <span class="sourceLineNo">241</span> }<a name="line.241"></a> |
| <span class="sourceLineNo">242</span> // Set it so we start throwing exceptions.<a name="line.242"></a> |
| <span class="sourceLineNo">243</span> LOG.info("SET throwing of exception on append");<a name="line.243"></a> |
| <span class="sourceLineNo">244</span> dodgyWAL.throwException = true;<a name="line.244"></a> |
| <span class="sourceLineNo">245</span> // This append provokes a WAL roll request<a name="line.245"></a> |
| <span class="sourceLineNo">246</span> dodgyWAL.appendData(region.getRegionInfo(), key, edit);<a name="line.246"></a> |
| <span class="sourceLineNo">247</span> boolean exception = false;<a name="line.247"></a> |
| <span class="sourceLineNo">248</span> try {<a name="line.248"></a> |
| <span class="sourceLineNo">249</span> dodgyWAL.sync(false);<a name="line.249"></a> |
| <span class="sourceLineNo">250</span> } catch (Exception e) {<a name="line.250"></a> |
| <span class="sourceLineNo">251</span> exception = true;<a name="line.251"></a> |
| <span class="sourceLineNo">252</span> }<a name="line.252"></a> |
| <span class="sourceLineNo">253</span> assertTrue("Did not get sync exception", exception);<a name="line.253"></a> |
| <span class="sourceLineNo">254</span><a name="line.254"></a> |
| <span class="sourceLineNo">255</span> // Get a memstore flush going too so we have same hung profile as up in the issue over<a name="line.255"></a> |
| <span class="sourceLineNo">256</span> // in HBASE-14317. Flush hangs trying to get sequenceid because the ringbuffer is held up<a name="line.256"></a> |
| <span class="sourceLineNo">257</span> // by the zigzaglatch waiting on syncs to come home.<a name="line.257"></a> |
| <span class="sourceLineNo">258</span> Thread t = new Thread("Flusher") {<a name="line.258"></a> |
| <span class="sourceLineNo">259</span> @Override<a name="line.259"></a> |
| <span class="sourceLineNo">260</span> public void run() {<a name="line.260"></a> |
| <span class="sourceLineNo">261</span> try {<a name="line.261"></a> |
| <span class="sourceLineNo">262</span> if (region.getMemStoreDataSize() <= 0) {<a name="line.262"></a> |
| <span class="sourceLineNo">263</span> throw new IOException("memstore size=" + region.getMemStoreDataSize());<a name="line.263"></a> |
| <span class="sourceLineNo">264</span> }<a name="line.264"></a> |
| <span class="sourceLineNo">265</span> region.flush(false);<a name="line.265"></a> |
| <span class="sourceLineNo">266</span> } catch (IOException e) {<a name="line.266"></a> |
| <span class="sourceLineNo">267</span> // Can fail trying to flush in middle of a roll. Not a failure. Will succeed later<a name="line.267"></a> |
| <span class="sourceLineNo">268</span> // when roll completes.<a name="line.268"></a> |
| <span class="sourceLineNo">269</span> LOG.info("In flush", e);<a name="line.269"></a> |
| <span class="sourceLineNo">270</span> }<a name="line.270"></a> |
| <span class="sourceLineNo">271</span> LOG.info("Exiting");<a name="line.271"></a> |
| <span class="sourceLineNo">272</span> }<a name="line.272"></a> |
| <span class="sourceLineNo">273</span> };<a name="line.273"></a> |
| <span class="sourceLineNo">274</span> t.setDaemon(true);<a name="line.274"></a> |
| <span class="sourceLineNo">275</span> t.start();<a name="line.275"></a> |
| <span class="sourceLineNo">276</span> // Wait until<a name="line.276"></a> |
| <span class="sourceLineNo">277</span> while (dodgyWAL.latch.getCount() > 0) {<a name="line.277"></a> |
| <span class="sourceLineNo">278</span> Threads.sleep(1);<a name="line.278"></a> |
| <span class="sourceLineNo">279</span> }<a name="line.279"></a> |
| <span class="sourceLineNo">280</span> // Now assert I got a new WAL file put in place even though loads of errors above.<a name="line.280"></a> |
| <span class="sourceLineNo">281</span> assertTrue(originalWAL != dodgyWAL.getCurrentFileName());<a name="line.281"></a> |
| <span class="sourceLineNo">282</span> // Can I append to it?<a name="line.282"></a> |
| <span class="sourceLineNo">283</span> dodgyWAL.throwException = false;<a name="line.283"></a> |
| <span class="sourceLineNo">284</span> try {<a name="line.284"></a> |
| <span class="sourceLineNo">285</span> region.put(put);<a name="line.285"></a> |
| <span class="sourceLineNo">286</span> } catch (Exception e) {<a name="line.286"></a> |
| <span class="sourceLineNo">287</span> LOG.info("In the put", e);<a name="line.287"></a> |
| <span class="sourceLineNo">288</span> }<a name="line.288"></a> |
| <span class="sourceLineNo">289</span> } finally {<a name="line.289"></a> |
| <span class="sourceLineNo">290</span> // To stop logRoller, its server has to say it is stopped.<a name="line.290"></a> |
| <span class="sourceLineNo">291</span> Mockito.when(services.isStopped()).thenReturn(true);<a name="line.291"></a> |
| <span class="sourceLineNo">292</span> Closeables.close(logRoller, true);<a name="line.292"></a> |
| <span class="sourceLineNo">293</span> try {<a name="line.293"></a> |
| <span class="sourceLineNo">294</span> if (region != null) {<a name="line.294"></a> |
| <span class="sourceLineNo">295</span> region.close();<a name="line.295"></a> |
| <span class="sourceLineNo">296</span> }<a name="line.296"></a> |
| <span class="sourceLineNo">297</span> if (dodgyWAL != null) {<a name="line.297"></a> |
| <span class="sourceLineNo">298</span> dodgyWAL.close();<a name="line.298"></a> |
| <span class="sourceLineNo">299</span> }<a name="line.299"></a> |
| <span class="sourceLineNo">300</span> } catch (Exception e) {<a name="line.300"></a> |
| <span class="sourceLineNo">301</span> LOG.info("On way out", e);<a name="line.301"></a> |
| <span class="sourceLineNo">302</span> }<a name="line.302"></a> |
| <span class="sourceLineNo">303</span> }<a name="line.303"></a> |
| <span class="sourceLineNo">304</span> }<a name="line.304"></a> |
| <span class="sourceLineNo">305</span><a name="line.305"></a> |
| <span class="sourceLineNo">306</span> /**<a name="line.306"></a> |
| <span class="sourceLineNo">307</span> * If below is broken, we will see this test timeout because RingBufferEventHandler was stuck in<a name="line.307"></a> |
| <span class="sourceLineNo">308</span> * attainSafePoint. Everyone will wait for sync to finish forever. See HBASE-14317.<a name="line.308"></a> |
| <span class="sourceLineNo">309</span> */<a name="line.309"></a> |
| <span class="sourceLineNo">310</span> @Test<a name="line.310"></a> |
| <span class="sourceLineNo">311</span> public void testRingBufferEventHandlerStuckWhenSyncFailed()<a name="line.311"></a> |
| <span class="sourceLineNo">312</span> throws IOException, InterruptedException {<a name="line.312"></a> |
| <span class="sourceLineNo">313</span><a name="line.313"></a> |
| <span class="sourceLineNo">314</span> // A WAL that we can have throw exceptions and slow FSHLog.replaceWriter down<a name="line.314"></a> |
| <span class="sourceLineNo">315</span> class DodgyFSLog extends FSHLog {<a name="line.315"></a> |
| <span class="sourceLineNo">316</span><a name="line.316"></a> |
| <span class="sourceLineNo">317</span> private volatile boolean zigZagCreated = false;<a name="line.317"></a> |
| <span class="sourceLineNo">318</span><a name="line.318"></a> |
| <span class="sourceLineNo">319</span> public DodgyFSLog(FileSystem fs, Path root, String logDir, Configuration conf)<a name="line.319"></a> |
| <span class="sourceLineNo">320</span> throws IOException {<a name="line.320"></a> |
| <span class="sourceLineNo">321</span> super(fs, root, logDir, conf);<a name="line.321"></a> |
| <span class="sourceLineNo">322</span> }<a name="line.322"></a> |
| <span class="sourceLineNo">323</span><a name="line.323"></a> |
| <span class="sourceLineNo">324</span> @Override<a name="line.324"></a> |
| <span class="sourceLineNo">325</span> protected void afterCreatingZigZagLatch() {<a name="line.325"></a> |
| <span class="sourceLineNo">326</span> zigZagCreated = true;<a name="line.326"></a> |
| <span class="sourceLineNo">327</span> // Sleep a while to wait for RingBufferEventHandler to get stuck first.<a name="line.327"></a> |
| <span class="sourceLineNo">328</span> try {<a name="line.328"></a> |
| <span class="sourceLineNo">329</span> Thread.sleep(3000);<a name="line.329"></a> |
| <span class="sourceLineNo">330</span> } catch (InterruptedException ignore) {<a name="line.330"></a> |
| <span class="sourceLineNo">331</span> }<a name="line.331"></a> |
| <span class="sourceLineNo">332</span> }<a name="line.332"></a> |
| <span class="sourceLineNo">333</span><a name="line.333"></a> |
| <span class="sourceLineNo">334</span> @Override<a name="line.334"></a> |
| <span class="sourceLineNo">335</span> protected long getSequenceOnRingBuffer() {<a name="line.335"></a> |
| <span class="sourceLineNo">336</span> return super.getSequenceOnRingBuffer();<a name="line.336"></a> |
| <span class="sourceLineNo">337</span> }<a name="line.337"></a> |
| <span class="sourceLineNo">338</span><a name="line.338"></a> |
| <span class="sourceLineNo">339</span> protected void publishSyncOnRingBufferAndBlock(long sequence) {<a name="line.339"></a> |
| <span class="sourceLineNo">340</span> try {<a name="line.340"></a> |
| <span class="sourceLineNo">341</span> super.blockOnSync(super.publishSyncOnRingBuffer(sequence, false));<a name="line.341"></a> |
| <span class="sourceLineNo">342</span> Assert.fail("Expect an IOException here.");<a name="line.342"></a> |
| <span class="sourceLineNo">343</span> } catch (IOException ignore) {<a name="line.343"></a> |
| <span class="sourceLineNo">344</span> // Here, we will get an IOException.<a name="line.344"></a> |
| <span class="sourceLineNo">345</span> }<a name="line.345"></a> |
| <span class="sourceLineNo">346</span> }<a name="line.346"></a> |
| <span class="sourceLineNo">347</span><a name="line.347"></a> |
| <span class="sourceLineNo">348</span> @Override<a name="line.348"></a> |
| <span class="sourceLineNo">349</span> protected Writer createWriterInstance(Path path) throws IOException {<a name="line.349"></a> |
| <span class="sourceLineNo">350</span> final Writer w = super.createWriterInstance(path);<a name="line.350"></a> |
| <span class="sourceLineNo">351</span> return new Writer() {<a name="line.351"></a> |
| <span class="sourceLineNo">352</span> @Override<a name="line.352"></a> |
| <span class="sourceLineNo">353</span> public void close() throws IOException {<a name="line.353"></a> |
| <span class="sourceLineNo">354</span> w.close();<a name="line.354"></a> |
| <span class="sourceLineNo">355</span> }<a name="line.355"></a> |
| <span class="sourceLineNo">356</span><a name="line.356"></a> |
| <span class="sourceLineNo">357</span> @Override<a name="line.357"></a> |
| <span class="sourceLineNo">358</span> public void sync(boolean forceSync) throws IOException {<a name="line.358"></a> |
| <span class="sourceLineNo">359</span> throw new IOException("FAKE! Failed to replace a bad datanode...SYNC");<a name="line.359"></a> |
| <span class="sourceLineNo">360</span> }<a name="line.360"></a> |
| <span class="sourceLineNo">361</span><a name="line.361"></a> |
| <span class="sourceLineNo">362</span> @Override<a name="line.362"></a> |
| <span class="sourceLineNo">363</span> public void append(Entry entry) throws IOException {<a name="line.363"></a> |
| <span class="sourceLineNo">364</span> w.append(entry);<a name="line.364"></a> |
| <span class="sourceLineNo">365</span> }<a name="line.365"></a> |
| <span class="sourceLineNo">366</span><a name="line.366"></a> |
| <span class="sourceLineNo">367</span> @Override<a name="line.367"></a> |
| <span class="sourceLineNo">368</span> public long getLength() {<a name="line.368"></a> |
| <span class="sourceLineNo">369</span> return w.getLength();<a name="line.369"></a> |
| <span class="sourceLineNo">370</span> }<a name="line.370"></a> |
| <span class="sourceLineNo">371</span><a name="line.371"></a> |
| <span class="sourceLineNo">372</span> @Override<a name="line.372"></a> |
| <span class="sourceLineNo">373</span> public long getSyncedLength() {<a name="line.373"></a> |
| <span class="sourceLineNo">374</span> return w.getSyncedLength();<a name="line.374"></a> |
| <span class="sourceLineNo">375</span> }<a name="line.375"></a> |
| <span class="sourceLineNo">376</span> };<a name="line.376"></a> |
| <span class="sourceLineNo">377</span> }<a name="line.377"></a> |
| <span class="sourceLineNo">378</span> }<a name="line.378"></a> |
| <span class="sourceLineNo">379</span><a name="line.379"></a> |
| <span class="sourceLineNo">380</span> // Mocked up server and regionserver services. Needed below.<a name="line.380"></a> |
| <span class="sourceLineNo">381</span> RegionServerServices services = Mockito.mock(RegionServerServices.class);<a name="line.381"></a> |
| <span class="sourceLineNo">382</span> Mockito.when(services.getConfiguration()).thenReturn(CONF);<a name="line.382"></a> |
| <span class="sourceLineNo">383</span> Mockito.when(services.isStopped()).thenReturn(false);<a name="line.383"></a> |
| <span class="sourceLineNo">384</span> Mockito.when(services.isAborted()).thenReturn(false);<a name="line.384"></a> |
| <span class="sourceLineNo">385</span><a name="line.385"></a> |
| <span class="sourceLineNo">386</span> // OK. Now I have my mocked up Server & RegionServerServices and dodgy WAL, go ahead with test.<a name="line.386"></a> |
| <span class="sourceLineNo">387</span> FileSystem fs = FileSystem.get(CONF);<a name="line.387"></a> |
| <span class="sourceLineNo">388</span> Path rootDir = new Path(dir + getName());<a name="line.388"></a> |
| <span class="sourceLineNo">389</span> final DodgyFSLog dodgyWAL = new DodgyFSLog(fs, rootDir, getName(), CONF);<a name="line.389"></a> |
| <span class="sourceLineNo">390</span> // I need a log roller running.<a name="line.390"></a> |
| <span class="sourceLineNo">391</span> LogRoller logRoller = new LogRoller(services);<a name="line.391"></a> |
| <span class="sourceLineNo">392</span> logRoller.addWAL(dodgyWAL);<a name="line.392"></a> |
| <span class="sourceLineNo">393</span> // There is no 'stop' once a logRoller is running.. it just dies.<a name="line.393"></a> |
| <span class="sourceLineNo">394</span> logRoller.start();<a name="line.394"></a> |
| <span class="sourceLineNo">395</span><a name="line.395"></a> |
| <span class="sourceLineNo">396</span> try {<a name="line.396"></a> |
| <span class="sourceLineNo">397</span> final long seqForSync = dodgyWAL.getSequenceOnRingBuffer();<a name="line.397"></a> |
| <span class="sourceLineNo">398</span><a name="line.398"></a> |
| <span class="sourceLineNo">399</span> // This call provokes a WAL roll, and we will get a new RingBufferEventHandler.ZigZagLatch<a name="line.399"></a> |
| <span class="sourceLineNo">400</span> // in LogRoller.<a name="line.400"></a> |
| <span class="sourceLineNo">401</span> // After creating ZigZagLatch, RingBufferEventHandler would get stuck due to sync event,<a name="line.401"></a> |
| <span class="sourceLineNo">402</span> // as long as HBASE-14317 hasn't be fixed.<a name="line.402"></a> |
| <span class="sourceLineNo">403</span> LOG.info("Trigger log roll for creating a ZigZagLatch.");<a name="line.403"></a> |
| <span class="sourceLineNo">404</span> logRoller.requestRollAll();<a name="line.404"></a> |
| <span class="sourceLineNo">405</span><a name="line.405"></a> |
| <span class="sourceLineNo">406</span> while (!dodgyWAL.zigZagCreated) {<a name="line.406"></a> |
| <span class="sourceLineNo">407</span> Thread.sleep(10);<a name="line.407"></a> |
| <span class="sourceLineNo">408</span> }<a name="line.408"></a> |
| <span class="sourceLineNo">409</span><a name="line.409"></a> |
| <span class="sourceLineNo">410</span> // Send a sync event for RingBufferEventHandler,<a name="line.410"></a> |
| <span class="sourceLineNo">411</span> // and it gets blocked in RingBufferEventHandler.attainSafePoint<a name="line.411"></a> |
| <span class="sourceLineNo">412</span> LOG.info("Send sync for RingBufferEventHandler");<a name="line.412"></a> |
| <span class="sourceLineNo">413</span> Thread syncThread = new Thread() {<a name="line.413"></a> |
| <span class="sourceLineNo">414</span> @Override<a name="line.414"></a> |
| <span class="sourceLineNo">415</span> public void run() {<a name="line.415"></a> |
| <span class="sourceLineNo">416</span> dodgyWAL.publishSyncOnRingBufferAndBlock(seqForSync);<a name="line.416"></a> |
| <span class="sourceLineNo">417</span> }<a name="line.417"></a> |
| <span class="sourceLineNo">418</span> };<a name="line.418"></a> |
| <span class="sourceLineNo">419</span> // Sync in another thread to avoid reset SyncFuture again.<a name="line.419"></a> |
| <span class="sourceLineNo">420</span> syncThread.start();<a name="line.420"></a> |
| <span class="sourceLineNo">421</span> syncThread.join();<a name="line.421"></a> |
| <span class="sourceLineNo">422</span><a name="line.422"></a> |
| <span class="sourceLineNo">423</span> try {<a name="line.423"></a> |
| <span class="sourceLineNo">424</span> LOG.info("Call sync for testing whether RingBufferEventHandler is hanging.");<a name="line.424"></a> |
| <span class="sourceLineNo">425</span> dodgyWAL.sync(false); // Should not get a hang here, otherwise we will see timeout in this<a name="line.425"></a> |
| <span class="sourceLineNo">426</span> // test.<a name="line.426"></a> |
| <span class="sourceLineNo">427</span> Assert.fail("Expect an IOException here.");<a name="line.427"></a> |
| <span class="sourceLineNo">428</span> } catch (IOException ignore) {<a name="line.428"></a> |
| <span class="sourceLineNo">429</span> }<a name="line.429"></a> |
| <span class="sourceLineNo">430</span><a name="line.430"></a> |
| <span class="sourceLineNo">431</span> } finally {<a name="line.431"></a> |
| <span class="sourceLineNo">432</span> // To stop logRoller, its server has to say it is stopped.<a name="line.432"></a> |
| <span class="sourceLineNo">433</span> Mockito.when(services.isStopped()).thenReturn(true);<a name="line.433"></a> |
| <span class="sourceLineNo">434</span> if (logRoller != null) {<a name="line.434"></a> |
| <span class="sourceLineNo">435</span> logRoller.close();<a name="line.435"></a> |
| <span class="sourceLineNo">436</span> }<a name="line.436"></a> |
| <span class="sourceLineNo">437</span> if (dodgyWAL != null) {<a name="line.437"></a> |
| <span class="sourceLineNo">438</span> dodgyWAL.close();<a name="line.438"></a> |
| <span class="sourceLineNo">439</span> }<a name="line.439"></a> |
| <span class="sourceLineNo">440</span> }<a name="line.440"></a> |
| <span class="sourceLineNo">441</span> }<a name="line.441"></a> |
| <span class="sourceLineNo">442</span><a name="line.442"></a> |
| <span class="sourceLineNo">443</span> /**<a name="line.443"></a> |
| <span class="sourceLineNo">444</span> * @return A region on which you must call {@link HBaseTestingUtil#closeRegionAndWAL(HRegion)}<a name="line.444"></a> |
| <span class="sourceLineNo">445</span> * when done.<a name="line.445"></a> |
| <span class="sourceLineNo">446</span> */<a name="line.446"></a> |
| <span class="sourceLineNo">447</span> private static HRegion initHRegion(TableName tableName, byte[] startKey, byte[] stopKey,<a name="line.447"></a> |
| <span class="sourceLineNo">448</span> Configuration conf, WAL wal) throws IOException {<a name="line.448"></a> |
| <span class="sourceLineNo">449</span> ChunkCreator.initialize(MemStoreLAB.CHUNK_SIZE_DEFAULT, false, 0, 0, 0, null,<a name="line.449"></a> |
| <span class="sourceLineNo">450</span> MemStoreLAB.INDEX_CHUNK_SIZE_PERCENTAGE_DEFAULT);<a name="line.450"></a> |
| <span class="sourceLineNo">451</span> return TEST_UTIL.createLocalHRegion(tableName, startKey, stopKey, conf, false,<a name="line.451"></a> |
| <span class="sourceLineNo">452</span> Durability.SYNC_WAL, wal, COLUMN_FAMILY_BYTES);<a name="line.452"></a> |
| <span class="sourceLineNo">453</span> }<a name="line.453"></a> |
| <span class="sourceLineNo">454</span>}<a name="line.454"></a> |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| |
| </pre> |
| </div> |
| </body> |
| </html> |
