[
https://issues.apache.org/jira/browse/ACCUMULO-3645?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14374806#comment-14374806
]
ASF GitHub Bot commented on ACCUMULO-3645:
------------------------------------------
Github user denine99 commented on the pull request:
https://github.com/apache/accumulo/pull/22#issuecomment-84524662
I fixed up the patch from Keith's comments. This one looks good.
My mistake starting out with the patch was trying to change *every*
location where an empty tablet could be disregarded during compaction. Now the
current patch has the *minimal* changes necessary to make empty tablets
compact. The biggest one is the change to the default compaction strategy. As
before, I'm open for ideas to test further. I think we're closing in on good
code.
To answer the question I posed previously, the expected behavior when a
major compaction iterator throws and exception (e.g.
[BadIterator](https://accumulo.apache.org/1.6/apidocs/org/apache/accumulo/test/functional/BadIterator.html))
is that the tablet server will fail the compaction, not updating the
metadata table, and then retry the compaction. If the error is permanent, then
the compaction will fail again and again continuously. The only way to stop
the cycle is to cancel the compaction manually. This patch maintains this
behavior.
Rebased the commits to master. Will squash once given a green light.
> Iterators not run at compaction when tablets are empty
> ------------------------------------------------------
>
> Key: ACCUMULO-3645
> URL: https://issues.apache.org/jira/browse/ACCUMULO-3645
> Project: Accumulo
> Issue Type: Bug
> Components: client, mini, tserver
> Affects Versions: 1.6.1
> Environment: Ubuntu 14.04, Accumulo 1.6.1, Hadoop 2.6.0, Zookeeper
> 3.4.6
> Reporter: Dylan Hutchison
> Assignee: Dylan Hutchison
>
> When an iterator is configured to run during a one-time manual major
> compaction on a tablet that is empty, the iterator never runs. It seems as if
> Accumulo never constructs the SKVI stack at all, because it tries to optimize
> by eliminating "unnecessary" tablet compactions. Also true of MiniAccumulo.
> This is bad for "generator" iterators that emit keys (in sorted order) not
> present in the tablet's data. They never have a chance to run.
> A workaround is for the client to insert "dummy data" into the tablets he
> wants to compact, before starting a compaction with "generator" iterators.
> Note that *any* data is sufficient, even delete keys, to ensure the SKVI
> stack is built and run through.
> Test file:
> [InjectTest|https://github.com/Accla/d4m_api_java/blob/master/src/test/java/edu/mit/ll/graphulo/InjectTest.java]
> * method {{testInjectOnCompact_Empty}} fails because no data is in the tablets
> * method {{testInjectOnCompact}} passes because data is written into the
> tablets before initiating compaction.
> Two logs follow. Both have a
> [DebugIterator|https://accumulo.apache.org/1.6/apidocs/org/apache/accumulo/core/iterators/DebugIterator.html]
> set after an
> [InjectIterator|https://github.com/Accla/d4m_api_java/blob/master/src/main/java/edu/mit/ll/graphulo/InjectIterator.java],
> an SKVI which "generates" hard-coded entries. The root user scans the table
> after flush and compact.
> Logs when no data in the tablets:
> {code}
> 2015-03-05 06:58:04,914 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:04,974 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.01 secs, nbTimes = [4 4 4.00 1]
> 2015-03-05 06:58:04,992 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 15 entries in 0.01 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:05,018 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 2 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:05,052 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 4 entries in 0.00 secs, nbTimes = [2 2 2.00 1]
> 2015-03-05 06:58:05,054 [tserver.TabletServer] DEBUG: Got compact message
> from user: !SYSTEM
> 2015-03-05 06:58:05,135 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 15 entries in 0.01 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:05,138 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36422 2 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:05,384 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36422 2 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:05,386 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 15 entries in 0.01 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:05,504 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 2 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:05,509 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36422 15 entries in 0.01 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:06,069 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.01 secs, nbTimes = [3 3 3.00 1]
> 2015-03-05 06:58:06,158 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36456;
> 2015-03-05 06:58:06,158 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36456;
> 2015-03-05 06:58:06,168 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36456 2 entries in 0.01 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:06,174 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36456;
> 2015-03-05 06:58:06,174 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36456; action: scan; targetTable:
> test_InjectTest_testInjectOnCompact_Empty; authorizations: ; range:
> {5m;f<=[(-inf,f%00; : [] 9223372036854775807 false)]}; columns: [];
> iterators: []; iteratorOptions: {};
> 2015-03-05 06:58:06,174 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36478;
> 2015-03-05 06:58:06,174 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36456;
> 2015-03-05 06:58:06,174 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36478; action: scan; targetTable:
> test_InjectTest_testInjectOnCompact_Empty; authorizations: ; range:
> {5m<;f=[[f%00; : [] 9223372036854775807 false,+inf)]}; columns: [];
> iterators: []; iteratorOptions: {};
> 2015-03-05 06:58:06,175 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36478;
> 2015-03-05 06:58:06,176 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36478 0 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:06,176 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36456 0 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> {code}
> Logs when data is in the tablets:
> {code}
> 2015-03-05 06:58:31,673 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:31,674 [tserver.NativeMap] DEBUG: Allocated native map
> 0x000000000300ac20
> 2015-03-05 06:58:31,674 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 17 entries in 0.01 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:31,676 [tserver.Tablet] DEBUG: MinC initiate lock 0.00 secs
> 2015-03-05 06:58:31,676 [tserver.NativeMap] DEBUG: Allocated native map
> 0x000000000300acc0
> 2015-03-05 06:58:31,676 [tserver.Tablet] DEBUG: MinC initiate lock 0.00 secs
> 2015-03-05 06:58:31,684 [tserver.MinorCompactor] DEBUG: Begin minor
> compaction hdfs://localhost:9000/accumulo/tables/5n/t-00001h5/F00001h6.rf_tmp
> 5n;f<
> 2015-03-05 06:58:31,691 [tserver.MinorCompactor] DEBUG: Begin minor
> compaction
> hdfs://localhost:9000/accumulo/tables/5n/default_tablet/F00001h7.rf_tmp 5n<;f
> 2015-03-05 06:58:31,725 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:31,726 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:31,766 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 2 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:31,779 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:31,780 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:31,833 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:31,834 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:31,886 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:31,887 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:31,939 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:31,940 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:31,992 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:31,993 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:32,046 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [0 0 0.00 1]
> 2015-03-05 06:58:32,047 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:32,099 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:32,100 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:32,147 [tserver.Compactor] DEBUG: Compaction 5n;f< 1 read |
> 1 written | 2,147,483,647 entries/sec | 0.000 secs
> 2015-03-05 06:58:32,148 [tserver.Compactor] DEBUG: Compaction 5n<;f 2 read |
> 2 written | 2,147,483,647 entries/sec | 0.000 secs
> 2015-03-05 06:58:32,153 [tserver.Tablet] DEBUG: Logs for memory compacted:
> 5n;f<
> localhost:9997/hdfs://localhost:9000/accumulo/wal/localhost+9997/706df902-2745-4c08-86bf-dfd4e9b8c729
> 2015-03-05 06:58:32,153 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [2 2 2.00 1]
> 2015-03-05 06:58:32,153 [tserver.Tablet] DEBUG: Logs to be destroyed: 5n;f<
> localhost:9997/hdfs://localhost:9000/accumulo/wal/localhost+9997/706df902-2745-4c08-86bf-dfd4e9b8c729
> 2015-03-05 06:58:32,155 [tserver.Tablet] DEBUG: Logs for memory compacted:
> 5n<;f
> localhost:9997/hdfs://localhost:9000/accumulo/wal/localhost+9997/706df902-2745-4c08-86bf-dfd4e9b8c729
> 2015-03-05 06:58:32,155 [tserver.Tablet] DEBUG: Logs to be destroyed: 5n<;f
> localhost:9997/hdfs://localhost:9000/accumulo/wal/localhost+9997/706df902-2745-4c08-86bf-dfd4e9b8c729
> 2015-03-05 06:58:32,155 [tserver.TabletServer] DEBUG: Got flush message from
> user: !SYSTEM
> 2015-03-05 06:58:32,172 [log.TabletServerLogger] DEBUG: wrote MinC finish
> 400: writeTime:10ms
> 2015-03-05 06:58:32,173 [tserver.Tablet] TABLET_HIST: 5n;f< MinC [memory] ->
> hdfs://localhost:9000/accumulo/tables/5n/t-00001h5/F00001h6.rf
> 2015-03-05 06:58:32,173 [tserver.Tablet] DEBUG: MinC finish lock 0.00 secs
> 5n;f<
> 2015-03-05 06:58:32,173 [tserver.NativeMap] DEBUG: Deallocating native map
> 0x00000000037a1720
> 2015-03-05 06:58:32,178 [log.TabletServerLogger] DEBUG: wrote MinC finish
> 401: writeTime:11ms
> 2015-03-05 06:58:32,178 [tserver.Tablet] TABLET_HIST: 5n<;f MinC [memory] ->
> hdfs://localhost:9000/accumulo/tables/5n/default_tablet/F00001h7.rf
> 2015-03-05 06:58:32,178 [tserver.Tablet] DEBUG: MinC finish lock 0.00 secs
> 5n<;f
> 2015-03-05 06:58:32,178 [tserver.NativeMap] DEBUG: Deallocating native map
> 0x000000000300aaa0
> 2015-03-05 06:58:32,208 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:32,253 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 4 entries in 0.00 secs, nbTimes = [1 1 1.00 1]
> 2015-03-05 06:58:32,254 [tserver.TabletServer] DEBUG: Got compact message
> from user: !SYSTEM
> 2015-03-05 06:58:32,256 [tserver.Tablet] DEBUG: Major compaction plan: null
> propogate deletes : false
> 2015-03-05 06:58:32,256 [tserver.Tablet] DEBUG: MajC initiate lock 0.00 secs,
> wait 0.00 secs
> 2015-03-05 06:58:32,256 [tserver.Tablet] DEBUG: Major compaction plan: null
> propogate deletes : false
> 2015-03-05 06:58:32,256 [tserver.Tablet] DEBUG: MajC initiate lock 0.00 secs,
> wait 0.00 secs
> 2015-03-05 06:58:32,257 [tserver.Tablet] DEBUG: Starting MajC 5n<;f (USER)
> [hdfs://localhost:9000/accumulo/tables/5n/default_tablet/F00001h7.rf] -->
> hdfs://localhost:9000/accumulo/tables/5n/default_tablet/A00001h8.rf_tmp
> [name:InjectIterator, priority:15, class:edu.mit.ll.graphulo.InjectIterator,
> properties:{}, name:DebugIterator, priority:16,
> class:org.apache.accumulo.core.iterators.DebugIterator, properties:{}]
> 2015-03-05 06:58:32,257 [tserver.Tablet] DEBUG: Starting MajC 5n;f< (USER)
> [hdfs://localhost:9000/accumulo/tables/5n/t-00001h5/F00001h6.rf] -->
> hdfs://localhost:9000/accumulo/tables/5n/t-00001h5/A00001h9.rf_tmp
> [name:InjectIterator, priority:15, class:edu.mit.ll.graphulo.InjectIterator,
> properties:{}, name:DebugIterator, priority:16,
> class:org.apache.accumulo.core.iterators.DebugIterator, properties:{}]
> 2015-03-05 06:58:32,270 [graphulo.BranchIterator] INFO : class
> edu.mit.ll.graphulo.InjectIterator: init on scope majc fullScan=true
> 2015-03-05 06:58:32,270 [iterators.DebugIterator] DEBUG:
> init(edu.mit.ll.graphulo.InjectIterator@20a11899, {},
> org.apache.accumulo.tserver.TabletIteratorEnvironment@3055f58b)
> 2015-03-05 06:58:32,271 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> seek([f%00; : [] 9223372036854775807 false,+inf), [], false)
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> kTablet2 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> kTablet2 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> kTablet2 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopValue() --> 7
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177 next()
> 2015-03-05 06:58:32,272 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> m1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> m1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> m1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopValue() --> 1
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 next()
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> zTablet2 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> zTablet2 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> true
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopKey() --> zTablet2 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177
> getTopValue() --> 7
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 next()
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> false
> 2015-03-05 06:58:32,273 [iterators.DebugIterator] DEBUG: 0x5B6B9177 hasTop()
> --> false
> 2015-03-05 06:58:32,275 [graphulo.BranchIterator] INFO : class
> edu.mit.ll.graphulo.InjectIterator: init on scope majc fullScan=true
> 2015-03-05 06:58:32,275 [iterators.DebugIterator] DEBUG:
> init(edu.mit.ll.graphulo.InjectIterator@6b76817, {},
> org.apache.accumulo.tserver.TabletIteratorEnvironment@7312c172)
> 2015-03-05 06:58:32,275 [iterators.DebugIterator] DEBUG: 0x545A5E63
> seek((-inf,f%00; : [] 9223372036854775807 false), [], false)
> 2015-03-05 06:58:32,276 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,276 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> a1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,276 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> a1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopValue() --> 1
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 next()
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> aTablet1 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> aTablet1 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> aTablet1 :cq [] 1425556711614 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopValue() --> 7
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 next()
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> c1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> c1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> c1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopValue() --> 1
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 next()
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> m1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> m1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> true
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopKey() --> m1 colF3:colQ3 [] 1425553534769 false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63
> getTopValue() --> 1
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 next()
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> false
> 2015-03-05 06:58:32,277 [iterators.DebugIterator] DEBUG: 0x545A5E63 hasTop()
> --> false
> 2015-03-05 06:58:32,307 [tserver.Compactor] DEBUG: Compaction 5n<;f 2 read |
> 3 written | 181 entries/sec | 0.011 secs
> 2015-03-05 06:58:32,320 [tserver.TabletServer] INFO : Adding 1 logs for
> extent !0<;~ as alias 3
> 2015-03-05 06:58:32,340 [tserver.Tablet] DEBUG: MajC finish lock 0.00 secs
> 2015-03-05 06:58:32,340 [tserver.Tablet] TABLET_HIST: 5n<;f MajC
> [hdfs://localhost:9000/accumulo/tables/5n/default_tablet/F00001h7.rf] -->
> hdfs://localhost:9000/accumulo/tables/5n/default_tablet/A00001h8.rf
> 2015-03-05 06:58:32,612 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36422 2 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:32,612 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 17 entries in 0.01 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:32,718 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36525;
> 2015-03-05 06:58:32,722 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36525;
> 2015-03-05 06:58:32,725 [tserver.Compactor] DEBUG: Compaction 5n;f< 1 read |
> 4 written | 71 entries/sec | 0.014 secs
> 2015-03-05 06:58:32,736 [tserver.TabletServer] DEBUG: UpSess 127.0.0.1:36525
> 59 in 0.018s, at=[0 0 0.00 1] ft=0.013s(pt=0.000s lt=0.013s ct=0.000s)
> 2015-03-05 06:58:32,749 [tserver.Tablet] DEBUG: MajC finish lock 0.00 secs
> 2015-03-05 06:58:32,749 [tserver.Tablet] TABLET_HIST: 5n;f< MajC
> [hdfs://localhost:9000/accumulo/tables/5n/t-00001h5/F00001h6.rf] -->
> hdfs://localhost:9000/accumulo/tables/5n/t-00001h5/A00001h9.rf
> 2015-03-05 06:58:33,269 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:60455 !0 6 entries in 0.00 secs, nbTimes = [4 4 4.00 1]
> 2015-03-05 06:58:33,361 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36506;
> 2015-03-05 06:58:33,361 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36526;
> 2015-03-05 06:58:33,362 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36506; action: scan; targetTable:
> test_InjectTest_testInjectOnCompact; authorizations: ; range:
> {5n;f<=[(-inf,f%00; : [] 9223372036854775807 false)]}; columns: [];
> iterators: []; iteratorOptions: {};
> 2015-03-05 06:58:33,362 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36526; action: scan; targetTable:
> test_InjectTest_testInjectOnCompact; authorizations: ; range: {5n<;f=[[f%00;
> : [] 9223372036854775807 false,+inf)]}; columns: []; iterators: [];
> iteratorOptions: {};
> 2015-03-05 06:58:33,362 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36506;
> 2015-03-05 06:58:33,362 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36526;
> 2015-03-05 06:58:33,378 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36526 3 entries in 0.02 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:33,378 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36506 3 entries in 0.02 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:33,459 [tserver.TabletServer] DEBUG: ScanSess tid
> 127.0.0.1:36530 !0 0 entries in 0.00 secs, nbTimes = [0 0 0.00 1]
> 2015-03-05 06:58:33,493 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36525;
> 2015-03-05 06:58:33,495 [Audit ] INFO : operation: permitted; user: root;
> client: 127.0.0.1:36525;
> 2015-03-05 06:58:33,505 [tserver.TabletServer] DEBUG: UpSess 127.0.0.1:36525
> 4 in 0.011s, at=[1 1 1.00 1] ft=0.008s(pt=0.000s lt=0.008s ct=0.000s)
> 2015-03-05 06:58:33,580 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:60455 2 entries in 0.00 secs (lookup_time:0.00 secs tablets:1
> ranges:1)
> 2015-03-05 06:58:33,586 [tserver.TabletServer] DEBUG: MultiScanSess
> 127.0.0.1:36422 17 entries in 0.01 secs (lookup_time:0.01 secs tablets:1
> ranges:1)
> {code}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
