You are viewing a plain text version of this content. The canonical link for it is here.
Posted to oak-commits@jackrabbit.apache.org by st...@apache.org on 2017/02/08 17:21:04 UTC

svn commit: r1782221 - /jackrabbit/oak/branches/1.0/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/JournalGCTest.java

Author: stefanegli
Date: Wed Feb  8 17:21:04 2017
New Revision: 1782221

URL: http://svn.apache.org/viewvc?rev=1782221&view=rev
Log:
OAK-5601 : merged from trunk: added a - currently ignored - test case that reproduces the missing journal entry exception

Added:
    jackrabbit/oak/branches/1.0/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/JournalGCTest.java   (with props)

Added: jackrabbit/oak/branches/1.0/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/JournalGCTest.java
URL: http://svn.apache.org/viewvc/jackrabbit/oak/branches/1.0/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/JournalGCTest.java?rev=1782221&view=auto
==============================================================================
--- jackrabbit/oak/branches/1.0/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/JournalGCTest.java (added)
+++ jackrabbit/oak/branches/1.0/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/JournalGCTest.java Wed Feb  8 17:21:04 2017
@@ -0,0 +1,179 @@
+/*
+ * Licensed to the Apache Software Foundation (ASF) under one or more
+ * contributor license agreements.  See the NOTICE file distributed with
+ * this work for additional information regarding copyright ownership.
+ * The ASF licenses this file to You under the Apache License, Version 2.0
+ * (the "License"); you may not use this file except in compliance with
+ * the License.  You may obtain a copy of the License at
+ *
+ *      http://www.apache.org/licenses/LICENSE-2.0
+ *
+ * Unless required by applicable law or agreed to in writing, software
+ * distributed under the License is distributed on an "AS IS" BASIS,
+ * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
+ * See the License for the specific language governing permissions and
+ * limitations under the License.
+ */
+package org.apache.jackrabbit.oak.plugins.document;
+
+import static org.apache.jackrabbit.oak.plugins.document.Collection.JOURNAL;
+import static org.junit.Assert.assertFalse;
+import static org.junit.Assert.assertNotNull;
+import static org.junit.Assert.assertNull;
+import static org.junit.Assert.assertTrue;
+import static org.junit.Assert.fail;
+
+import java.util.concurrent.Semaphore;
+import java.util.concurrent.TimeUnit;
+
+import org.apache.jackrabbit.oak.plugins.document.memory.MemoryDocumentStore;
+import org.apache.jackrabbit.oak.spi.commit.CommitInfo;
+import org.apache.jackrabbit.oak.spi.commit.EmptyHook;
+import org.apache.jackrabbit.oak.spi.state.NodeBuilder;
+import org.junit.After;
+import org.junit.Before;
+import org.junit.Ignore;
+import org.junit.Rule;
+import org.junit.Test;
+import org.slf4j.Logger;
+import org.slf4j.LoggerFactory;
+
+public class JournalGCTest {
+
+    private static final Logger LOG = LoggerFactory.getLogger(JournalTest.class);
+
+    @Rule
+    public DocumentMKBuilderProvider builderProvider = new DocumentMKBuilderProvider();
+    
+    private final ThreadLocal<Boolean> shouldWait = new ThreadLocal<Boolean>();
+
+    @Before
+    public void setUp() {
+    	shouldWait.remove();
+    }
+    
+    @After
+    public void tearDown() {
+    	shouldWait.remove();
+    }
+    
+    /**
+     * reproducing OAK-5601:
+     * <ul>
+     *  <li>have two documentMk's, one to make changes, one does only read</li>
+     *  <li>make a commit, let 1.2 seconds pass, run gc, then read it from the other documentMk</li>
+     *  <li>the gc (1sec timeout) will have cleaned up that 1.2sec old journal entry, resulting in
+     *      a missing journal entry exception when reading from the 2nd documentMk</li>
+     * </ul>
+     * What the test has to ensure is that the JournalEntry does the query, then blocks that
+     * thread to let the GC happen, then continues on with find(). This results in those
+     * revisions that the JournalEntry got back from the query to be removed and
+     * thus end up missing by later on in addTo.
+     */
+    @Test
+    @Ignore("OAK-5601")
+    public void gcCausingMissingJournalEntries() throws Exception {
+        // cluster setup
+        final Semaphore enteringFind = new Semaphore(0);
+        final Semaphore continuingFind = new Semaphore(100);
+        DocumentStore sharedDocStore = new MemoryDocumentStore() {
+            @Override
+            public <T extends Document> T find(Collection<T> collection, String key) {
+                if (collection == JOURNAL && (shouldWait.get() == null || shouldWait.get())) {
+                    LOG.info("find(JOURNAL,..): entered... releasing enteringFind semaphore");
+                    enteringFind.release();
+                    try {
+                        LOG.info("find(JOURNAL,..): waiting for OK to continue");
+                        if (!continuingFind.tryAcquire(5, TimeUnit.SECONDS)) {
+                            fail("could not continue within 5 sec");
+                        }
+                        LOG.info("find(JOURNAL,..): continuing");
+                    } catch (InterruptedException e) {
+                        throw new AssertionError(e);
+                    }
+                }
+                return super.find(collection, key);
+            }
+        };
+        final DocumentNodeStore writingNs = builderProvider.newBuilder()
+                .setDocumentStore(sharedDocStore)
+//                .setClusterId(1) // setting the clusterId in the 1.0 branch prevents journal.gc
+                .setAsyncDelay(0).getNodeStore();
+        DocumentNodeStore readingNs = builderProvider.newBuilder()
+                .setDocumentStore(sharedDocStore)
+//                .setClusterId(2) // setting the clusterId in the 1.0 branch prevents journal.gc
+                .setAsyncDelay(0).getNodeStore();
+        
+        // 'proper cluster sync': do it a bit too many times
+        readingNs.runBackgroundOperations();
+        writingNs.runBackgroundOperations();
+        readingNs.runBackgroundOperations();
+        writingNs.runBackgroundOperations();
+        
+        // perform some change in writingNs - not yet seen by readingNs
+        NodeBuilder builder = writingNs.getRoot().builder();
+        NodeBuilder foo = builder.child("foo");
+        // cause a branch commit
+        for(int i=0; i<DocumentRootBuilder.UPDATE_LIMIT + 1; i++) {
+            foo.setProperty(String.valueOf(i), "foobar");
+        }
+        writingNs.merge(builder, EmptyHook.INSTANCE, CommitInfo.EMPTY);
+        final Revision head = writingNs.getHeadRevision();
+        assertNotNull(head);
+
+        // trigger creation of journal entry - still not yet seen by readingNs
+        writingNs.runBackgroundOperations();
+        JournalEntry entry = writingNs.getDocumentStore().find(JOURNAL, JournalEntry.asId(head));
+        assertNotNull(entry);
+
+        // wait slightly more than 1 sec - readingNs does nothing during this time
+        Thread.sleep(1200);
+
+        // clear up the semaphore
+        enteringFind.drainPermits();
+        continuingFind.drainPermits();
+        
+        final StringBuffer errorMsg = new StringBuffer();
+        Runnable r = new Runnable() {
+            @Override
+            public void run() {
+                // wait for find(JOURNAL,..) to be entered
+                LOG.info("waiting for find(JOURNAL,... to be called...");
+                try {
+                    if (!enteringFind.tryAcquire(5, TimeUnit.SECONDS)) {
+                        errorMsg.append("find(JOURNAL,..) did not get called within 5sec");
+                        return;
+                    }
+                } catch (InterruptedException e) {
+                    errorMsg.append("Got interrupted: "+e);
+                    return;
+                }
+                LOG.info("find(JOURNAL,..) got called, running GC.");
+                
+                // avoid find to block in this thread - via a ThreadLocal
+                shouldWait.set(false);
+                
+                // instruct journal GC to remove entries older than one hour - readingNs hasn't seen it
+                writingNs.getJournalGarbageCollector().gc(1, TimeUnit.SECONDS);
+
+                // entry should be removed
+                JournalEntry entry = writingNs.getDocumentStore().find(JOURNAL, JournalEntry.asId(head));
+                assertNull(entry);
+                
+                // now release the waiting find(JOURNAL,..) thread
+                continuingFind.release(100);
+            }
+        };
+        Thread th = new Thread(r);
+        th.start();
+        
+        // verify that readingNs doesn't have /foo yet
+        assertFalse(readingNs.getRoot().hasChildNode("foo"));
+        
+        // now run background ops on readingNs - it should be able to see 'foo'
+        for(int i=0; i<5; i++) {
+            readingNs.runBackgroundOperations();
+        }
+        assertTrue(readingNs.getRoot().hasChildNode("foo"));
+    }
+}
\ No newline at end of file

Propchange: jackrabbit/oak/branches/1.0/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/JournalGCTest.java
------------------------------------------------------------------------------
    svn:eol-style = native