Author: reschke Date: Tue Jul 14 14:47:30 2015 New Revision: 1690948 URL: http://svn.apache.org/r1690948 Log: OAK-3096: improve diagnostics for failed batch inserts, add test case observing the DocumentStore's behavior wrt to atomicity (ported to 1.2)
Modified: jackrabbit/oak/branches/1.2/ (props changed) jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBDocumentStore.java jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBJDBCTools.java jackrabbit/oak/branches/1.2/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/BasicDocumentStoreTest.java Propchange: jackrabbit/oak/branches/1.2/ ------------------------------------------------------------------------------ --- svn:mergeinfo (original) +++ svn:mergeinfo Tue Jul 14 14:47:30 2015 @@ -1,3 +1,3 @@ /jackrabbit/oak/branches/1.0:1665962 -/jackrabbit/oak/trunk:1672350,1672468,1672537,1672603,1672642,1672644,1672834-1672835,1673351,1673410,1673414-1673415,1673436,1673644,1673662-1673664,1673669,1673695,1673738,1673787,1673791,1674046,1674065,1674075,1674107,1674228,1674780,1674880,1675054-1675055,1675319,1675332,1675354,1675357,1675382,1675555,1675566,1675593,1676198,1676237,1676407,1676458,1676539,1676670,1676693,1676703,1676725,1677579,1677581,1677609,1677611,1677774,1677788,1677797,1677804,1677806,1677939,1677991,1678023,1678095-1678096,1678171,1678173,1678211,1678323,1678758,1678938,1678954,1679144,1679165,1679191,1679232,1679235,1679503,1679958,1679961,1680170,1680182,1680222,1680232,1680236,1680461,1680633,1680643,1680747,1680805-1680806,1680903,1681282,1681767,1681918,1682042,1682218,1682235,1682437,1682494,1682555,1682855,1682904,1683059,1683089,1683213,1683249,1683259,1683278,1683323,1683687,1683700,1684174-1684175,1684186,1684376,1684442,1684561,1684570,1684601,1684618,1684820,1684868,1685023,1685370,1685552 ,1685589-1685590,1685840,1685964,1685977,1685989,1685999,1686023,1686032,1686097,1686162,1686229,1686234,1686253,1686414,1686780,1686854,1686857,1686971,1687053-1687055,1687175,1687196,1687198,1687220,1687239-1687240,1687301,1687441,1687553,1688089-1688090,1688172,1688179,1688349,1688421,1688436,1688453,1688616,1688622,1688636,1688817,1689003-1689004,1689008,1689577,1689581,1689623,1689810,1689828,1689833,1689903,1690017,1690043,1690047,1690057,1690247,1690249,1690634-1690637,1690650,1690669,1690674,1690885 +/jackrabbit/oak/trunk:1672350,1672468,1672537,1672603,1672642,1672644,1672834-1672835,1673351,1673410,1673414-1673415,1673436,1673644,1673662-1673664,1673669,1673695,1673738,1673787,1673791,1674046,1674065,1674075,1674107,1674228,1674780,1674880,1675054-1675055,1675319,1675332,1675354,1675357,1675382,1675555,1675566,1675593,1676198,1676237,1676407,1676458,1676539,1676670,1676693,1676703,1676725,1677579,1677581,1677609,1677611,1677774,1677788,1677797,1677804,1677806,1677939,1677991,1678023,1678095-1678096,1678171,1678173,1678211,1678323,1678758,1678938,1678954,1679144,1679165,1679191,1679232,1679235,1679503,1679958,1679961,1680170,1680182,1680222,1680232,1680236,1680461,1680633,1680643,1680747,1680805-1680806,1680903,1681282,1681767,1681918,1682042,1682218,1682235,1682437,1682494,1682555,1682855,1682904,1683059,1683089,1683213,1683249,1683259,1683278,1683323,1683687,1683700,1684174-1684175,1684186,1684376,1684442,1684561,1684570,1684601,1684618,1684820,1684868,1685023,1685370,1685552 ,1685589-1685590,1685840,1685964,1685977,1685989,1685999,1686023,1686032,1686097,1686162,1686229,1686234,1686253,1686414,1686780,1686854,1686857,1686971,1687053-1687055,1687175,1687196,1687198,1687220,1687239-1687240,1687301,1687441,1687553,1688089-1688090,1688172,1688179,1688349,1688421,1688436,1688453,1688616,1688622,1688636,1688817,1689003-1689004,1689008,1689577,1689581,1689623,1689810,1689828,1689833,1689903,1690017,1690043,1690047,1690057,1690247,1690249,1690634-1690637,1690650,1690669,1690674,1690885,1690941 /jackrabbit/trunk:1345480 Modified: jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBDocumentStore.java URL: http://svn.apache.org/viewvc/jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBDocumentStore.java?rev=1690948&r1=1690947&r2=1690948&view=diff ============================================================================== --- jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBDocumentStore.java (original) +++ jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBDocumentStore.java Tue Jul 14 14:47:30 2015 @@ -1498,15 +1498,25 @@ public class RDBDocumentStore implements private <T extends Document> boolean insertDocuments(Collection<T> collection, List<T> documents) { Connection connection = null; String tableName = getTable(collection); - List<String> ids = new ArrayList<String>(); try { connection = this.ch.getRWConnection(); boolean result = dbInsert(connection, tableName, documents); connection.commit(); return result; } catch (SQLException ex) { - LOG.debug("insert of " + ids + " failed", ex); this.ch.rollbackConnection(connection); + + List<String> ids = new ArrayList<String>(); + for (T doc : documents) { + ids.add(doc.getId()); + } + LOG.debug("insert of " + ids + " failed", ex); + + // collect additional exceptions + String messages = LOG.isDebugEnabled() ? RDBJDBCTools.getAdditionalMessages(ex) : ""; + if (!messages.isEmpty()) { + LOG.debug("additional diagnostics: " + messages); + } throw new DocumentStoreException(ex); } finally { this.ch.closeConnection(connection); Modified: jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBJDBCTools.java URL: http://svn.apache.org/viewvc/jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBJDBCTools.java?rev=1690948&r1=1690947&r2=1690948&view=diff ============================================================================== --- jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBJDBCTools.java (original) +++ jackrabbit/oak/branches/1.2/oak-core/src/main/java/org/apache/jackrabbit/oak/plugins/document/rdb/RDBJDBCTools.java Tue Jul 14 14:47:30 2015 @@ -17,6 +17,9 @@ package org.apache.jackrabbit.oak.plugins.document.rdb; import java.sql.Connection; +import java.sql.SQLException; +import java.util.ArrayList; +import java.util.List; import java.util.Locale; import java.util.Map; @@ -119,4 +122,22 @@ public class RDBJDBCTools { } return String.format("%s (%d)", name, isolationLevel); } + + /** + * Return a string containing additional messages from chained exceptions. + */ + protected static @Nonnull String getAdditionalMessages(SQLException ex) { + List<String> messages = new ArrayList<String>(); + String message = ex.getMessage(); + SQLException next = ex.getNextException(); + while (next != null) { + String m = next.getMessage(); + if (!message.equals(m)) { + messages.add(m); + } + next = next.getNextException(); + } + + return messages.isEmpty() ? "" : messages.toString(); + } } Modified: jackrabbit/oak/branches/1.2/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/BasicDocumentStoreTest.java URL: http://svn.apache.org/viewvc/jackrabbit/oak/branches/1.2/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/BasicDocumentStoreTest.java?rev=1690948&r1=1690947&r2=1690948&view=diff ============================================================================== --- jackrabbit/oak/branches/1.2/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/BasicDocumentStoreTest.java (original) +++ jackrabbit/oak/branches/1.2/oak-core/src/test/java/org/apache/jackrabbit/oak/plugins/document/BasicDocumentStoreTest.java Tue Jul 14 14:47:30 2015 @@ -25,10 +25,11 @@ import static org.junit.Assert.fail; import java.io.UnsupportedEncodingException; import java.util.ArrayList; -import java.util.Arrays; import java.util.Collections; +import java.util.HashSet; import java.util.List; import java.util.Map; +import java.util.Set; import java.util.UUID; import org.apache.jackrabbit.oak.plugins.document.UpdateOp.Condition; @@ -226,26 +227,63 @@ public class BasicDocumentStoreTest exte @Test public void testCreatePartialFailure() { - String id = this.getClass().getName() + ".testCreatePartialFailure"; + String bid = this.getClass().getName() + ".testCreatePartialFailure-"; + int cnt = 10; + assertTrue(cnt > 8); + + // clear repo + for (int i = 0; i < cnt; i++) { + super.ds.remove(Collection.NODES, bid + i); + removeMe.add(bid + i); + } - // remove if present - NodeDocument nd = super.ds.find(Collection.NODES, id); - if (nd != null) { - super.ds.remove(Collection.NODES, id); + // create one of the test nodes + int pre = cnt / 2; + UpdateOp up = new UpdateOp(bid + pre, true); + up.set("_id", bid + pre); + up.set("foo", "bar"); + assertTrue(super.ds.create(Collection.NODES, Collections.singletonList(up))); + + // batch create + Set<String> toCreate = new HashSet<String>(); + Set<String> toCreateFailEarly = new HashSet<String>(); + List<UpdateOp> ups = new ArrayList<UpdateOp>(); + for (int i = 0; i < cnt; i++) { + UpdateOp op = new UpdateOp(bid + i, true); + op.set("_id", bid + i); + op.set("foo", "qux"); + ups.add(op); + if (i != pre) { + toCreate.add(bid + i); + } + if (i < pre) { + toCreateFailEarly.add(bid + i); + } } + assertFalse(super.ds.create(Collection.NODES, ups)); - // create a test node - UpdateOp up = new UpdateOp(id, true); - up.set("_id", id); - boolean success = super.ds.create(Collection.NODES, Collections.singletonList(up)); - assertTrue(success); - removeMe.add(id); + // check how many nodes are there + Set<String> created = new HashSet<String>(); + for (int i = 0; i < cnt; i++) { + boolean present = null != super.ds.find(Collection.NODES, bid + i, 0); + if (i == pre && !present) { + fail(super.dsname + ": batch update removed previously existing node " + (bid + i)); + } else if (present && i != pre) { + created.add(bid + i); + } + } - // try to create two nodes, of which one exists - UpdateOp up2 = new UpdateOp(id + "2", true); - up2.set("_id", id + "2"); - success = super.ds.create(Collection.NODES, Arrays.asList(new UpdateOp[] { up, up2 })); - assertFalse(success); + // diagnostics + toCreate.removeAll(created); + if (created.isEmpty()) { + LOG.info(super.dsname + ": create() apparently is atomic"); + } else if (created.size() == toCreate.size()) { + LOG.info(super.dsname + ": create() apparently is best-effort"); + } else if (created.equals(toCreateFailEarly)) { + LOG.info(super.dsname + ": create() stops at first failure"); + } else { + LOG.info(super.dsname + ": create() created: " + created + ", missing: " + toCreate); + } } @Test