13/05/14 17:46:58 INFO auth.Auth: Created default superuser 'cassandra' 13/05/14 17:47:27 ERROR gremlin.GremlinExtension: Gremlin Extension: javax.script.ScriptException: groovy.lang.MissingMethodException: No signature of method: com.thinkaurelius.titan.graphdb.types.StandardTypeMaker.functional() is applicable for argument types: () values: [] javax.script.ScriptException: javax.script.ScriptException: groovy.lang.MissingMethodException: No signature of method: com.thinkaurelius.titan.graphdb.types.StandardTypeMaker.functional() is applicable for argument types: () values: [] at com.tinkerpop.gremlin.groovy.jsr223.GremlinGroovyScriptEngine.eval(GremlinGroovyScriptEngine.java:110) at javax.script.AbstractScriptEngine.eval(AbstractScriptEngine.java:216) at com.tinkerpop.rexster.gremlin.GremlinExtension.tryExecuteGremlinScript(GremlinExtension.java:253) at com.tinkerpop.rexster.gremlin.GremlinExtension.evaluatePostOnGraph(GremlinExtension.java:179) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at com.tinkerpop.rexster.AbstractSubResource.invokeExtension(AbstractSubResource.java:321) at com.tinkerpop.rexster.AbstractSubResource.invokeExtension(AbstractSubResource.java:229) at com.tinkerpop.rexster.GraphResource.executeGraphExtension(GraphResource.java:269) at com.tinkerpop.rexster.GraphResource.postGraphExtension(GraphResource.java:192) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at com.sun.jersey.spi.container.JavaMethodInvokerFactory$1.invoke(JavaMethodInvokerFactory.java:60) at com.sun.jersey.server.impl.model.method.dispatch.AbstractResourceMethodDispatchProvider$ResponseOutInvoker._dispatch(AbstractResourceMethodDispatchProvider.java:205) at com.sun.jersey.server.impl.model.method.dispatch.ResourceJavaMethodDispatcher.dispatch(ResourceJavaMethodDispatcher.java:75) at com.sun.jersey.server.impl.uri.rules.HttpMethodRule.accept(HttpMethodRule.java:288) at com.sun.jersey.server.impl.uri.rules.RightHandPathRule.accept(RightHandPathRule.java:147) at com.sun.jersey.server.impl.uri.rules.ResourceClassRule.accept(ResourceClassRule.java:108) at com.sun.jersey.server.impl.uri.rules.RightHandPathRule.accept(RightHandPathRule.java:147) at com.sun.jersey.server.impl.uri.rules.RootResourceClassesRule.accept(RootResourceClassesRule.java:84) at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1469) at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1400) at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1349) at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1339) at com.sun.jersey.spi.container.servlet.WebComponent.service(WebComponent.java:416) at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:537) at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:699) at javax.servlet.http.HttpServlet.service(HttpServlet.java:770) at org.glassfish.grizzly.servlet.FilterChainImpl.doFilter(FilterChainImpl.java:147) at org.glassfish.grizzly.servlet.FilterChainImpl.invokeFilterChain(FilterChainImpl.java:106) at org.glassfish.grizzly.servlet.ServletHandler.doServletService(ServletHandler.java:252) at org.glassfish.grizzly.servlet.ServletHandler.service(ServletHandler.java:188) at org.glassfish.grizzly.http.server.HttpHandler.doHandle(HttpHandler.java:164) at org.glassfish.grizzly.http.server.HttpHandlerChain.service(HttpHandlerChain.java:196) at org.glassfish.grizzly.http.server.HttpHandler.doHandle(HttpHandler.java:164) at org.glassfish.grizzly.http.server.HttpServerFilter.handleRead(HttpServerFilter.java:175) at org.glassfish.grizzly.filterchain.ExecutorResolver$9.execute(ExecutorResolver.java:119) at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeFilter(DefaultFilterChain.java:265) at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeChainPart(DefaultFilterChain.java:200) at org.glassfish.grizzly.filterchain.DefaultFilterChain.execute(DefaultFilterChain.java:134) at org.glassfish.grizzly.filterchain.DefaultFilterChain.process(DefaultFilterChain.java:112) at org.glassfish.grizzly.ProcessorExecutor.execute(ProcessorExecutor.java:78) at org.glassfish.grizzly.nio.transport.TCPNIOTransport.fireIOEvent(TCPNIOTransport.java:815) at org.glassfish.grizzly.strategies.AbstractIOStrategy.fireIOEvent(AbstractIOStrategy.java:112) at org.glassfish.grizzly.strategies.LeaderFollowerNIOStrategy.executeIoEvent(LeaderFollowerNIOStrategy.java:102) at org.glassfish.grizzly.strategies.AbstractIOStrategy.executeIoEvent(AbstractIOStrategy.java:88) at org.glassfish.grizzly.nio.SelectorRunner.iterateKeyEvents(SelectorRunner.java:398) at org.glassfish.grizzly.nio.SelectorRunner.iterateKeys(SelectorRunner.java:368) at org.glassfish.grizzly.nio.SelectorRunner.doSelect(SelectorRunner.java:334) at org.glassfish.grizzly.nio.SelectorRunner.run(SelectorRunner.java:264) at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:567) at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.run(AbstractThreadPool.java:547) at java.lang.Thread.run(Thread.java:680) Caused by: javax.script.ScriptException: groovy.lang.MissingMethodException: No signature of method: com.thinkaurelius.titan.graphdb.types.StandardTypeMaker.functional() is applicable for argument types: () values: [] at com.tinkerpop.gremlin.groovy.jsr223.GremlinGroovyScriptEngine.eval(GremlinGroovyScriptEngine.java:237) at com.tinkerpop.gremlin.groovy.jsr223.GremlinGroovyScriptEngine.eval(GremlinGroovyScriptEngine.java:106) ... 56 more Caused by: groovy.lang.MissingMethodException: No signature of method: com.thinkaurelius.titan.graphdb.types.StandardTypeMaker.functional() is applicable for argument types: () values: [] at org.codehaus.groovy.runtime.ScriptBytecodeAdapter.unwrap(ScriptBytecodeAdapter.java:55) at org.codehaus.groovy.runtime.callsite.PojoMetaClassSite.call(PojoMetaClassSite.java:46) at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:42) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:108) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:112) at Script2.run(Script2.groovy:1) at com.tinkerpop.gremlin.groovy.jsr223.GremlinGroovyScriptEngine.eval(GremlinGroovyScriptEngine.java:235) ... 57 more 13/05/14 17:47:27 WARN rexster.GraphResource: The [tp:gremlin+*] extension raised an error response. 14 mai 2013 17:47:27 com.sun.jersey.spi.container.ContainerResponse logException GRAVE: Mapped exception to response: 500 (Internal Server Error) javax.ws.rs.WebApplicationException at com.tinkerpop.rexster.GraphResource.executeGraphExtension(GraphResource.java:301) at com.tinkerpop.rexster.GraphResource.postGraphExtension(GraphResource.java:192) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at com.sun.jersey.spi.container.JavaMethodInvokerFactory$1.invoke(JavaMethodInvokerFactory.java:60) at com.sun.jersey.server.impl.model.method.dispatch.AbstractResourceMethodDispatchProvider$ResponseOutInvoker._dispatch(AbstractResourceMethodDispatchProvider.java:205) at com.sun.jersey.server.impl.model.method.dispatch.ResourceJavaMethodDispatcher.dispatch(ResourceJavaMethodDispatcher.java:75) at com.sun.jersey.server.impl.uri.rules.HttpMethodRule.accept(HttpMethodRule.java:288) at com.sun.jersey.server.impl.uri.rules.RightHandPathRule.accept(RightHandPathRule.java:147) at com.sun.jersey.server.impl.uri.rules.ResourceClassRule.accept(ResourceClassRule.java:108) at com.sun.jersey.server.impl.uri.rules.RightHandPathRule.accept(RightHandPathRule.java:147) at com.sun.jersey.server.impl.uri.rules.RootResourceClassesRule.accept(RootResourceClassesRule.java:84) at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1469) at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1400) at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1349) at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1339) at com.sun.jersey.spi.container.servlet.WebComponent.service(WebComponent.java:416) at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:537) at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:699) at javax.servlet.http.HttpServlet.service(HttpServlet.java:770) at org.glassfish.grizzly.servlet.FilterChainImpl.doFilter(FilterChainImpl.java:147) at org.glassfish.grizzly.servlet.FilterChainImpl.invokeFilterChain(FilterChainImpl.java:106) at org.glassfish.grizzly.servlet.ServletHandler.doServletService(ServletHandler.java:252) at org.glassfish.grizzly.servlet.ServletHandler.service(ServletHandler.java:188) at org.glassfish.grizzly.http.server.HttpHandler.doHandle(HttpHandler.java:164) at org.glassfish.grizzly.http.server.HttpHandlerChain.service(HttpHandlerChain.java:196) at org.glassfish.grizzly.http.server.HttpHandler.doHandle(HttpHandler.java:164) at org.glassfish.grizzly.http.server.HttpServerFilter.handleRead(HttpServerFilter.java:175) at org.glassfish.grizzly.filterchain.ExecutorResolver$9.execute(ExecutorResolver.java:119) at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeFilter(DefaultFilterChain.java:265) at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeChainPart(DefaultFilterChain.java:200) at org.glassfish.grizzly.filterchain.DefaultFilterChain.execute(DefaultFilterChain.java:134) at org.glassfish.grizzly.filterchain.DefaultFilterChain.process(DefaultFilterChain.java:112) at org.glassfish.grizzly.ProcessorExecutor.execute(ProcessorExecutor.java:78) at org.glassfish.grizzly.nio.transport.TCPNIOTransport.fireIOEvent(TCPNIOTransport.java:815) at org.glassfish.grizzly.strategies.AbstractIOStrategy.fireIOEvent(AbstractIOStrategy.java:112) at org.glassfish.grizzly.strategies.LeaderFollowerNIOStrategy.executeIoEvent(LeaderFollowerNIOStrategy.java:102) at org.glassfish.grizzly.strategies.AbstractIOStrategy.executeIoEvent(AbstractIOStrategy.java:88) at org.glassfish.grizzly.nio.SelectorRunner.iterateKeyEvents(SelectorRunner.java:398) at org.glassfish.grizzly.nio.SelectorRunner.iterateKeys(SelectorRunner.java:368) at org.glassfish.grizzly.nio.SelectorRunner.doSelect(SelectorRunner.java:334) at org.glassfish.grizzly.nio.SelectorRunner.run(SelectorRunner.java:264) at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:567) at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.run(AbstractThreadPool.java:547) at java.lang.Thread.run(Thread.java:680) 13/05/14 17:47:33 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 23.48409090909091 (just-counted was 23.48409090909091). calculation took 44ms for 32 columns 13/05/14 17:47:36 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 48.86824324324324 (just-counted was 48.86824324324324). calculation took 29ms for 68 columns 13/05/14 17:47:37 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 23.83653846153846 (just-counted was 23.83653846153846). calculation took 44ms for 64 columns 13/05/14 17:47:37 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 13.17098133654425 (just-counted was 13.17098133654425). calculation took 16ms for 412 columns 13/05/14 17:47:45 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 50.35787671232877 (just-counted was 50.35787671232877). calculation took 31ms for 132 columns 13/05/14 17:47:45 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 24.2044921875 (just-counted was 24.2044921875). calculation took 6ms for 128 columns 13/05/14 17:47:45 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 13.16436473365843 (just-counted was 13.157748130772614). calculation took 7ms for 860 columns 13/05/14 17:48:00 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 51.11810344827586 (just-counted was 51.11810344827586). calculation took 11ms for 260 columns 13/05/14 17:48:01 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 24.20966796875 (just-counted was 24.20966796875). calculation took 12ms for 256 columns 13/05/14 17:48:01 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 13.175762556194712 (just-counted was 13.175762556194712). calculation took 13ms for 1756 columns 13/05/14 17:48:31 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 51.502162629757784 (just-counted was 51.502162629757784). calculation took 20ms for 516 columns 13/05/14 17:48:32 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 25.105799220272903 (just-counted was 25.105799220272903). calculation took 20ms for 512 columns 13/05/14 17:48:32 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 13.219139113559383 (just-counted was 13.219139113559383). calculation took 25ms for 3548 columns 13/05/14 17:49:32 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 51.695190641247834 (just-counted was 51.695190641247834). calculation took 36ms for 1028 columns 13/05/14 17:49:32 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 25.626024390243902 (just-counted was 25.626024390243902). calculation took 38ms for 1025 columns 13/05/14 17:49:32 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 13.241038698376583 (just-counted was 13.241038698376583). calculation took 50ms for 7132 columns 13/05/14 17:51:28 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 51.79195576756288 (just-counted was 51.79195576756288). calculation took 84ms for 2052 columns 13/05/14 17:51:29 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 25.86289653489507 (just-counted was 25.86289653489507). calculation took 66ms for 2049 columns 13/05/14 17:51:29 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 13.138752984249795 (just-counted was 13.036467270123005). calculation took 112ms for 14307 columns 13/05/14 17:53:39 INFO service.GCInspector: GC for PS MarkSweep: 258 ms for 1 collections, 33629864 used; max is 477233152 13/05/14 17:54:49 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 13.02446895392729 (just-counted was 12.910184923604787). calculation took 225ms for 26682 columns 13/05/14 17:55:29 INFO service.GCInspector: GC for PS MarkSweep: 287 ms for 1 collections, 60541136 used; max is 477233152 13/05/14 17:55:37 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 12.818091623311805 (just-counted was 12.61171429269632). calculation took 203ms for 30779 columns 13/05/14 17:55:54 INFO service.GCInspector: GC for PS MarkSweep: 333 ms for 1 collections, 42007304 used; max is 477233152 13/05/14 17:57:08 INFO service.GCInspector: GC for PS MarkSweep: 271 ms for 1 collections, 49646912 used; max is 477233152 13/05/14 17:57:11 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 12.483795645081948 (just-counted was 12.149499666852089). calculation took 225ms for 38975 columns 13/05/14 17:58:37 INFO service.GCInspector: GC for PS MarkSweep: 287 ms for 1 collections, 42063880 used; max is 477233152 13/05/14 17:59:03 INFO service.GCInspector: GC for PS MarkSweep: 288 ms for 1 collections, 94591112 used; max is 477233152 13/05/14 18:01:04 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 54.29447719726232 (just-counted was 54.29447719726232). calculation took 124ms for 4101 columns 13/05/14 18:01:05 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 26.013332926531607 (just-counted was 26.013332926531607). calculation took 121ms for 4097 columns 13/05/14 18:02:13 INFO service.GCInspector: GC for PS MarkSweep: 357 ms for 1 collections, 39289512 used; max is 477233152 13/05/14 18:03:37 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 11.976767478813144 (just-counted was 11.469739312544343). calculation took 334ms for 62087 columns 13/05/14 18:06:57 INFO service.GCInspector: GC for PS MarkSweep: 338 ms for 1 collections, 53337176 used; max is 477233152 13/05/14 18:08:00 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22697471 bytes) 13/05/14 18:08:00 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1917706987(1895125/22697471 serialized/live bytes, 79713 ops) 13/05/14 18:08:00 INFO db.Memtable: Writing Memtable-edgestore@1917706987(1895125/22697471 serialized/live bytes, 79713 ops) 13/05/14 18:08:00 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-1-Data.db (1624372 bytes) for commitlog position ReplayPosition(segmentId=1368546407803, position=8575277) 13/05/14 18:08:43 INFO service.GCInspector: GC for PS MarkSweep: 291 ms for 1 collections, 81177464 used; max is 477233152 13/05/14 18:09:37 INFO service.GCInspector: GC for PS MarkSweep: 286 ms for 1 collections, 62490192 used; max is 477233152 13/05/14 18:10:39 INFO service.GCInspector: GC for PS MarkSweep: 342 ms for 1 collections, 35615624 used; max is 477233152 13/05/14 18:11:41 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 8.276550469910228 (just-counted was 4.576333461007312). calculation took 137ms for 24966 columns 13/05/14 18:11:52 INFO service.GCInspector: GC for PS MarkSweep: 291 ms for 1 collections, 40851944 used; max is 477233152 13/05/14 18:13:15 INFO service.GCInspector: GC for PS MarkSweep: 298 ms for 1 collections, 56699176 used; max is 477233152 13/05/14 18:14:33 INFO service.GCInspector: GC for PS MarkSweep: 304 ms for 1 collections, 55688096 used; max is 477233152 13/05/14 18:15:32 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22620772 bytes) 13/05/14 18:15:32 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1722224998(2733116/22620772 serialized/live bytes, 47944 ops) 13/05/14 18:15:32 INFO db.Memtable: Writing Memtable-edgestore@1722224998(2733116/22620772 serialized/live bytes, 47944 ops) 13/05/14 18:15:32 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-2-Data.db (987230 bytes) for commitlog position ReplayPosition(segmentId=1368546407803, position=15377665) 13/05/14 18:15:33 INFO service.GCInspector: GC for PS MarkSweep: 295 ms for 1 collections, 32990048 used; max is 477233152 13/05/14 18:17:04 INFO service.GCInspector: GC for PS MarkSweep: 274 ms for 1 collections, 56426344 used; max is 477233152 13/05/14 18:18:30 INFO service.GCInspector: GC for PS MarkSweep: 283 ms for 1 collections, 76520872 used; max is 477233152 13/05/14 18:19:48 INFO service.GCInspector: GC for PS MarkSweep: 289 ms for 1 collections, 51527728 used; max is 477233152 13/05/14 18:21:30 INFO service.GCInspector: GC for PS MarkSweep: 354 ms for 1 collections, 65669776 used; max is 477233152 13/05/14 18:22:51 INFO service.GCInspector: GC for PS MarkSweep: 292 ms for 1 collections, 56186152 used; max is 477233152 13/05/14 18:24:29 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 6.253897892145751 (just-counted was 4.231245314381273). calculation took 510ms for 45184 columns 13/05/14 18:24:29 INFO service.GCInspector: GC for PS MarkSweep: 301 ms for 1 collections, 72674760 used; max is 477233152 13/05/14 18:26:06 INFO service.GCInspector: GC for PS MarkSweep: 321 ms for 1 collections, 82243192 used; max is 477233152 13/05/14 18:27:32 INFO service.GCInspector: GC for PS MarkSweep: 356 ms for 1 collections, 154631592 used; max is 477233152 13/05/14 18:28:34 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22625820 bytes) 13/05/14 18:28:34 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1730616297(3617875/22625820 serialized/live bytes, 63359 ops) 13/05/14 18:28:34 INFO db.Memtable: Writing Memtable-edgestore@1730616297(3617875/22625820 serialized/live bytes, 63359 ops) 13/05/14 18:28:34 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-3-Data.db (1281272 bytes) for commitlog position ReplayPosition(segmentId=1368546407803, position=24373512) 13/05/14 18:29:11 INFO service.GCInspector: GC for PS MarkSweep: 383 ms for 1 collections, 37241560 used; max is 477233152 13/05/14 18:30:42 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 53.518349182587194 (just-counted was 52.74222116791207). calculation took 295ms for 8207 columns 13/05/14 18:30:57 INFO service.GCInspector: GC for PS MarkSweep: 288 ms for 1 collections, 149194312 used; max is 477233152 13/05/14 18:31:17 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 26.215240478515625 (just-counted was 26.215240478515625). calculation took 256ms for 8192 columns 13/05/14 18:32:36 INFO service.GCInspector: GC for PS MarkSweep: 297 ms for 1 collections, 40319328 used; max is 477233152 13/05/14 18:34:20 INFO service.GCInspector: GC for PS MarkSweep: 314 ms for 1 collections, 51834272 used; max is 477233152 13/05/14 18:36:11 INFO service.GCInspector: GC for PS MarkSweep: 350 ms for 1 collections, 46853184 used; max is 477233152 13/05/14 18:38:13 INFO service.GCInspector: GC for PS MarkSweep: 320 ms for 1 collections, 147930688 used; max is 477233152 13/05/14 18:40:03 INFO service.GCInspector: GC for PS MarkSweep: 315 ms for 1 collections, 85438280 used; max is 477233152 13/05/14 18:42:04 INFO service.GCInspector: GC for PS MarkSweep: 339 ms for 1 collections, 94677248 used; max is 477233152 13/05/14 18:44:06 INFO service.GCInspector: GC for PS MarkSweep: 339 ms for 1 collections, 116427416 used; max is 477233152 13/05/14 18:46:10 INFO service.GCInspector: GC for PS MarkSweep: 329 ms for 1 collections, 123300536 used; max is 477233152 13/05/14 18:46:15 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22627640 bytes) 13/05/14 18:46:15 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@474672386(3618166/22627640 serialized/live bytes, 63294 ops) 13/05/14 18:46:15 INFO db.Memtable: Writing Memtable-edgestore@474672386(3618166/22627640 serialized/live bytes, 63294 ops) 13/05/14 18:46:15 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-4-Data.db (1285070 bytes) for commitlog position ReplayPosition(segmentId=1368546407803, position=33364135) 13/05/14 18:46:15 INFO compaction.CompactionTask: Compacting [SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-3-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-4-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-1-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-2-Data.db')] 13/05/14 18:46:20 INFO compaction.CompactionTask: Compacted 4 sstables to [/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-5,]. 5?177?944 bytes to 5?228?168 (~100% of original) in 4?780ms = 1,043090MB/s. 15?317 total rows, 8?564 unique. Row merge counts were {1:6007, 2:320, 3:278, 4:1959, } 13/05/14 18:47:48 INFO service.GCInspector: GC for PS MarkSweep: 299 ms for 1 collections, 120919520 used; max is 477233152 13/05/14 18:49:22 INFO service.GCInspector: GC for PS MarkSweep: 344 ms for 1 collections, 37505800 used; max is 477233152 13/05/14 18:50:59 INFO service.GCInspector: GC for PS MarkSweep: 300 ms for 1 collections, 165007592 used; max is 477233152 13/05/14 18:53:05 INFO service.GCInspector: GC for PS MarkSweep: 303 ms for 1 collections, 200344704 used; max is 477233152 13/05/14 18:55:03 INFO service.GCInspector: GC for PS MarkSweep: 322 ms for 1 collections, 51299528 used; max is 477233152 13/05/14 18:57:31 INFO service.GCInspector: GC for PS MarkSweep: 333 ms for 1 collections, 51216448 used; max is 477233152 13/05/14 18:58:57 INFO service.GCInspector: GC for PS MarkSweep: 316 ms for 1 collections, 150971488 used; max is 477233152 13/05/14 19:01:21 INFO service.GCInspector: GC for PS MarkSweep: 320 ms for 1 collections, 50873968 used; max is 477233152 13/05/14 19:02:03 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 5.206485372471725 (just-counted was 4.159072852797698). calculation took 235ms for 54760 columns 13/05/14 19:03:42 INFO service.GCInspector: GC for PS MarkSweep: 340 ms for 1 collections, 175116592 used; max is 477233152 13/05/14 19:06:11 INFO service.GCInspector: GC for PS MarkSweep: 371 ms for 1 collections, 49622256 used; max is 477233152 13/05/14 19:08:45 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22630290 bytes) 13/05/14 19:08:45 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1655872588(4346558/22630290 serialized/live bytes, 75962 ops) 13/05/14 19:08:45 INFO db.Memtable: Writing Memtable-edgestore@1655872588(4346558/22630290 serialized/live bytes, 75962 ops) 13/05/14 19:08:45 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-6-Data.db (1544938 bytes) for commitlog position ReplayPosition(segmentId=1368546407804, position=10603919) 13/05/14 19:09:12 INFO service.GCInspector: GC for PS MarkSweep: 300 ms for 1 collections, 81358384 used; max is 477233152 13/05/14 19:11:44 INFO service.GCInspector: GC for PS MarkSweep: 298 ms for 1 collections, 40499312 used; max is 477233152 13/05/14 19:14:20 INFO service.GCInspector: GC for PS MarkSweep: 310 ms for 1 collections, 176396936 used; max is 477233152 13/05/14 19:16:46 INFO service.GCInspector: GC for PS MarkSweep: 333 ms for 1 collections, 191905264 used; max is 477233152 13/05/14 19:19:24 INFO service.GCInspector: GC for PS MarkSweep: 313 ms for 1 collections, 71141416 used; max is 477233152 13/05/14 19:21:52 INFO service.GCInspector: GC for PS MarkSweep: 324 ms for 1 collections, 48698584 used; max is 477233152 13/05/14 19:23:55 INFO service.GCInspector: GC for PS MarkSweep: 327 ms for 1 collections, 155782872 used; max is 477233152 13/05/14 19:26:49 INFO service.GCInspector: GC for PS MarkSweep: 354 ms for 1 collections, 64816912 used; max is 477233152 13/05/14 19:30:50 INFO service.GCInspector: GC for PS MarkSweep: 336 ms for 1 collections, 55347528 used; max is 477233152 13/05/14 19:33:02 INFO service.GCInspector: GC for PS MarkSweep: 339 ms for 1 collections, 56140288 used; max is 477233152 13/05/14 19:35:09 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22632222 bytes) 13/05/14 19:35:09 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1044149960(4346929/22632222 serialized/live bytes, 75804 ops) 13/05/14 19:35:09 INFO db.Memtable: Writing Memtable-edgestore@1044149960(4346929/22632222 serialized/live bytes, 75804 ops) 13/05/14 19:35:09 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-7-Data.db (1549551 bytes) for commitlog position ReplayPosition(segmentId=1368546407804, position=21385008) 13/05/14 19:35:53 INFO service.GCInspector: GC for PS MarkSweep: 303 ms for 1 collections, 176025088 used; max is 477233152 13/05/14 19:37:41 INFO service.GCInspector: GC for PS MarkSweep: 322 ms for 1 collections, 38575176 used; max is 477233152 13/05/14 19:39:43 INFO service.GCInspector: GC for PS MarkSweep: 306 ms for 1 collections, 204913664 used; max is 477233152 13/05/14 19:42:09 INFO service.GCInspector: GC for PS MarkSweep: 313 ms for 1 collections, 51406160 used; max is 477233152 13/05/14 19:44:25 INFO service.GCInspector: GC for PS MarkSweep: 317 ms for 1 collections, 106456040 used; max is 477233152 13/05/14 19:46:09 INFO service.GCInspector: GC for PS MarkSweep: 351 ms for 1 collections, 48667888 used; max is 477233152 13/05/14 19:48:43 INFO service.GCInspector: GC for PS MarkSweep: 331 ms for 1 collections, 51422728 used; max is 477233152 13/05/14 19:51:43 INFO service.GCInspector: GC for PS MarkSweep: 330 ms for 1 collections, 53214400 used; max is 477233152 13/05/14 19:53:59 INFO service.GCInspector: GC for PS MarkSweep: 340 ms for 1 collections, 102620248 used; max is 477233152 13/05/14 19:56:51 INFO service.GCInspector: GC for PS MarkSweep: 357 ms for 1 collections, 53307096 used; max is 477233152 13/05/14 19:59:46 INFO service.GCInspector: GC for PS MarkSweep: 341 ms for 1 collections, 116595352 used; max is 477233152 13/05/14 20:02:08 INFO service.GCInspector: GC for PS MarkSweep: 352 ms for 1 collections, 60632512 used; max is 477233152 13/05/14 20:03:04 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22620918 bytes) 13/05/14 20:03:04 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@396612544(4344758/22620918 serialized/live bytes, 75168 ops) 13/05/14 20:03:04 INFO db.Memtable: Writing Memtable-edgestore@396612544(4344758/22620918 serialized/live bytes, 75168 ops) 13/05/14 20:03:04 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-8-Data.db (1534576 bytes) for commitlog position ReplayPosition(segmentId=1368546407804, position=32110022) 13/05/14 20:03:04 INFO compaction.CompactionTask: Compacting [SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-5-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-8-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-6-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-7-Data.db')] 13/05/14 20:03:06 INFO compaction.CompactionTask: Compacted 4 sstables to [/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-9,]. 9?857?233 bytes to 9?770?970 (~99% of original) in 1?864ms = 4,999100MB/s. 17?047 total rows, 10?010 unique. Row merge counts were {1:7418, 2:218, 3:303, 4:2071, } 13/05/14 20:05:07 INFO service.GCInspector: GC for PS MarkSweep: 314 ms for 1 collections, 58139136 used; max is 477233152 13/05/14 20:07:40 INFO service.GCInspector: GC for PS MarkSweep: 341 ms for 1 collections, 41249064 used; max is 477233152 13/05/14 20:11:34 INFO service.GCInspector: GC for PS MarkSweep: 323 ms for 1 collections, 115359216 used; max is 477233152 13/05/14 20:15:00 INFO service.GCInspector: GC for PS MarkSweep: 327 ms for 1 collections, 213787312 used; max is 477233152 13/05/14 20:17:17 INFO service.GCInspector: GC for PS MarkSweep: 331 ms for 1 collections, 50378440 used; max is 477233152 13/05/14 20:20:34 INFO service.GCInspector: GC for PS MarkSweep: 361 ms for 1 collections, 47749416 used; max is 477233152 13/05/14 20:23:52 INFO service.GCInspector: GC for PS MarkSweep: 336 ms for 1 collections, 154351664 used; max is 477233152 13/05/14 20:26:39 INFO service.GCInspector: GC for PS MarkSweep: 346 ms for 1 collections, 195749968 used; max is 477233152 13/05/14 20:30:39 INFO service.GCInspector: GC for PS MarkSweep: 353 ms for 1 collections, 158382416 used; max is 477233152 13/05/14 20:33:31 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22622329 bytes) 13/05/14 20:33:31 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1505714500(4345029/22622329 serialized/live bytes, 75167 ops) 13/05/14 20:33:31 INFO db.Memtable: Writing Memtable-edgestore@1505714500(4345029/22622329 serialized/live bytes, 75167 ops) 13/05/14 20:33:31 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-10-Data.db (1540951 bytes) for commitlog position ReplayPosition(segmentId=1368546407805, position=9280707) 13/05/14 20:33:58 INFO service.GCInspector: GC for PS MarkSweep: 327 ms for 1 collections, 145101416 used; max is 477233152 13/05/14 20:38:53 INFO service.GCInspector: GC for PS MarkSweep: 317 ms for 1 collections, 170738496 used; max is 477233152 13/05/14 20:41:53 INFO service.GCInspector: GC for PS MarkSweep: 324 ms for 1 collections, 136411608 used; max is 477233152 13/05/14 20:44:54 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 4.881467460803641 (just-counted was 4.556449549135556). calculation took 134ms for 25119 columns 13/05/14 20:44:57 INFO service.GCInspector: GC for PS MarkSweep: 345 ms for 1 collections, 189483456 used; max is 477233152 13/05/14 20:49:30 INFO service.GCInspector: GC for PS MarkSweep: 374 ms for 1 collections, 98382056 used; max is 477233152 13/05/14 21:16:34 INFO service.GCInspector: GC for PS MarkSweep: 342 ms for 1 collections, 188451136 used; max is 477233152 13/05/14 21:19:59 INFO service.GCInspector: GC for PS MarkSweep: 351 ms for 1 collections, 56004624 used; max is 477233152 13/05/14 21:24:09 INFO service.GCInspector: GC for PS MarkSweep: 370 ms for 1 collections, 221837584 used; max is 477233152 13/05/14 21:30:03 INFO service.GCInspector: GC for PS MarkSweep: 425 ms for 1 collections, 58769864 used; max is 477233152 13/05/14 21:34:14 INFO service.GCInspector: GC for PS MarkSweep: 387 ms for 1 collections, 134083560 used; max is 477233152 13/05/14 21:34:23 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22619665 bytes) 13/05/14 21:34:23 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1227863349(4633784/22619665 serialized/live bytes, 80190 ops) 13/05/14 21:34:23 INFO db.Memtable: Writing Memtable-edgestore@1227863349(4633784/22619665 serialized/live bytes, 80190 ops) 13/05/14 21:34:23 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-11-Data.db (1650183 bytes) for commitlog position ReplayPosition(segmentId=1368546407805, position=20720984) 13/05/14 21:38:27 INFO service.GCInspector: GC for PS MarkSweep: 336 ms for 1 collections, 80347024 used; max is 477233152 13/05/14 21:43:26 INFO service.GCInspector: GC for PS MarkSweep: 338 ms for 1 collections, 214798512 used; max is 477233152 13/05/14 21:46:47 INFO cache.AutoSavingCache: Saved KeyCache (1738 items) in 23 ms 13/05/14 21:47:11 INFO service.GCInspector: GC for PS MarkSweep: 379 ms for 1 collections, 47984080 used; max is 477233152 13/05/14 21:51:56 INFO service.GCInspector: GC for PS MarkSweep: 344 ms for 1 collections, 51981296 used; max is 477233152 13/05/14 21:56:08 INFO service.GCInspector: GC for PS MarkSweep: 360 ms for 1 collections, 59038080 used; max is 477233152 13/05/14 22:00:58 INFO service.GCInspector: GC for PS MarkSweep: 370 ms for 1 collections, 161417216 used; max is 477233152 13/05/14 22:04:57 INFO service.GCInspector: GC for PS MarkSweep: 415 ms for 1 collections, 54979968 used; max is 477233152 13/05/14 22:08:32 INFO service.GCInspector: GC for PS MarkSweep: 362 ms for 1 collections, 108993664 used; max is 477233152 13/05/14 22:13:02 INFO service.GCInspector: GC for PS MarkSweep: 381 ms for 1 collections, 122390792 used; max is 477233152 13/05/14 22:17:37 INFO service.GCInspector: GC for PS MarkSweep: 393 ms for 1 collections, 70721184 used; max is 477233152 13/05/14 22:18:26 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22624996 bytes) 13/05/14 22:18:26 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@601916076(4634876/22624996 serialized/live bytes, 80215 ops) 13/05/14 22:18:26 INFO db.Memtable: Writing Memtable-edgestore@601916076(4634876/22624996 serialized/live bytes, 80215 ops) 13/05/14 22:18:26 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-12-Data.db (1651562 bytes) for commitlog position ReplayPosition(segmentId=1368546407805, position=32164426) 13/05/14 22:18:26 INFO compaction.CompactionTask: Compacting [SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-9-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-11-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-10-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-12-Data.db')] 13/05/14 22:18:29 INFO compaction.CompactionTask: Compacted 4 sstables to [/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-13,]. 14?613?666 bytes to 14?446?731 (~98% of original) in 2?868ms = 4,803862MB/s. 18?541 total rows, 11?510 unique. Row merge counts were {1:8945, 2:187, 3:290, 4:2088, } 13/05/14 22:22:21 INFO service.GCInspector: GC for PS MarkSweep: 373 ms for 1 collections, 79165168 used; max is 477233152 13/05/14 22:27:34 INFO service.GCInspector: GC for PS MarkSweep: 351 ms for 1 collections, 220389464 used; max is 477233152 13/05/14 22:32:06 INFO service.GCInspector: GC for PS MarkSweep: 350 ms for 1 collections, 55736200 used; max is 477233152 13/05/14 22:37:09 INFO service.GCInspector: GC for PS MarkSweep: 364 ms for 1 collections, 59481400 used; max is 477233152 13/05/14 22:41:50 INFO service.GCInspector: GC for PS MarkSweep: 374 ms for 1 collections, 48197248 used; max is 477233152 13/05/14 22:46:06 INFO service.GCInspector: GC for PS MarkSweep: 370 ms for 1 collections, 113807680 used; max is 477233152 13/05/14 22:50:22 INFO service.GCInspector: GC for PS MarkSweep: 369 ms for 1 collections, 158105192 used; max is 477233152 13/05/14 22:55:12 INFO service.GCInspector: GC for PS MarkSweep: 390 ms for 1 collections, 65768672 used; max is 477233152 13/05/14 23:00:32 INFO service.GCInspector: GC for PS MarkSweep: 396 ms for 1 collections, 173631360 used; max is 477233152 13/05/14 23:06:04 INFO service.GCInspector: GC for PS MarkSweep: 383 ms for 1 collections, 67488256 used; max is 477233152 13/05/14 23:06:31 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22623331 bytes) 13/05/14 23:06:31 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@913802355(4634535/22623331 serialized/live bytes, 80187 ops) 13/05/14 23:06:31 INFO db.Memtable: Writing Memtable-edgestore@913802355(4634535/22623331 serialized/live bytes, 80187 ops) 13/05/14 23:06:31 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-14-Data.db (1648358 bytes) for commitlog position ReplayPosition(segmentId=1368546407806, position=10050858) 13/05/14 23:10:53 INFO service.GCInspector: GC for PS MarkSweep: 356 ms for 1 collections, 214136840 used; max is 477233152 13/05/14 23:15:31 INFO service.GCInspector: GC for PS MarkSweep: 344 ms for 1 collections, 105302888 used; max is 477233152 13/05/14 23:21:07 INFO service.GCInspector: GC for PS MarkSweep: 357 ms for 1 collections, 49827464 used; max is 477233152 13/05/14 23:26:04 INFO service.GCInspector: GC for PS MarkSweep: 354 ms for 1 collections, 57204952 used; max is 477233152 13/05/14 23:31:00 INFO service.GCInspector: GC for PS MarkSweep: 362 ms for 1 collections, 59718120 used; max is 477233152 13/05/14 23:36:23 INFO service.GCInspector: GC for PS MarkSweep: 364 ms for 1 collections, 64839232 used; max is 477233152 13/05/14 23:41:28 INFO service.GCInspector: GC for PS MarkSweep: 382 ms for 1 collections, 134284376 used; max is 477233152 13/05/14 23:46:19 INFO service.GCInspector: GC for PS MarkSweep: 370 ms for 1 collections, 212268248 used; max is 477233152 13/05/14 23:51:52 INFO service.GCInspector: GC for PS MarkSweep: 379 ms for 1 collections, 66556528 used; max is 477233152 13/05/14 23:57:05 INFO service.GCInspector: GC for PS MarkSweep: 382 ms for 1 collections, 138977808 used; max is 477233152 13/05/14 23:58:24 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22621066 bytes) 13/05/14 23:58:24 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1041013707(4634071/22621066 serialized/live bytes, 80196 ops) 13/05/14 23:58:24 INFO db.Memtable: Writing Memtable-edgestore@1041013707(4634071/22621066 serialized/live bytes, 80196 ops) 13/05/14 23:58:24 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-15-Data.db (1649985 bytes) for commitlog position ReplayPosition(segmentId=1368546407806, position=21491638) 13/05/15 00:01:23 INFO service.GCInspector: GC for PS MarkSweep: 369 ms for 1 collections, 45875280 used; max is 477233152 13/05/15 00:07:21 INFO service.GCInspector: GC for PS MarkSweep: 357 ms for 1 collections, 85007152 used; max is 477233152 13/05/15 00:11:26 INFO service.GCInspector: GC for PS MarkSweep: 364 ms for 1 collections, 122575352 used; max is 477233152 13/05/15 00:17:33 INFO service.GCInspector: GC for PS MarkSweep: 361 ms for 1 collections, 95269064 used; max is 477233152 13/05/15 00:23:03 INFO service.GCInspector: GC for PS MarkSweep: 373 ms for 1 collections, 97030480 used; max is 477233152 13/05/15 00:28:22 INFO service.GCInspector: GC for PS MarkSweep: 360 ms for 1 collections, 135613984 used; max is 477233152 13/05/15 00:33:30 INFO service.GCInspector: GC for PS MarkSweep: 372 ms for 1 collections, 64400952 used; max is 477233152 13/05/15 00:38:11 INFO service.GCInspector: GC for PS MarkSweep: 377 ms for 1 collections, 64778224 used; max is 477233152 13/05/15 00:43:10 INFO service.GCInspector: GC for PS MarkSweep: 397 ms for 1 collections, 56824680 used; max is 477233152 13/05/15 00:49:12 INFO service.GCInspector: GC for PS MarkSweep: 387 ms for 1 collections, 181186712 used; max is 477233152 13/05/15 00:56:07 INFO service.GCInspector: GC for PS MarkSweep: 390 ms for 1 collections, 74099184 used; max is 477233152 13/05/15 00:57:01 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22622028 bytes) 13/05/15 00:57:01 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@439708556(4634268/22622028 serialized/live bytes, 80246 ops) 13/05/15 00:57:01 INFO db.Memtable: Writing Memtable-edgestore@439708556(4634268/22622028 serialized/live bytes, 80246 ops) 13/05/15 00:57:01 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-16-Data.db (1658229 bytes) for commitlog position ReplayPosition(segmentId=1368546407806, position=32937053) 13/05/15 00:57:01 INFO compaction.CompactionTask: Compacting [SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-15-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-16-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-14-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-13-Data.db')] 13/05/15 00:57:05 INFO compaction.CompactionTask: Compacted 4 sstables to [/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-17,]. 19?403?303 bytes to 19?196?622 (~98% of original) in 3?776ms = 4,848338MB/s. 20?082 total rows, 13?047 unique. Row merge counts were {1:10446, 2:221, 3:326, 4:2054, } 13/05/15 01:01:00 INFO service.GCInspector: GC for PS MarkSweep: 368 ms for 1 collections, 59511416 used; max is 477233152 13/05/15 01:07:53 INFO service.GCInspector: GC for PS MarkSweep: 368 ms for 1 collections, 46021856 used; max is 477233152 13/05/15 01:11:50 INFO service.GCInspector: GC for PS MarkSweep: 358 ms for 1 collections, 192377416 used; max is 477233152 13/05/15 01:17:27 INFO service.GCInspector: GC for PS MarkSweep: 379 ms for 1 collections, 130201344 used; max is 477233152 13/05/15 01:23:24 INFO service.GCInspector: GC for PS MarkSweep: 389 ms for 1 collections, 141984032 used; max is 477233152 13/05/15 01:27:45 INFO service.GCInspector: GC for PS MarkSweep: 396 ms for 1 collections, 61469784 used; max is 477233152 13/05/15 01:34:11 INFO service.GCInspector: GC for PS MarkSweep: 369 ms for 1 collections, 61661272 used; max is 477233152 13/05/15 01:39:34 INFO service.GCInspector: GC for PS MarkSweep: 393 ms for 1 collections, 70392136 used; max is 477233152 13/05/15 01:44:21 INFO service.GCInspector: GC for PS MarkSweep: 401 ms for 1 collections, 143833248 used; max is 477233152 13/05/15 01:46:47 INFO cache.AutoSavingCache: Saved KeyCache (1565 items) in 11 ms 13/05/15 01:50:03 INFO service.GCInspector: GC for PS MarkSweep: 431 ms for 1 collections, 70941528 used; max is 477233152 13/05/15 01:54:56 INFO service.GCInspector: GC for PS MarkSweep: 412 ms for 1 collections, 146782248 used; max is 477233152 13/05/15 01:59:43 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22624225 bytes) 13/05/15 01:59:43 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1019868256(4634718/22624225 serialized/live bytes, 80175 ops) 13/05/15 01:59:43 INFO db.Memtable: Writing Memtable-edgestore@1019868256(4634718/22624225 serialized/live bytes, 80175 ops) 13/05/15 01:59:43 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-18-Data.db (1652536 bytes) for commitlog position ReplayPosition(segmentId=1368546407807, position=10822723) 13/05/15 01:59:55 INFO service.GCInspector: GC for PS MarkSweep: 371 ms for 1 collections, 60092296 used; max is 477233152 13/05/15 02:05:55 INFO service.GCInspector: GC for PS MarkSweep: 373 ms for 1 collections, 60067704 used; max is 477233152 13/05/15 02:10:37 INFO service.GCInspector: GC for PS MarkSweep: 393 ms for 1 collections, 51358880 used; max is 477233152 13/05/15 02:15:24 INFO service.GCInspector: GC for PS MarkSweep: 381 ms for 1 collections, 102032400 used; max is 477233152 13/05/15 02:21:47 INFO service.GCInspector: GC for PS MarkSweep: 377 ms for 1 collections, 146769264 used; max is 477233152 13/05/15 02:26:43 INFO service.GCInspector: GC for PS MarkSweep: 388 ms for 1 collections, 68384888 used; max is 477233152 13/05/15 02:32:14 INFO service.GCInspector: GC for PS MarkSweep: 410 ms for 1 collections, 58657832 used; max is 477233152 13/05/15 02:37:05 INFO service.GCInspector: GC for PS MarkSweep: 393 ms for 1 collections, 162178920 used; max is 477233152 13/05/15 02:41:15 INFO service.GCInspector: GC for PS MarkSweep: 397 ms for 1 collections, 70785624 used; max is 477233152 13/05/15 02:44:58 INFO service.GCInspector: GC for PS MarkSweep: 400 ms for 1 collections, 70299032 used; max is 477233152 13/05/15 02:48:46 INFO service.GCInspector: GC for PS MarkSweep: 418 ms for 1 collections, 132748576 used; max is 477233152 13/05/15 02:52:47 INFO service.GCInspector: GC for PS MarkSweep: 409 ms for 1 collections, 221873480 used; max is 477233152 13/05/15 02:56:34 INFO service.GCInspector: GC for PS MarkSweep: 410 ms for 1 collections, 130890240 used; max is 477233152 13/05/15 03:02:08 INFO service.GCInspector: GC for PS MarkSweep: 413 ms for 1 collections, 74080128 used; max is 477233152 13/05/15 03:05:28 INFO service.GCInspector: GC for PS MarkSweep: 420 ms for 1 collections, 70274072 used; max is 477233152 13/05/15 03:07:37 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22619680 bytes) 13/05/15 03:07:37 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@447895873(4633787/22619680 serialized/live bytes, 80185 ops) 13/05/15 03:07:37 INFO db.Memtable: Writing Memtable-edgestore@447895873(4633787/22619680 serialized/live bytes, 80185 ops) 13/05/15 03:07:37 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-19-Data.db (1653032 bytes) for commitlog position ReplayPosition(segmentId=1368546407807, position=22262487) 13/05/15 03:09:54 INFO service.GCInspector: GC for PS MarkSweep: 381 ms for 1 collections, 60469808 used; max is 477233152 13/05/15 03:13:22 INFO service.GCInspector: GC for PS MarkSweep: 377 ms for 1 collections, 60748880 used; max is 477233152 13/05/15 03:15:40 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='edgestore') liveRatio is 5.349188335429524 (just-counted was 5.349188335429524). calculation took 59ms for 8864 columns 13/05/15 03:16:23 INFO service.GCInspector: GC for PS MarkSweep: 382 ms for 1 collections, 205384400 used; max is 477233152 13/05/15 03:19:47 INFO service.GCInspector: GC for PS MarkSweep: 398 ms for 1 collections, 53903864 used; max is 477233152 13/05/15 03:22:58 INFO service.GCInspector: GC for PS MarkSweep: 390 ms for 1 collections, 72507880 used; max is 477233152 13/05/15 03:26:42 INFO service.GCInspector: GC for PS MarkSweep: 395 ms for 1 collections, 66706888 used; max is 477233152 13/05/15 03:29:47 INFO service.GCInspector: GC for PS MarkSweep: 379 ms for 1 collections, 170383048 used; max is 477233152 13/05/15 03:32:27 INFO service.GCInspector: GC for PS MarkSweep: 409 ms for 1 collections, 56707088 used; max is 477233152 13/05/15 03:35:16 INFO service.GCInspector: GC for PS MarkSweep: 399 ms for 1 collections, 188394176 used; max is 477233152 13/05/15 03:38:32 INFO service.GCInspector: GC for PS MarkSweep: 405 ms for 1 collections, 95936144 used; max is 477233152 13/05/15 03:43:15 INFO service.GCInspector: GC for PS MarkSweep: 409 ms for 1 collections, 187530376 used; max is 477233152 13/05/15 03:46:53 INFO service.GCInspector: GC for PS MarkSweep: 422 ms for 1 collections, 63118064 used; max is 477233152 13/05/15 03:49:13 INFO service.GCInspector: GC for PS MarkSweep: 411 ms for 1 collections, 82430040 used; max is 477233152 13/05/15 03:52:12 INFO service.GCInspector: GC for PS MarkSweep: 411 ms for 1 collections, 111755976 used; max is 477233152 13/05/15 03:54:27 INFO service.GCInspector: GC for PS MarkSweep: 419 ms for 1 collections, 74720568 used; max is 477233152 13/05/15 03:57:23 INFO service.GCInspector: GC for PS MarkSweep: 431 ms for 1 collections, 59403736 used; max is 477233152 13/05/15 04:01:13 INFO service.GCInspector: GC for PS MarkSweep: 420 ms for 1 collections, 75729120 used; max is 477233152 13/05/15 04:03:51 INFO service.GCInspector: GC for PS MarkSweep: 422 ms for 1 collections, 83917368 used; max is 477233152 13/05/15 04:07:03 INFO service.GCInspector: GC for PS MarkSweep: 427 ms for 1 collections, 114583568 used; max is 477233152 13/05/15 04:12:03 INFO service.GCInspector: GC for PS MarkSweep: 427 ms for 1 collections, 130494016 used; max is 477233152 13/05/15 04:13:46 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22621193 bytes) 13/05/15 04:13:46 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1141680276(4228902/22621193 serialized/live bytes, 73183 ops) 13/05/15 04:13:46 INFO db.Memtable: Writing Memtable-edgestore@1141680276(4228902/22621193 serialized/live bytes, 73183 ops) 13/05/15 04:13:46 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-20-Data.db (1514840 bytes) for commitlog position ReplayPosition(segmentId=1368546407807, position=32703016) 13/05/15 04:13:46 INFO compaction.CompactionTask: Compacting [SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-17-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-19-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-18-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-20-Data.db')] 13/05/15 04:13:51 INFO compaction.CompactionTask: Compacted 4 sstables to [/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-21,]. 24?017?030 bytes to 23?782?145 (~99% of original) in 4?728ms = 4,797043MB/s. 21?489 total rows, 14?524 unique. Row merge counts were {1:11952, 2:279, 3:193, 4:2100, } 13/05/15 04:15:06 INFO service.GCInspector: GC for PS MarkSweep: 387 ms for 1 collections, 171884712 used; max is 477233152 13/05/15 04:17:33 INFO service.GCInspector: GC for PS MarkSweep: 388 ms for 1 collections, 133485424 used; max is 477233152 13/05/15 04:20:28 INFO service.GCInspector: GC for PS MarkSweep: 393 ms for 1 collections, 64048384 used; max is 477233152 13/05/15 04:22:49 INFO service.GCInspector: GC for PS MarkSweep: 388 ms for 1 collections, 113872664 used; max is 477233152 13/05/15 04:24:48 INFO service.GCInspector: GC for PS MarkSweep: 392 ms for 1 collections, 70265088 used; max is 477233152 13/05/15 04:28:12 INFO service.GCInspector: GC for PS MarkSweep: 396 ms for 1 collections, 65906608 used; max is 477233152 13/05/15 04:31:35 INFO service.GCInspector: GC for PS MarkSweep: 398 ms for 1 collections, 132404296 used; max is 477233152 13/05/15 04:34:00 INFO service.GCInspector: GC for PS MarkSweep: 420 ms for 1 collections, 196094344 used; max is 477233152 13/05/15 04:36:42 INFO service.GCInspector: GC for PS MarkSweep: 403 ms for 1 collections, 167880816 used; max is 477233152 13/05/15 04:39:41 INFO service.GCInspector: GC for PS MarkSweep: 407 ms for 1 collections, 78912624 used; max is 477233152 13/05/15 04:41:59 INFO service.GCInspector: GC for PS MarkSweep: 409 ms for 1 collections, 70683464 used; max is 477233152 13/05/15 04:45:01 INFO service.GCInspector: GC for PS MarkSweep: 446 ms for 1 collections, 103889512 used; max is 477233152 13/05/15 04:48:14 INFO service.GCInspector: GC for PS MarkSweep: 412 ms for 1 collections, 69415864 used; max is 477233152 13/05/15 04:50:45 INFO service.GCInspector: GC for PS MarkSweep: 394 ms for 1 collections, 65816776 used; max is 477233152 13/05/15 04:53:01 INFO service.GCInspector: GC for PS MarkSweep: 414 ms for 1 collections, 72764000 used; max is 477233152 13/05/15 04:55:02 INFO service.GCInspector: GC for PS MarkSweep: 431 ms for 1 collections, 69622944 used; max is 477233152 13/05/15 04:57:10 INFO service.GCInspector: GC for PS MarkSweep: 417 ms for 1 collections, 192282632 used; max is 477233152 13/05/15 04:59:26 INFO service.GCInspector: GC for PS MarkSweep: 422 ms for 1 collections, 75439176 used; max is 477233152 13/05/15 05:02:48 INFO service.GCInspector: GC for PS MarkSweep: 425 ms for 1 collections, 76708544 used; max is 477233152 13/05/15 05:05:31 INFO service.GCInspector: GC for PS MarkSweep: 443 ms for 1 collections, 64609624 used; max is 477233152 13/05/15 05:08:04 INFO service.GCInspector: GC for PS MarkSweep: 428 ms for 1 collections, 74422736 used; max is 477233152 13/05/15 05:10:21 INFO service.GCInspector: GC for PS MarkSweep: 414 ms for 1 collections, 102107200 used; max is 477233152 13/05/15 05:11:52 INFO service.GCInspector: GC for PS MarkSweep: 417 ms for 1 collections, 73213448 used; max is 477233152 13/05/15 05:14:12 INFO service.GCInspector: GC for PS MarkSweep: 470 ms for 1 collections, 182506968 used; max is 477233152 13/05/15 05:17:04 INFO service.GCInspector: GC for PS MarkSweep: 435 ms for 1 collections, 117465664 used; max is 477233152 13/05/15 05:20:07 INFO service.GCInspector: GC for PS MarkSweep: 439 ms for 1 collections, 79663192 used; max is 477233152 13/05/15 05:22:18 INFO service.GCInspector: GC for PS MarkSweep: 440 ms for 1 collections, 81836216 used; max is 477233152 13/05/15 05:24:07 INFO service.GCInspector: GC for PS MarkSweep: 484 ms for 1 collections, 68473176 used; max is 477233152 13/05/15 05:25:41 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22620134 bytes) 13/05/15 05:25:41 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@2117293307(4228704/22620134 serialized/live bytes, 73191 ops) 13/05/15 05:25:41 INFO db.Memtable: Writing Memtable-edgestore@2117293307(4228704/22620134 serialized/live bytes, 73191 ops) 13/05/15 05:25:41 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-22-Data.db (1516498 bytes) for commitlog position ReplayPosition(segmentId=1368546407808, position=9589838) 13/05/15 05:26:15 INFO service.GCInspector: GC for PS MarkSweep: 403 ms for 1 collections, 82994544 used; max is 477233152 13/05/15 05:29:15 INFO service.GCInspector: GC for PS MarkSweep: 394 ms for 1 collections, 133167128 used; max is 477233152 13/05/15 05:31:28 INFO service.GCInspector: GC for PS MarkSweep: 407 ms for 1 collections, 189097928 used; max is 477233152 13/05/15 05:33:43 INFO service.GCInspector: GC for PS MarkSweep: 420 ms for 1 collections, 159054568 used; max is 477233152 13/05/15 05:36:03 INFO service.GCInspector: GC for PS MarkSweep: 414 ms for 1 collections, 67262600 used; max is 477233152 13/05/15 05:39:56 INFO service.GCInspector: GC for PS MarkSweep: 403 ms for 1 collections, 80633264 used; max is 477233152 13/05/15 05:42:13 INFO service.GCInspector: GC for PS MarkSweep: 416 ms for 1 collections, 71158712 used; max is 477233152 13/05/15 05:45:27 INFO service.GCInspector: GC for PS MarkSweep: 429 ms for 1 collections, 58591696 used; max is 477233152 13/05/15 05:46:47 INFO cache.AutoSavingCache: Saved KeyCache (1581 items) in 15 ms 13/05/15 05:48:28 INFO service.GCInspector: GC for PS MarkSweep: 408 ms for 1 collections, 69725680 used; max is 477233152 13/05/15 05:50:34 INFO service.GCInspector: GC for PS MarkSweep: 426 ms for 1 collections, 74261928 used; max is 477233152 13/05/15 05:53:31 INFO service.GCInspector: GC for PS MarkSweep: 426 ms for 1 collections, 199064064 used; max is 477233152 13/05/15 05:55:14 INFO service.GCInspector: GC for PS MarkSweep: 472 ms for 1 collections, 204361784 used; max is 477233152 13/05/15 05:57:02 INFO service.GCInspector: GC for PS MarkSweep: 428 ms for 1 collections, 82318176 used; max is 477233152 13/05/15 05:59:11 INFO service.GCInspector: GC for PS MarkSweep: 432 ms for 1 collections, 75879984 used; max is 479920128 13/05/15 06:00:43 INFO service.GCInspector: GC for PS MarkSweep: 434 ms for 1 collections, 97828056 used; max is 481624064 13/05/15 06:03:23 INFO service.GCInspector: GC for PS MarkSweep: 445 ms for 1 collections, 79724728 used; max is 477233152 13/05/15 06:05:40 INFO service.GCInspector: GC for PS MarkSweep: 436 ms for 1 collections, 76346504 used; max is 486670336 13/05/15 06:07:40 INFO service.GCInspector: GC for PS MarkSweep: 437 ms for 1 collections, 76521480 used; max is 490995712 13/05/15 06:10:23 INFO service.GCInspector: GC for PS MarkSweep: 442 ms for 1 collections, 78323952 used; max is 494075904 13/05/15 06:12:27 INFO service.GCInspector: GC for PS MarkSweep: 456 ms for 1 collections, 172727960 used; max is 483065856 13/05/15 06:14:24 INFO service.GCInspector: GC for PS MarkSweep: 446 ms for 1 collections, 188705744 used; max is 499646464 13/05/15 06:16:03 INFO service.GCInspector: GC for PS MarkSweep: 441 ms for 1 collections, 77978792 used; max is 502857728 13/05/15 06:18:03 INFO service.GCInspector: GC for PS MarkSweep: 451 ms for 1 collections, 83114880 used; max is 510590976 13/05/15 06:20:53 INFO service.GCInspector: GC for PS MarkSweep: 465 ms for 1 collections, 66595192 used; max is 495648768 13/05/15 06:23:56 INFO service.GCInspector: GC for PS MarkSweep: 431 ms for 1 collections, 75023112 used; max is 512360448 13/05/15 06:25:53 INFO service.GCInspector: GC for PS MarkSweep: 449 ms for 1 collections, 82115768 used; max is 520093696 13/05/15 06:28:07 INFO service.GCInspector: GC for PS MarkSweep: 440 ms for 1 collections, 78455072 used; max is 522977280 13/05/15 06:31:23 INFO service.GCInspector: GC for PS MarkSweep: 474 ms for 1 collections, 67293808 used; max is 513736704 13/05/15 06:33:46 INFO service.GCInspector: GC for PS MarkSweep: 462 ms for 1 collections, 226876688 used; max is 526123008 13/05/15 06:35:44 INFO service.GCInspector: GC for PS MarkSweep: 464 ms for 1 collections, 86368160 used; max is 527040512 13/05/15 06:39:23 INFO service.GCInspector: GC for PS MarkSweep: 452 ms for 1 collections, 140264208 used; max is 526909440 13/05/15 06:41:29 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22621006 bytes) 13/05/15 06:41:29 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@1135891984(4228867/22621006 serialized/live bytes, 73163 ops) 13/05/15 06:41:29 INFO db.Memtable: Writing Memtable-edgestore@1135891984(4228867/22621006 serialized/live bytes, 73163 ops) 13/05/15 06:41:30 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-23-Data.db (1512244 bytes) for commitlog position ReplayPosition(segmentId=1368546407808, position=20028842) 13/05/15 06:41:31 INFO service.GCInspector: GC for PS MarkSweep: 432 ms for 1 collections, 87370256 used; max is 510918656 13/05/15 06:44:17 INFO service.GCInspector: GC for PS MarkSweep: 421 ms for 1 collections, 92739712 used; max is 522518528 13/05/15 06:46:16 INFO service.GCInspector: GC for PS MarkSweep: 430 ms for 1 collections, 73191376 used; max is 527106048 13/05/15 06:49:07 INFO service.GCInspector: GC for PS MarkSweep: 468 ms for 1 collections, 178468192 used; max is 526319616 13/05/15 06:51:22 INFO service.GCInspector: GC for PS MarkSweep: 445 ms for 1 collections, 57092520 used; max is 518127616 13/05/15 06:54:02 INFO service.GCInspector: GC for PS MarkSweep: 431 ms for 1 collections, 93225960 used; max is 523239424 13/05/15 06:55:43 INFO service.GCInspector: GC for PS MarkSweep: 436 ms for 1 collections, 77382336 used; max is 526254080 13/05/15 06:58:11 INFO service.GCInspector: GC for PS MarkSweep: 438 ms for 1 collections, 82844328 used; max is 526188544 13/05/15 07:00:05 INFO service.GCInspector: GC for PS MarkSweep: 441 ms for 1 collections, 57868456 used; max is 518324224 13/05/15 07:03:06 INFO service.GCInspector: GC for PS MarkSweep: 430 ms for 1 collections, 69182224 used; max is 527302656 13/05/15 07:05:11 INFO service.GCInspector: GC for PS MarkSweep: 442 ms for 1 collections, 74601992 used; max is 526778368 13/05/15 07:08:26 INFO service.GCInspector: GC for PS MarkSweep: 437 ms for 1 collections, 186761200 used; max is 526385152 13/05/15 07:10:44 INFO service.GCInspector: GC for PS MarkSweep: 438 ms for 1 collections, 127351768 used; max is 518586368 13/05/15 07:12:34 INFO service.GCInspector: GC for PS MarkSweep: 445 ms for 1 collections, 76625592 used; max is 526778368 13/05/15 07:14:38 INFO service.GCInspector: GC for PS MarkSweep: 430 ms for 1 collections, 92620216 used; max is 527695872 13/05/15 07:16:49 INFO service.GCInspector: GC for PS MarkSweep: 447 ms for 1 collections, 185731648 used; max is 526385152 13/05/15 07:19:45 INFO service.GCInspector: GC for PS MarkSweep: 460 ms for 1 collections, 61869704 used; max is 520945664 13/05/15 07:22:10 INFO service.GCInspector: GC for PS MarkSweep: 435 ms for 1 collections, 182083688 used; max is 525402112 13/05/15 07:23:38 INFO service.GCInspector: GC for PS MarkSweep: 434 ms for 1 collections, 74452584 used; max is 525139968 13/05/15 07:25:34 INFO service.GCInspector: GC for PS MarkSweep: 450 ms for 1 collections, 80035152 used; max is 526516224 13/05/15 07:28:47 INFO service.GCInspector: GC for PS MarkSweep: 465 ms for 1 collections, 96291024 used; max is 525008896 13/05/15 07:30:19 INFO service.GCInspector: GC for PS MarkSweep: 456 ms for 1 collections, 79324448 used; max is 525860864 13/05/15 07:33:39 INFO service.GCInspector: GC for PS MarkSweep: 456 ms for 1 collections, 91769648 used; max is 527040512 13/05/15 07:35:05 INFO service.GCInspector: GC for PS MarkSweep: 438 ms for 1 collections, 147429552 used; max is 525533184 13/05/15 07:36:53 INFO service.GCInspector: GC for PS MarkSweep: 479 ms for 1 collections, 66465816 used; max is 525271040 13/05/15 07:38:58 INFO service.GCInspector: GC for PS MarkSweep: 463 ms for 1 collections, 92275464 used; max is 524943360 13/05/15 07:40:26 INFO service.GCInspector: GC for PS MarkSweep: 458 ms for 1 collections, 229721736 used; max is 526647296 13/05/15 07:42:16 INFO service.GCInspector: GC for PS MarkSweep: 459 ms for 1 collections, 215513064 used; max is 527892480 13/05/15 07:43:17 INFO service.GCInspector: GC for PS MarkSweep: 468 ms for 1 collections, 92635672 used; max is 527171584 13/05/15 07:45:22 INFO service.GCInspector: GC for PS MarkSweep: 461 ms for 1 collections, 119921240 used; max is 527499264 13/05/15 07:47:17 INFO service.GCInspector: GC for PS MarkSweep: 458 ms for 1 collections, 157917784 used; max is 525795328 13/05/15 07:49:06 INFO service.GCInspector: GC for PS MarkSweep: 463 ms for 1 collections, 86565976 used; max is 527499264 13/05/15 07:51:03 INFO service.GCInspector: GC for PS MarkSweep: 472 ms for 1 collections, 68442344 used; max is 525729792 13/05/15 07:52:43 INFO service.GCInspector: GC for PS MarkSweep: 465 ms for 1 collections, 220084240 used; max is 526516224 13/05/15 07:54:51 INFO service.GCInspector: GC for PS MarkSweep: 464 ms for 1 collections, 88690904 used; max is 527826944 13/05/15 07:55:33 INFO service.GCInspector: GC for PS MarkSweep: 445 ms for 1 collections, 95403224 used; max is 526319616 13/05/15 07:58:46 INFO service.GCInspector: GC for PS MarkSweep: 480 ms for 1 collections, 72495736 used; max is 525991936 13/05/15 08:00:25 INFO service.GCInspector: GC for PS MarkSweep: 488 ms for 1 collections, 84044792 used; max is 525926400 13/05/15 08:02:44 INFO service.GCInspector: GC for PS MarkSweep: 459 ms for 1 collections, 166413408 used; max is 526450688 13/05/15 08:04:41 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22620027 bytes) 13/05/15 08:04:41 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@477510552(4228684/22620027 serialized/live bytes, 73229 ops) 13/05/15 08:04:41 INFO db.Memtable: Writing Memtable-edgestore@477510552(4228684/22620027 serialized/live bytes, 73229 ops) 13/05/15 08:04:41 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-24-Data.db (1519436 bytes) for commitlog position ReplayPosition(segmentId=1368546407808, position=30472961) 13/05/15 08:04:41 INFO compaction.CompactionTask: Compacting [SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-23-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-24-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-22-Data.db'), SSTableReader(path='/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-21-Data.db')] 13/05/15 08:04:47 INFO compaction.CompactionTask: Compacted 4 sstables to [/Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-25,]. 28?330?323 bytes to 28?094?087 (~99% of original) in 5?390ms = 4,970799MB/s. 22?754 total rows, 15?933 unique. Row merge counts were {1:13411, 2:228, 3:289, 4:2005, } 13/05/15 08:05:21 INFO service.GCInspector: GC for PS MarkSweep: 410 ms for 1 collections, 123498368 used; max is 526450688 13/05/15 08:06:54 INFO service.GCInspector: GC for PS MarkSweep: 424 ms for 1 collections, 50941904 used; max is 517210112 13/05/15 08:08:54 INFO service.GCInspector: GC for PS MarkSweep: 434 ms for 1 collections, 72554976 used; max is 527368192 13/05/15 08:10:30 INFO service.GCInspector: GC for PS MarkSweep: 402 ms for 1 collections, 122188128 used; max is 517931008 13/05/15 08:12:36 INFO service.GCInspector: GC for PS MarkSweep: 418 ms for 1 collections, 70524816 used; max is 524484608 13/05/15 08:14:42 INFO service.GCInspector: GC for PS MarkSweep: 438 ms for 1 collections, 57254752 used; max is 520224768 13/05/15 08:16:44 INFO service.GCInspector: GC for PS MarkSweep: 440 ms for 1 collections, 212874848 used; max is 523632640 13/05/15 08:18:00 INFO service.GCInspector: GC for PS MarkSweep: 436 ms for 1 collections, 115350064 used; max is 524484608 13/05/15 08:21:13 INFO service.GCInspector: GC for PS MarkSweep: 478 ms for 1 collections, 76000024 used; max is 527564800 13/05/15 08:22:29 INFO service.GCInspector: GC for PS MarkSweep: 453 ms for 1 collections, 61379472 used; max is 520749056 13/05/15 08:24:17 INFO service.GCInspector: GC for PS MarkSweep: 426 ms for 1 collections, 160118504 used; max is 525271040 13/05/15 08:25:26 INFO service.GCInspector: GC for PS MarkSweep: 440 ms for 1 collections, 73723472 used; max is 521469952 13/05/15 08:26:21 INFO service.GCInspector: GC for PS MarkSweep: 441 ms for 1 collections, 74111960 used; max is 523501568 13/05/15 08:27:27 INFO service.GCInspector: GC for PS MarkSweep: 448 ms for 1 collections, 59396928 used; max is 520749056 13/05/15 08:28:30 INFO service.GCInspector: GC for PS MarkSweep: 444 ms for 1 collections, 74987512 used; max is 520028160 13/05/15 08:29:48 INFO service.GCInspector: GC for PS MarkSweep: 448 ms for 1 collections, 110031968 used; max is 526319616 13/05/15 08:32:25 INFO service.GCInspector: GC for PS MarkSweep: 449 ms for 1 collections, 79693728 used; max is 529137664 13/05/15 08:33:54 INFO service.GCInspector: GC for PS MarkSweep: 449 ms for 1 collections, 56777592 used; max is 522321920 13/05/15 08:36:08 INFO service.GCInspector: GC for PS MarkSweep: 431 ms for 1 collections, 68742232 used; max is 527040512 13/05/15 08:37:36 INFO service.GCInspector: GC for PS MarkSweep: 430 ms for 1 collections, 113065824 used; max is 524353536 13/05/15 08:38:43 INFO service.GCInspector: GC for PS MarkSweep: 446 ms for 1 collections, 79657576 used; max is 527106048 13/05/15 08:39:42 INFO service.GCInspector: GC for PS MarkSweep: 455 ms for 1 collections, 62827584 used; max is 526319616 13/05/15 08:40:40 INFO service.GCInspector: GC for PS MarkSweep: 433 ms for 1 collections, 73290920 used; max is 527368192 13/05/15 08:43:31 INFO service.GCInspector: GC for PS MarkSweep: 432 ms for 1 collections, 197438272 used; max is 526843904 13/05/15 08:44:42 INFO service.GCInspector: GC for PS MarkSweep: 433 ms for 1 collections, 75375440 used; max is 527499264 13/05/15 08:46:34 INFO service.GCInspector: GC for PS MarkSweep: 462 ms for 1 collections, 64563648 used; max is 528089088 13/05/15 08:47:48 INFO service.GCInspector: GC for PS MarkSweep: 455 ms for 1 collections, 80790920 used; max is 523304960 13/05/15 08:49:27 INFO service.GCInspector: GC for PS MarkSweep: 435 ms for 1 collections, 174001320 used; max is 524419072 13/05/15 08:52:02 INFO service.GCInspector: GC for PS MarkSweep: 440 ms for 1 collections, 114973000 used; max is 528416768 13/05/15 08:53:24 INFO service.GCInspector: GC for PS MarkSweep: 630 ms for 1 collections, 64603616 used; max is 526974976 13/05/15 08:55:21 INFO service.GCInspector: GC for PS MarkSweep: 459 ms for 1 collections, 81985464 used; max is 527892480 13/05/15 08:56:18 INFO service.GCInspector: GC for PS MarkSweep: 455 ms for 1 collections, 191680592 used; max is 526909440 13/05/15 08:58:04 INFO service.GCInspector: GC for PS MarkSweep: 438 ms for 1 collections, 214425328 used; max is 527761408 13/05/15 08:59:12 INFO service.GCInspector: GC for PS MarkSweep: 495 ms for 1 collections, 92589376 used; max is 529530880 13/05/15 09:00:31 INFO service.GCInspector: GC for PS MarkSweep: 458 ms for 1 collections, 181400352 used; max is 526385152 13/05/15 09:01:38 INFO service.GCInspector: GC for PS MarkSweep: 461 ms for 1 collections, 198628008 used; max is 525664256 13/05/15 09:03:49 INFO service.GCInspector: GC for PS MarkSweep: 443 ms for 1 collections, 80574744 used; max is 527564800 13/05/15 09:07:04 INFO service.GCInspector: GC for PS MarkSweep: 480 ms for 1 collections, 137787472 used; max is 523829248 13/05/15 09:09:12 INFO service.GCInspector: GC for PS MarkSweep: 489 ms for 1 collections, 80212384 used; max is 522780672 13/05/15 09:12:11 INFO service.GCInspector: GC for PS MarkSweep: 556 ms for 1 collections, 86141888 used; max is 527433728 13/05/15 09:13:15 INFO service.GCInspector: GC for PS MarkSweep: 463 ms for 1 collections, 224679896 used; max is 528613376 13/05/15 09:14:11 INFO service.GCInspector: GC for PS MarkSweep: 480 ms for 1 collections, 134520736 used; max is 525402112 13/05/15 09:15:17 INFO service.GCInspector: GC for PS MarkSweep: 487 ms for 1 collections, 233413448 used; max is 525926400 13/05/15 09:16:34 INFO service.GCInspector: GC for PS MarkSweep: 465 ms for 1 collections, 110355312 used; max is 525860864 13/05/15 09:17:19 INFO service.GCInspector: GC for PS MarkSweep: 469 ms for 1 collections, 87746424 used; max is 527499264 13/05/15 09:18:25 INFO service.GCInspector: GC for PS MarkSweep: 477 ms for 1 collections, 70637720 used; max is 527761408 13/05/15 09:20:38 INFO service.GCInspector: GC for PS MarkSweep: 469 ms for 1 collections, 90178272 used; max is 525795328 13/05/15 09:22:08 INFO service.GCInspector: GC for PS MarkSweep: 467 ms for 1 collections, 88916568 used; max is 526123008 13/05/15 09:23:04 INFO service.GCInspector: GC for PS MarkSweep: 467 ms for 1 collections, 89466808 used; max is 525402112 13/05/15 09:25:06 INFO service.GCInspector: GC for PS MarkSweep: 481 ms for 1 collections, 72338616 used; max is 526843904 13/05/15 09:26:38 INFO service.GCInspector: GC for PS MarkSweep: 497 ms for 1 collections, 83489240 used; max is 524615680 13/05/15 09:28:10 INFO service.GCInspector: GC for PS MarkSweep: 483 ms for 1 collections, 89358240 used; max is 525860864 13/05/15 09:30:03 INFO service.GCInspector: GC for PS MarkSweep: 616 ms for 1 collections, 129205896 used; max is 527499264 13/05/15 09:30:23 INFO db.MeteredFlusher: flushing high-traffic column family CFS(Keyspace='titan', ColumnFamily='edgestore') (estimated 22621359 bytes) 13/05/15 09:30:23 INFO db.ColumnFamilyStore: Enqueuing flush of Memtable-edgestore@2069667518(4228933/22621359 serialized/live bytes, 73215 ops) 13/05/15 09:30:23 INFO db.Memtable: Writing Memtable-edgestore@2069667518(4228933/22621359 serialized/live bytes, 73215 ops) 13/05/15 09:30:24 INFO db.Memtable: Completed flushing /Users/vallette/projects/DATA/gdb/data/titan/edgestore/titan-edgestore-ib-26-Data.db (1518605 bytes) for commitlog position ReplayPosition(segmentId=1368546407809, position=7361911) 13/05/15 09:30:59 INFO service.GCInspector: GC for PS MarkSweep: 444 ms for 1 collections, 57334768 used; max is 523239424 13/05/15 09:31:49 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex') liveRatio is 52.624442347941155 (just-counted was 51.73053551329512). calculation took 551ms for 16399 columns 13/05/15 09:32:24 INFO service.GCInspector: GC for PS MarkSweep: 449 ms for 1 collections, 126951680 used; max is 525991936 13/05/15 09:33:46 INFO service.GCInspector: GC for PS MarkSweep: 445 ms for 1 collections, 74997208 used; max is 525139968 13/05/15 09:35:05 INFO service.GCInspector: GC for PS MarkSweep: 468 ms for 1 collections, 70988568 used; max is 527958016 13/05/15 09:35:12 INFO db.Memtable: CFS(Keyspace='titan', ColumnFamily='vertexindex_lock_') liveRatio is 26.503092956542968 (just-counted was 26.503092956542968). calculation took 532ms for 16384 columns 13/05/15 09:36:36 INFO service.GCInspector: GC for PS MarkSweep: 470 ms for 1 collections, 58195200 used; max is 522846208