Skip to content

Instantly share code, notes, and snippets.

@sbespalov
Created April 2, 2020 13:18
Show Gist options
  • Save sbespalov/a04c0ea3918a08bbd5644ba4aa5311aa to your computer and use it in GitHub Desktop.
Save sbespalov/a04c0ea3918a08bbd5644ba4aa5311aa to your computer and use it in GitHub Desktop.
org.janusgraph.diskstorage.locking.PermanentLockingException: Local lock contention
19:14:04.551 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.s.p.r.MavenGroupRepositoryProviderTest | Started MavenGroupRepositoryProviderTest in 30.468 seconds (JVM running for 32.602)
19:14:05.290 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:05.290 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:05.290 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:05.294 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:05.294 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:05.294 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:05.309 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:05.313 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:05.313 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:05.344 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:05.344 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:05.345 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:05.500 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (aig:`ArtifactIdGroup`) WHERE aig.storageId=$storageId and aig.repositoryId=$repositoryId and aig.name=$artifactId WITH aig OPTIONAL MATCH (aig)-[r0]->(artifact:Artifact)-[r1]->(genericCoordinates:GenericArtifactCoordinates)<-[r2]-(layoutCoordinates) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN aig, r0, artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-drrsbv-1, 2=com.artifacts.accepted:foo, repositoryId=mgrpt-releases-drrsbv-1, artifactId=com.artifacts.accepted:foo, storageId=storage0}
19:14:05.500 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (aig:`ArtifactIdGroup`) WHERE aig.storageId=$storageId and aig.repositoryId=$repositoryId and aig.name=$artifactId WITH aig OPTIONAL MATCH (aig)-[r0]->(artifact:Artifact)-[r1]->(genericCoordinates:GenericArtifactCoordinates)<-[r2]-(layoutCoordinates) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN aig, r0, artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-tganr-1, 2=com.artifacts.in.releases.one:foo, repositoryId=mgrpt-releases-tganr-1, artifactId=com.artifacts.in.releases.one:foo, storageId=storage0}
19:14:09.522 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('aig').hasLabel('ArtifactIdGroup').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-drrsbv-1')).has('name', eq('com.artifacts.accepted:foo')).as('aig').choose(__.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')), __.select('r1').where(neq('r2')))), __.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')), __.select('r1').where(neq('r2')))), __.constant(' cypher.null').as('r0').as('artifact').as('r1').as('genericCoordinates').as('r2').as('layoutCoordinates')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).as(' GENERATED7').select('aig').as('aig').select(' GENERATED7').select('r0').as('r0').select(' GENERATED7').select('artifact').as('artifact').select(' GENERATED7').select('r1').as('r1').select(' GENERATED7').select('genericCoordinates').as('genericCoordinates').select(' GENERATED7').select('r2').as('r2').select(' GENERATED7').select('layoutCoordinates').as('layoutCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED11').select('aig').as('aig').select(' GENERATED11').select('r0').as('r0').select(' GENERATED11').select('artifact').as('artifact').select(' GENERATED11').select('r1').as('r1').select(' GENERATED11').select('genericCoordinates').as('genericCoordinates').select(' GENERATED11').select('r2').as('r2').select(' GENERATED11').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED11').select('r4').as('r4').select(' GENERATED11').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r0').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:09.534 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('aig').hasLabel('ArtifactIdGroup').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-tganr-1')).has('name', eq('com.artifacts.in.releases.one:foo')).as('aig').choose(__.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')), __.select('r1').where(neq('r2')))), __.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')), __.select('r1').where(neq('r2')))), __.constant(' cypher.null').as('r0').as('artifact').as('r1').as('genericCoordinates').as('r2').as('layoutCoordinates')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).as(' GENERATED7').select('aig').as('aig').select(' GENERATED7').select('r0').as('r0').select(' GENERATED7').select('artifact').as('artifact').select(' GENERATED7').select('r1').as('r1').select(' GENERATED7').select('genericCoordinates').as('genericCoordinates').select(' GENERATED7').select('r2').as('r2').select(' GENERATED7').select('layoutCoordinates').as('layoutCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED11').select('aig').as('aig').select(' GENERATED11').select('r0').as('r0').select(' GENERATED11').select('artifact').as('artifact').select(' GENERATED11').select('r1').as('r1').select(' GENERATED11').select('genericCoordinates').as('genericCoordinates').select(' GENERATED11').select('r2').as('r2').select(' GENERATED11').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED11').select('r4').as('r4').select(' GENERATED11').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r0').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:10.042 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.042 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.042 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.044 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.044 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.044 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.046 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.046 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.046 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.048 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.048 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.048 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.048 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.049 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.049 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.049 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.049 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.049 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.050 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.050 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.050 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.051 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.051 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.051 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.052 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.052 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.052 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.184 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.184 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.196 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.205 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.205 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.205 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.205 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.205 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.205 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.206 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.206 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.206 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.206 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.207 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.207 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.207 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.207 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.207 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.207 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.208 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.208 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.208 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.208 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.208 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.209 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.209 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.209 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.210 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.210 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.210 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.210 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.210 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.210 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.211 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.211 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.211 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.212 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.212 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.212 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.212 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.212 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.212 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.213 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.213 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.213 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.216 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.217 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.218 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.219 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.220 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.221 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.221 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.221 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.221 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.221 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.221 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.221 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.222 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.222 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.222 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.222 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.222 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.223 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.224 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.224 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.225 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.225 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.225 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.226 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.226 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.226 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.227 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.227 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.227 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.228 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.228 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.228 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.229 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.230 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.230 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.230 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.230 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.230 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.230 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.259 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.259 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.260 02-04-2020 | DEBUG | nusGraphID(21)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(21)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:10.261 02-04-2020 | DEBUG | nusGraphID(21)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(21)-namespace(0), exec time 534.8 μs, exec+q time 1.397 ms
19:14:10.261 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(21)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:10.262 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.264 02-04-2020 | DEBUG | anusGraphID(8)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(8)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:10.265 02-04-2020 | DEBUG | anusGraphID(8)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(8)-namespace(0), exec time 419.4 μs, exec+q time 5.340 ms
19:14:10.266 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(8)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:10.267 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.269 02-04-2020 | DEBUG | nusGraphID(21)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(21)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:10.269 02-04-2020 | DEBUG | nusGraphID(21)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(21)-namespace(3), exec time 383.6 μs, exec+q time 6.429 ms
19:14:10.269 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(21)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:10.271 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.271 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.271 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.280 02-04-2020 | DEBUG | anusGraphID(8)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(8)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:10.280 02-04-2020 | DEBUG | anusGraphID(8)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(8)-namespace(3), exec time 451.6 μs, exec+q time 13.14 ms
19:14:10.280 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(8)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:10.281 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.283 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.283 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.290 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [ArtifactIdGroup]-[4264]-[storage0-mgrpt-releases-tganr-1-com.artifacts.in.releases.one:foo]
19:14:10.291 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [ArtifactIdGroup]-[4160]-[storage0-mgrpt-releases-drrsbv-1-com.artifacts.accepted:foo]
19:14:10.291 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.292 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.292 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.292 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.292 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.293 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.599 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.602 02-04-2020 | DEBUG | nusGraphID(14)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(14)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:10.602 02-04-2020 | DEBUG | nusGraphID(14)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(14)-namespace(0), exec time 480.3 μs, exec+q time 2.872 ms
19:14:10.603 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(14)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:10.603 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.608 02-04-2020 | DEBUG | nusGraphID(14)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(14)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:10.608 02-04-2020 | DEBUG | nusGraphID(14)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(14)-namespace(3), exec time 478.7 μs, exec+q time 5.064 ms
19:14:10.609 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(14)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:10.613 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [ArtifactTag]-[4208]-[last-version]
19:14:10.647 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [ArtifactTag]-[8304]-[last-version]
19:14:10.708 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [ArtifactIdGroup]-[4160]-[storage0-mgrpt-releases-drrsbv-1-com.artifacts.accepted:foo]
19:14:10.711 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [ArtifactIdGroup]-[4264]-[storage0-mgrpt-releases-tganr-1-com.artifacts.in.releases.one:foo]
19:14:10.719 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [Artifact]-[12400]-[storage0-mgrpt-releases-drrsbv-1-com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:10.719 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.719 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.719 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.725 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [MavenArtifactCoordinates]-[16496]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:10.725 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.725 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.725 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.728 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.729 02-04-2020 | DEBUG | nusGraphID(13)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(13)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:10.729 02-04-2020 | DEBUG | nusGraphID(13)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(13)-namespace(0), exec time 471.9 μs, exec+q time 1.000 ms
19:14:10.730 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(13)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:10.730 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.731 02-04-2020 | DEBUG | nusGraphID(13)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(13)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:10.731 02-04-2020 | DEBUG | nusGraphID(13)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(13)-namespace(3), exec time 357.5 μs, exec+q time 872.1 μs
19:14:10.731 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(13)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:10.734 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [GenericArtifactCoordinates]-[4200]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:10.736 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.736 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.736 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.738 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.738 02-04-2020 | DEBUG | nusGraphID(16)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(16)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:10.738 02-04-2020 | DEBUG | nusGraphID(16)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(16)-namespace(0), exec time 334.5 μs, exec+q time 745.8 μs
19:14:10.739 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(16)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:10.739 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.739 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.739 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.739 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.742 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.744 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.744 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.748 02-04-2020 | DEBUG | nusGraphID(16)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(16)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:10.748 02-04-2020 | DEBUG | nusGraphID(16)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(16)-namespace(3), exec time 464.1 μs, exec+q time 9.316 ms
19:14:10.749 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(16)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:10.752 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.752 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.752 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.758 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [Artifact]-[4224]-[storage0-mgrpt-releases-tganr-1-com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.pom]
19:14:10.763 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [MavenArtifactCoordinates]-[20592]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.pom]
19:14:10.765 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.770 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.770 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.770 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.772 02-04-2020 | DEBUG | nusGraphID(15)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(15)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:10.772 02-04-2020 | DEBUG | nusGraphID(15)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(15)-namespace(0), exec time 337.3 μs, exec+q time 7.019 ms
19:14:10.776 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(15)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:10.776 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.778 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.778 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.778 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.786 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.786 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.786 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.788 02-04-2020 | DEBUG | nusGraphID(15)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(15)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:10.788 02-04-2020 | DEBUG | nusGraphID(15)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(15)-namespace(3), exec time 454.0 μs, exec+q time 12.28 ms
19:14:10.789 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(15)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:10.792 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [GenericArtifactCoordinates]-[4216]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.pom]
19:14:10.792 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.793 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.793 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.797 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.797 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.797 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.800 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.800 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.800 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.803 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.811 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.811 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.811 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.813 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.813 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.814 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.816 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.816 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.816 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.816 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.816 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.818 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.818 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.818 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.821 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.821 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.822 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.824 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.825 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.825 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.826 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(0)-namespace(4) acquired block: [[51,101)/0:4]
19:14:10.826 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:10.826 02-04-2020 | DEBUG | anusGraphID(0)(4)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[101,151)/0:4] on partition(0)-namespace(4) (my rid is 7f00010117450-sbespalov1)
19:14:10.826 02-04-2020 | DEBUG | anusGraphID(0)(4)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(0)-namespace(4), exec time 291.3 μs, exec+q time 347.6 μs
19:14:10.829 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.829 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.829 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.832 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.832 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.832 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.835 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.835 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.835 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.896 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | org.janusgraph.graphdb.database.StandardJanusGraph | Saving transaction. Added 140, removed 0
19:14:10.898 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30-118-101-114-115-105-111-238, c=0x 0] ev=null
19:14:10.899 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30-118-101-114-115-105-111-238, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.899 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30-118-101-114-115-105-111-238, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.898Z newexp=2020-04-02T13:19:10.899Z
19:14:10.899 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30-118-101-114-115-105-111-238, c=0x 0]: null
19:14:10.900 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-115-101-228, c=0x 0] ev=null
19:14:10.900 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-115-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.900 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-115-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.900Z newexp=2020-04-02T13:19:10.900Z
19:14:10.901 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-115-101-228, c=0x 0]: null
19:14:10.901 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30- 99-114-101- 97-116-101-228, c=0x 0] ev=null
19:14:10.901 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30- 99-114-101- 97-116-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.902 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30- 99-114-101- 97-116-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.901Z newexp=2020-04-02T13:19:10.901Z
19:14:10.902 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30- 99-114-101- 97-116-101-228, c=0x 0]: null
19:14:10.902 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30-115-105-122-101- 73-110- 66-121-116-101-243, c=0x 0] ev=null
19:14:10.902 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30-115-105-122-101- 73-110- 66-121-116-101-243, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.902 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30-115-105-122-101- 73-110- 66-121-116-101-243, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.902Z newexp=2020-04-02T13:19:10.902Z
19:14:10.903 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30-115-105-122-101- 73-110- 66-121-116-101-243, c=0x 0]: null
19:14:10.903 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-101-120-116-101-110-115-105-111-238, c=0x 0] ev=null
19:14:10.903 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-101-120-116-101-110-115-105-111-238, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.903 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-101-120-116-101-110-115-105-111-238, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.903Z newexp=2020-04-02T13:19:10.903Z
19:14:10.904 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-101-120-116-101-110-115-105-111-238, c=0x 0]: null
19:14:10.904 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-112-100- 97-116-101-228, c=0x 0] ev=null
19:14:10.905 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-112-100- 97-116-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.906 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-112-100- 97-116-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.904Z newexp=2020-04-02T13:19:10.905Z
19:14:10.906 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-112-100- 97-116-101-228, c=0x 0]: null
19:14:10.906 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0] ev=null
19:14:10.907 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.907 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.907Z newexp=2020-04-02T13:19:10.907Z
19:14:10.908 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0]: null
19:14:10.909 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-103-114-111-117-112- 73-228, c=0x 0] ev=null
19:14:10.909 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | org.janusgraph.graphdb.database.StandardJanusGraph | Saving transaction. Added 140, removed 0
19:14:10.910 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-103-114-111-117-112- 73-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.910 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-103-114-111-117-112- 73-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.909Z newexp=2020-04-02T13:19:10.910Z
19:14:10.911 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-103-114-111-117-112- 73-228, c=0x 0]: null
19:14:10.911 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46- 97-114-116-105-102- 97- 99-116- 73-228, c=0x 0] ev=null
19:14:10.911 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.d.l.consistentkey.ExpectedValueCheckingStore | Attempting to acquireLock on KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0] ev=null
19:14:10.911 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | New local lock created: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46- 97-114-116-105-102- 97- 99-116- 73-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:10.911 02-04-2020 | TRACE | kJoinPool-1-worker-2 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local lock failed: KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@363b7aca (already owned by AuditRecord [txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949, expires=2020-04-02T13:19:10.907Z])
19:14:10.911 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Updated local lock expiration: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46- 97-114-116-105-102- 97- 99-116- 73-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 oldexp=2020-04-02T13:19:10.911Z newexp=2020-04-02T13:19:10.911Z
19:14:10.912 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingTransaction | Store expected value for KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46- 97-114-116-105-102- 97- 99-116- 73-228, c=0x 0]: null
19:14:10.912 02-04-2020 | TRACE | kJoinPool-1-worker-2 | o.janusgraph.diskstorage.locking.LocalLockMediator | Owner stacktrace:
org.janusgraph.diskstorage.locking.LocalLockMediator.lock(LocalLockMediator.java:104)
org.janusgraph.diskstorage.locking.AbstractLocker.lockLocally(AbstractLocker.java:401)
org.janusgraph.diskstorage.locking.AbstractLocker.writeLock(AbstractLocker.java:305)
org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingStore.acquireLock(ExpectedValueCheckingStore.java:103)
org.janusgraph.diskstorage.keycolumnvalue.KCVSProxy.acquireLock(KCVSProxy.java:51)
org.janusgraph.diskstorage.BackendTransaction.acquireIndexLock(BackendTransaction.java:255)
org.janusgraph.graphdb.database.StandardJanusGraph.prepareCommit(StandardJanusGraph.java:591)
org.janusgraph.graphdb.database.StandardJanusGraph.commit(StandardJanusGraph.java:712)
org.janusgraph.graphdb.transaction.StandardJanusGraphTx.commit(StandardJanusGraphTx.java:1425)
org.janusgraph.graphdb.tinkerpop.JanusGraphBlueprintsTransaction$1.doCommit(JanusGraphBlueprintsTransaction.java:185)
org.apache.tinkerpop.gremlin.structure.util.AbstractTransaction.commit(AbstractTransaction.java:104)
org.opencypher.gremlin.neo4j.ogm.transaction.GremlinTransaction.doCommit(GremlinTransaction.java:111)
org.opencypher.gremlin.neo4j.ogm.transaction.GremlinTransaction.commit(GremlinTransaction.java:82)
org.springframework.data.neo4j.transaction.Neo4jTransactionManager.doCommit(Neo4jTransactionManager.java:288)
org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:744)
org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:712)
org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:631)
org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:385)
org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:99)
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:747)
org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:689)
org.carlspring.strongbox.services.ArtifactManagementService$$EnhancerBySpringCGLIB$$15df52a2.store(<generated>)
org.carlspring.strongbox.testing.artifact.TestArtifactContext.store(TestArtifactContext.java:209)
org.carlspring.strongbox.util.ThrowingConsumer.lambda$0(ThrowingConsumer.java:19)
java.lang.Iterable.forEach(Iterable.java:75)
org.carlspring.strongbox.testing.artifact.TestArtifactContext.deployArtifact(TestArtifactContext.java:199)
org.carlspring.strongbox.testing.artifact.TestArtifactContext.generateArtifact(TestArtifactContext.java:166)
org.carlspring.strongbox.testing.artifact.TestArtifactContext.createArtifacts(TestArtifactContext.java:139)
org.carlspring.strongbox.testing.artifact.TestArtifactContext.<init>(TestArtifactContext.java:78)
sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
java.lang.reflect.Constructor.newInstance(Constructor.java:422)
org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:203)
org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:117)
org.springframework.beans.factory.support.ConstructorResolver.instantiate(ConstructorResolver.java:310)
org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:295)
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:1358)
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1204)
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:557)
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:517)
org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:323)
org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:321)
org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:202)
org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:879)
org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:878)
org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:550)
org.carlspring.strongbox.testing.storage.repository.TestRepositoryManagementApplicationContext.tryToStart(TestRepositoryManagementApplicationContext.java:163)
org.carlspring.strongbox.testing.artifact.ArtifactManagementTestExecutionListener.resolveParameter(ArtifactManagementTestExecutionListener.java:90)
org.junit.jupiter.engine.execution.ExecutableInvoker.resolveParameter(ExecutableInvoker.java:209)
org.junit.jupiter.engine.execution.ExecutableInvoker.resolveParameters(ExecutableInvoker.java:176)
org.junit.jupiter.engine.execution.ExecutableInvoker.resolveParameters(ExecutableInvoker.java:137)
org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:118)
org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.lambda$invokeTestMethod$7(TestMethodTestDescriptor.java:184)
org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.invokeTestMethod(TestMethodTestDescriptor.java:180)
org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:127)
org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:68)
org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:135)
org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:125)
org.junit.platform.engine.support.hierarchical.Node.around(Node.java:135)
org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:123)
org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:122)
org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:80)
org.junit.platform.engine.support.hierarchical.ForkJoinPoolHierarchicalTestExecutorService$ExclusiveTask.compute(ForkJoinPoolHierarchicalTestExecutorService.java:171)
java.util.concurrent.RecursiveAction.exec(RecursiveAction.java:189)
java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:289)
java.util.concurrent.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1056)
java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1692)
java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)
19:14:10.912 02-04-2020 | ERROR | kJoinPool-1-worker-2 | org.janusgraph.graphdb.database.StandardJanusGraph | Could not commit transaction [18] due to exception
org.janusgraph.diskstorage.locking.PermanentLockingException: Local lock contention
at org.janusgraph.diskstorage.locking.AbstractLocker.writeLock(AbstractLocker.java:327)
at org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingStore.acquireLock(ExpectedValueCheckingStore.java:103)
at org.janusgraph.diskstorage.keycolumnvalue.KCVSProxy.acquireLock(KCVSProxy.java:51)
at org.janusgraph.diskstorage.BackendTransaction.acquireIndexLock(BackendTransaction.java:255)
at org.janusgraph.graphdb.database.StandardJanusGraph.prepareCommit(StandardJanusGraph.java:591)
at org.janusgraph.graphdb.database.StandardJanusGraph.commit(StandardJanusGraph.java:712)
at org.janusgraph.graphdb.transaction.StandardJanusGraphTx.commit(StandardJanusGraphTx.java:1425)
at org.janusgraph.graphdb.tinkerpop.JanusGraphBlueprintsTransaction$1.doCommit(JanusGraphBlueprintsTransaction.java:185)
at org.apache.tinkerpop.gremlin.structure.util.AbstractTransaction.commit(AbstractTransaction.java:104)
at org.opencypher.gremlin.neo4j.ogm.transaction.GremlinTransaction.doCommit(GremlinTransaction.java:111)
at org.opencypher.gremlin.neo4j.ogm.transaction.GremlinTransaction.commit(GremlinTransaction.java:82)
at org.springframework.data.neo4j.transaction.Neo4jTransactionManager.doCommit(Neo4jTransactionManager.java:288)
at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:744)
at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:712)
at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:631)
at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:385)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:99)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:747)
at org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:689)
at org.carlspring.strongbox.services.ArtifactManagementService$$EnhancerBySpringCGLIB$$15df52a2.store(<generated>)
at org.carlspring.strongbox.testing.artifact.TestArtifactContext.store(TestArtifactContext.java:209)
at org.carlspring.strongbox.util.ThrowingConsumer.lambda$0(ThrowingConsumer.java:19)
at java.lang.Iterable.forEach(Iterable.java:75)
at org.carlspring.strongbox.testing.artifact.TestArtifactContext.deployArtifact(TestArtifactContext.java:199)
at org.carlspring.strongbox.testing.artifact.TestArtifactContext.generateArtifact(TestArtifactContext.java:166)
at org.carlspring.strongbox.testing.artifact.TestArtifactContext.createArtifacts(TestArtifactContext.java:139)
at org.carlspring.strongbox.testing.artifact.TestArtifactContext.<init>(TestArtifactContext.java:78)
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:203)
at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:117)
at org.springframework.beans.factory.support.ConstructorResolver.instantiate(ConstructorResolver.java:310)
at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:295)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:1358)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1204)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:557)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:517)
at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:323)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:321)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:202)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:879)
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:878)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:550)
at org.carlspring.strongbox.testing.storage.repository.TestRepositoryManagementApplicationContext.tryToStart(TestRepositoryManagementApplicationContext.java:163)
at org.carlspring.strongbox.testing.artifact.ArtifactManagementTestExecutionListener.resolveParameter(ArtifactManagementTestExecutionListener.java:90)
at org.junit.jupiter.engine.execution.ExecutableInvoker.resolveParameter(ExecutableInvoker.java:209)
at org.junit.jupiter.engine.execution.ExecutableInvoker.resolveParameters(ExecutableInvoker.java:176)
at org.junit.jupiter.engine.execution.ExecutableInvoker.resolveParameters(ExecutableInvoker.java:137)
at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:118)
at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.lambda$invokeTestMethod$7(TestMethodTestDescriptor.java:184)
at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.invokeTestMethod(TestMethodTestDescriptor.java:180)
at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:127)
at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:68)
at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:135)
at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:125)
at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:135)
at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:123)
at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:122)
at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:80)
at org.junit.platform.engine.support.hierarchical.ForkJoinPoolHierarchicalTestExecutorService$ExclusiveTask.compute(ForkJoinPoolHierarchicalTestExecutorService.java:171)
at java.util.concurrent.RecursiveAction.exec(RecursiveAction.java:189)
at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:289)
at java.util.concurrent.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1056)
at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1692)
at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)
19:14:10.918 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (aig:`ArtifactIdGroup`) WHERE aig.storageId=$storageId and aig.repositoryId=$repositoryId and aig.name=$artifactId WITH aig OPTIONAL MATCH (aig)-[r0]->(artifact:Artifact)-[r1]->(genericCoordinates:GenericArtifactCoordinates)<-[r2]-(layoutCoordinates) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN aig, r0, artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-drrswirasinp-1, 2=com.artifacts.accepted:foo, repositoryId=mgrpt-releases-drrswirasinp-1, artifactId=com.artifacts.accepted:foo, storageId=storage0}
19:14:10.921 02-04-2020 | WARN | kJoinPool-1-worker-2 | s.t.s.r.TestRepositoryManagementApplicationContext | Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'storage0/mgrpt-releases-drrsbv-1/com.artifacts.accepted:foo': Bean instantiation via constructor failed; nested exception is org.springframework.beans.BeanInstantiationException: Failed to instantiate [org.carlspring.strongbox.testing.artifact.TestArtifactContext]: Constructor threw exception; nested exception is java.lang.RuntimeException: org.springframework.dao.InvalidDataAccessApiUsageException: Could not commit transaction due to exception during persistence; nested exception is org.neo4j.ogm.exception.TransactionException: Could not commit transaction due to exception during persistence
19:14:10.960 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:10.960 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:10.960 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:10.979 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (genericCoordinates:GenericArtifactCoordinates)<-[r1]-(artifact:Artifact) WHERE genericCoordinates.uuid=$path and artifact.storageId=$storageId and artifact.repositoryId=$repositoryId WITH artifact, r1, genericCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH artifact, r1, genericCoordinates, r4, tag MATCH (genericCoordinates)<-[r2]-(layoutCoordinates) WITH artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-drrsbv-1, path=com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom, 2=com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom, repositoryId=mgrpt-releases-drrsbv-1, storageId=storage0}
19:14:11.007 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-112-100- 97-116-101-228, c=0x 0]
19:14:11.011 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-103-114-111-117-112- 73-228, c=0x 0]
19:14:11.011 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-115-101-228, c=0x 0]
19:14:11.011 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30- 99-114-101- 97-116-101-228, c=0x 0]
19:14:11.012 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30-115-105-122-101- 73-110- 66-121-116-101-243, c=0x 0]
19:14:11.012 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46- 97-114-116-105-102- 97- 99-116- 73-228, c=0x 0]
19:14:11.012 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0]
19:14:11.012 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30-118-101-114-115-105-111-238, c=0x 0]
19:14:11.012 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.locking.consistentkey.ConsistentKeyLocker | Checked lock KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-101-120-116-101-110-115-105-111-238, c=0x 0]
19:14:11.012 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingStoreManager | Transaction org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingTransaction@19bc736f holds one or more locks: writing using consistent transaction org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949 due to held locks
19:14:11.013 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-112-100- 97-116-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.013 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-103-114-111-117-112- 73-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.013 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30-108- 97-115-116- 85-115-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.013 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30- 99-114-101- 97-116-101-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.013 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30-115-105-122-101- 73-110- 66-121-116-101-243, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.014 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46- 97-114-116-105-102- 97- 99-116- 73-228, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.014 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30-100-111-119-110-108-111- 97-100- 67-111-117-110-244, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.014 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30-118-101-114-115-105-111-238, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.014 02-04-2020 | TRACE | kJoinPool-1-worker-3 | o.janusgraph.diskstorage.locking.LocalLockMediator | Local unlock succeeded: KeyColumn [k=0x 16-165-160-114-116- 30- 99-111-111-114-100-105-110- 97-116-101-115- 46-101-120-116-101-110-115-105-111-238, c=0x 0] namespace=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager@591fd17a txn=org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4c380949
19:14:11.027 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingStoreManager | Transaction org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingTransaction@79873f82 holds no locks: writing mutations using store transaction org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@75f903ba
19:14:11.127 02-04-2020 | DEBUG | Thread-18 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.127 02-04-2020 | DEBUG | Thread-18 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.128 02-04-2020 | DEBUG | Thread-18 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.128 02-04-2020 | WARN | Thread-18 | o.c.s.services.support.ArtifactStoredEventListener | No [Artifact] for [/home/sbespalov/kingston/projects/strongbox/strongbox/strongbox-storage/strongbox-storage-layout-providers/strongbox-storage-maven-layout/strongbox-storage-maven-layout-provider/target/strongbox-vault/storages/storage0/mgrpt-releases-tganr-1/com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.pom].
19:14:11.132 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.132 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.132 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.160 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (aig:`ArtifactIdGroup`) WHERE aig.storageId=$storageId and aig.repositoryId=$repositoryId and aig.name=$artifactId WITH aig OPTIONAL MATCH (aig)-[r0]->(artifact:Artifact)-[r1]->(genericCoordinates:GenericArtifactCoordinates)<-[r2]-(layoutCoordinates) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN aig, r0, artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-tganr-1, 2=com.artifacts.in.releases.one:foo, repositoryId=mgrpt-releases-tganr-1, artifactId=com.artifacts.in.releases.one:foo, storageId=storage0}
19:14:11.222 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('aig').hasLabel('ArtifactIdGroup').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-drrswirasinp-1')).has('name', eq('com.artifacts.accepted:foo')).as('aig').choose(__.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r1').where(neq('r2')), __.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')))), __.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r1').where(neq('r2')), __.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')))), __.constant(' cypher.null').as('r0').as('artifact').as('r1').as('genericCoordinates').as('r2').as('layoutCoordinates')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).as(' GENERATED7').select('aig').as('aig').select(' GENERATED7').select('r0').as('r0').select(' GENERATED7').select('artifact').as('artifact').select(' GENERATED7').select('r1').as('r1').select(' GENERATED7').select('genericCoordinates').as('genericCoordinates').select(' GENERATED7').select('r2').as('r2').select(' GENERATED7').select('layoutCoordinates').as('layoutCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED11').select('aig').as('aig').select(' GENERATED11').select('r0').as('r0').select(' GENERATED11').select('artifact').as('artifact').select(' GENERATED11').select('r1').as('r1').select(' GENERATED11').select('genericCoordinates').as('genericCoordinates').select(' GENERATED11').select('r2').as('r2').select(' GENERATED11').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED11').select('r4').as('r4').select(' GENERATED11').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r0').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:11.229 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').has('uuid', eq('com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom')).inE().as('r1').outV().as('artifact').hasLabel('Artifact').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-drrsbv-1')).select('artifact', 'r1', 'genericCoordinates').project('artifact', 'r1', 'genericCoordinates').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).as(' GENERATED2').select('artifact').as('artifact').select(' GENERATED2').select('r1').as('r1').select(' GENERATED2').select('genericCoordinates').as('genericCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED6').select('artifact').as('artifact').select(' GENERATED6').select('r1').as('r1').select(' GENERATED6').select('genericCoordinates').as('genericCoordinates').select(' GENERATED6').select('r4').as('r4').select(' GENERATED6').select('tag').as('tag').select('genericCoordinates').is(neq(' cypher.null')).inE().as('r2').outV().as('layoutCoordinates').select('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED9').select('artifact').as('artifact').select(' GENERATED9').select('r1').as('r1').select(' GENERATED9').select('genericCoordinates').as('genericCoordinates').select(' GENERATED9').select('r2').as('r2').select(' GENERATED9').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED9').select('r4').as('r4').select(' GENERATED9').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:11.312 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('aig').hasLabel('ArtifactIdGroup').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-tganr-1')).has('name', eq('com.artifacts.in.releases.one:foo')).as('aig').choose(__.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r1').where(neq('r2')), __.select('r0').where(neq('r2')))), __.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r1').where(neq('r2')), __.select('r0').where(neq('r2')))), __.constant(' cypher.null').as('r0').as('artifact').as('r1').as('genericCoordinates').as('r2').as('layoutCoordinates')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).as(' GENERATED7').select('aig').as('aig').select(' GENERATED7').select('r0').as('r0').select(' GENERATED7').select('artifact').as('artifact').select(' GENERATED7').select('r1').as('r1').select(' GENERATED7').select('genericCoordinates').as('genericCoordinates').select(' GENERATED7').select('r2').as('r2').select(' GENERATED7').select('layoutCoordinates').as('layoutCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED11').select('aig').as('aig').select(' GENERATED11').select('r0').as('r0').select(' GENERATED11').select('artifact').as('artifact').select(' GENERATED11').select('r1').as('r1').select(' GENERATED11').select('genericCoordinates').as('genericCoordinates').select(' GENERATED11').select('r2').as('r2').select(' GENERATED11').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED11').select('r4').as('r4').select(' GENERATED11').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r0').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:11.430 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.433 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.441 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.442 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.442 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.442 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.442 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.443 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.443 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.443 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.443 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.443 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.444 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.444 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.444 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.446 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [ArtifactIdGroup]-[8256]-[storage0-mgrpt-releases-drrswirasinp-1-com.artifacts.accepted:foo]
19:14:11.564 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [ArtifactIdGroup]-[8256]-[storage0-mgrpt-releases-drrswirasinp-1-com.artifacts.accepted:foo]
19:14:11.595 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.597 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.597 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.597 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.597 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.597 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.598 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.598 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.598 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.598 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.598 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.598 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.599 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.599 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.599 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.599 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.599 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.599 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.600 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.600 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.600 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.602 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [Artifact]-[8320]-[storage0-mgrpt-releases-drrswirasinp-1-com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:11.609 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.611 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.611 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [MavenArtifactCoordinates]-[8296]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:11.620 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.620 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.620 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.620 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.620 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.620 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.621 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.621 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.621 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.621 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.621 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.621 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.621 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.624 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.624 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.624 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.625 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.625 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.625 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.625 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.626 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.626 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.624 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [GenericArtifactCoordinates]-[12416]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:11.626 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.626 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.627 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.626 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.627 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.627 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.627 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.627 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.627 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.628 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.628 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.628 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.630 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.630 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.631 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.631 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.631 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.632 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.632 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.632 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.634 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.634 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.634 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.635 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.635 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.635 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.636 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.636 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.636 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.636 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.636 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.637 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.637 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.637 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.637 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.637 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.637 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.637 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.638 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.638 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.638 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.638 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.638 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.638 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.638 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.643 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.643 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.643 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.645 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.646 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.646 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.647 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.647 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.647 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.660 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.660 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.660 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.661 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.661 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.661 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.674 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.674 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.674 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.675 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.675 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.675 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.676 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.676 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.676 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.677 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.677 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.677 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.681 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.681 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.684 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.685 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.685 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.685 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.789 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | org.janusgraph.graphdb.database.StandardJanusGraph | Saving transaction. Added 37, removed 0
19:14:11.799 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [ArtifactIdGroup]-[4264]-[storage0-mgrpt-releases-tganr-1-com.artifacts.in.releases.one:foo]
19:14:11.800 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.d.l.c.ExpectedValueCheckingStoreManager | Transaction org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingTransaction@10a676bb holds no locks: writing mutations using store transaction org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@46465a0d
19:14:11.828 02-04-2020 | INFO | kJoinPool-1-worker-0 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (aig:`ArtifactIdGroup`) WHERE aig.storageId=$storageId and aig.repositoryId=$repositoryId and aig.name=$artifactId WITH aig OPTIONAL MATCH (aig)-[r0]->(artifact:Artifact)-[r1]->(genericCoordinates:GenericArtifactCoordinates)<-[r2]-(layoutCoordinates) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN aig, r0, artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-drrswfagutss-1, 2=com.artifacts.accepted:foo, repositoryId=mgrpt-releases-drrswfagutss-1, artifactId=com.artifacts.accepted:foo, storageId=storage0}
19:14:11.838 02-04-2020 | DEBUG | Thread-19 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.838 02-04-2020 | DEBUG | Thread-19 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.838 02-04-2020 | DEBUG | Thread-19 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.838 02-04-2020 | WARN | Thread-19 | o.c.s.services.support.ArtifactStoredEventListener | No [Artifact] for [/home/sbespalov/kingston/projects/strongbox/strongbox/strongbox-storage/strongbox-storage-layout-providers/strongbox-storage-maven-layout/strongbox-storage-maven-layout-provider/target/strongbox-vault/storages/storage0/mgrpt-releases-drrswirasinp-1/com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom].
19:14:11.847 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.847 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.847 02-04-2020 | DEBUG | kJoinPool-1-worker-1 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.866 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:11.870 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:11.877 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:11.877 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:11.880 02-04-2020 | DEBUG | anusGraphID(4)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(4)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:11.880 02-04-2020 | DEBUG | anusGraphID(4)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(4)-namespace(0), exec time 418.6 μs, exec+q time 14.25 ms
19:14:11.881 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(4)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:11.881 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:11.894 02-04-2020 | DEBUG | anusGraphID(4)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(4)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:11.894 02-04-2020 | DEBUG | anusGraphID(4)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(4)-namespace(3), exec time 413.6 μs, exec+q time 13.39 ms
19:14:11.895 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(4)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:11.897 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [Artifact]-[4128]-[storage0-mgrpt-releases-tganr-1-com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar]
19:14:11.900 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [MavenArtifactCoordinates]-[8312]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar]
19:14:11.906 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [GenericArtifactCoordinates]-[8360]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar]
19:14:11.927 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [Artifact]-[4224]-[storage0-mgrpt-releases-tganr-1-com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.pom]
19:14:11.929 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [MavenArtifactCoordinates]-[20592]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.pom]
19:14:11.940 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [GenericArtifactCoordinates]-[4216]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.pom]
19:14:12.011 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | org.janusgraph.graphdb.database.StandardJanusGraph | Saving transaction. Added 50, removed 11
19:14:12.020 02-04-2020 | INFO | kJoinPool-1-worker-0 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('aig').hasLabel('ArtifactIdGroup').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-drrswfagutss-1')).has('name', eq('com.artifacts.accepted:foo')).as('aig').choose(__.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r1').where(neq('r2')), __.select('r0').where(neq('r2')), __.select('r0').where(neq('r1')))), __.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r1').where(neq('r2')), __.select('r0').where(neq('r2')), __.select('r0').where(neq('r1')))), __.constant(' cypher.null').as('r0').as('artifact').as('r1').as('genericCoordinates').as('r2').as('layoutCoordinates')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).as(' GENERATED7').select('aig').as('aig').select(' GENERATED7').select('r0').as('r0').select(' GENERATED7').select('artifact').as('artifact').select(' GENERATED7').select('r1').as('r1').select(' GENERATED7').select('genericCoordinates').as('genericCoordinates').select(' GENERATED7').select('r2').as('r2').select(' GENERATED7').select('layoutCoordinates').as('layoutCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED11').select('aig').as('aig').select(' GENERATED11').select('r0').as('r0').select(' GENERATED11').select('artifact').as('artifact').select(' GENERATED11').select('r1').as('r1').select(' GENERATED11').select('genericCoordinates').as('genericCoordinates').select(' GENERATED11').select('r2').as('r2').select(' GENERATED11').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED11').select('r4').as('r4').select(' GENERATED11').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r0').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:12.073 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.d.l.c.ExpectedValueCheckingStoreManager | Transaction org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingTransaction@5c8160da holds no locks: writing mutations using store transaction org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@2d3bc824
19:14:12.088 02-04-2020 | DEBUG | Thread-20 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.088 02-04-2020 | DEBUG | Thread-20 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.088 02-04-2020 | DEBUG | Thread-20 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.088 02-04-2020 | WARN | Thread-20 | o.c.s.services.support.ArtifactStoredEventListener | No [Artifact] for [/home/sbespalov/kingston/projects/strongbox/strongbox/strongbox-storage/strongbox-storage-layout-providers/strongbox-storage-maven-layout/strongbox-storage-maven-layout-provider/target/strongbox-vault/storages/storage0/mgrpt-releases-tganr-1/com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar].
19:14:12.093 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.093 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.093 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.127 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.127 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.127 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.160 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.160 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.160 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.180 02-04-2020 | INFO | kJoinPool-1-worker-0 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [ArtifactIdGroup]-[12408]-[storage0-mgrpt-releases-drrswfagutss-1-com.artifacts.accepted:foo]
19:14:12.185 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.186 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.186 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.221 02-04-2020 | INFO | kJoinPool-1-worker-0 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [ArtifactIdGroup]-[12408]-[storage0-mgrpt-releases-drrswfagutss-1-com.artifacts.accepted:foo]
19:14:12.228 02-04-2020 | INFO | kJoinPool-1-worker-0 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [Artifact]-[16504]-[storage0-mgrpt-releases-drrswfagutss-1-com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:12.230 02-04-2020 | INFO | kJoinPool-1-worker-0 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [MavenArtifactCoordinates]-[8296]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:12.231 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.231 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.231 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.232 02-04-2020 | INFO | kJoinPool-1-worker-0 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [GenericArtifactCoordinates]-[12416]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:12.234 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (genericCoordinates:GenericArtifactCoordinates)<-[r1]-(artifact:Artifact) WHERE genericCoordinates.uuid=$path and artifact.storageId=$storageId and artifact.repositoryId=$repositoryId WITH artifact, r1, genericCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH artifact, r1, genericCoordinates, r4, tag MATCH (genericCoordinates)<-[r2]-(layoutCoordinates) WITH artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-tganr-1, path=com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar, 2=com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar, repositoryId=mgrpt-releases-tganr-1, storageId=storage0}
19:14:12.268 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | org.janusgraph.graphdb.database.StandardJanusGraph | Saving transaction. Added 28, removed 0
19:14:12.273 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | o.j.d.l.c.ExpectedValueCheckingStoreManager | Transaction org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingTransaction@6aca267b holds no locks: writing mutations using store transaction org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@ba77f2e
19:14:12.282 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (aig:`ArtifactIdGroup`) WHERE aig.storageId=$storageId and aig.repositoryId=$repositoryId and aig.name=$artifactId WITH aig OPTIONAL MATCH (aig)-[r0]->(artifact:Artifact)-[r1]->(genericCoordinates:GenericArtifactCoordinates)<-[r2]-(layoutCoordinates) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN aig, r0, artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-drrswfagutsrn-1, 2=com.artifacts.accepted:foo, repositoryId=mgrpt-releases-drrswfagutsrn-1, artifactId=com.artifacts.accepted:foo, storageId=storage0}
19:14:12.315 02-04-2020 | DEBUG | Thread-21 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.315 02-04-2020 | DEBUG | Thread-21 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.315 02-04-2020 | DEBUG | Thread-21 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.315 02-04-2020 | WARN | Thread-21 | o.c.s.services.support.ArtifactStoredEventListener | No [Artifact] for [/home/sbespalov/kingston/projects/strongbox/strongbox/strongbox-storage/strongbox-storage-layout-providers/strongbox-storage-maven-layout/strongbox-storage-maven-layout-provider/target/strongbox-vault/storages/storage0/mgrpt-releases-drrswfagutss-1/com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom].
19:14:12.317 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.317 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.317 02-04-2020 | DEBUG | kJoinPool-1-worker-0 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.337 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').has('uuid', eq('com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar')).inE().as('r1').outV().as('artifact').hasLabel('Artifact').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-tganr-1')).select('artifact', 'r1', 'genericCoordinates').project('artifact', 'r1', 'genericCoordinates').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).as(' GENERATED2').select('artifact').as('artifact').select(' GENERATED2').select('r1').as('r1').select(' GENERATED2').select('genericCoordinates').as('genericCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED6').select('artifact').as('artifact').select(' GENERATED6').select('r1').as('r1').select(' GENERATED6').select('genericCoordinates').as('genericCoordinates').select(' GENERATED6').select('r4').as('r4').select(' GENERATED6').select('tag').as('tag').select('genericCoordinates').is(neq(' cypher.null')).inE().as('r2').outV().as('layoutCoordinates').select('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED9').select('artifact').as('artifact').select(' GENERATED9').select('r1').as('r1').select(' GENERATED9').select('genericCoordinates').as('genericCoordinates').select(' GENERATED9').select('r2').as('r2').select(' GENERATED9').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED9').select('r4').as('r4').select(' GENERATED9').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:12.436 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.436 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.437 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.437 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (genericCoordinates:GenericArtifactCoordinates)<-[r1]-(artifact:Artifact) WHERE genericCoordinates.uuid=$path and artifact.storageId=$storageId and artifact.repositoryId=$repositoryId WITH artifact, r1, genericCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH artifact, r1, genericCoordinates, r4, tag MATCH (genericCoordinates)<-[r2]-(layoutCoordinates) WITH artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-tganr-1, path=com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar, 2=com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar, repositoryId=mgrpt-releases-tganr-1, storageId=storage0}
19:14:12.462 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('aig').hasLabel('ArtifactIdGroup').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-drrswfagutsrn-1')).has('name', eq('com.artifacts.accepted:foo')).as('aig').choose(__.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')), __.select('r1').where(neq('r2')))), __.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r0').where(neq('r2')), __.select('r1').where(neq('r2')))), __.constant(' cypher.null').as('r0').as('artifact').as('r1').as('genericCoordinates').as('r2').as('layoutCoordinates')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).as(' GENERATED7').select('aig').as('aig').select(' GENERATED7').select('r0').as('r0').select(' GENERATED7').select('artifact').as('artifact').select(' GENERATED7').select('r1').as('r1').select(' GENERATED7').select('genericCoordinates').as('genericCoordinates').select(' GENERATED7').select('r2').as('r2').select(' GENERATED7').select('layoutCoordinates').as('layoutCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED11').select('aig').as('aig').select(' GENERATED11').select('r0').as('r0').select(' GENERATED11').select('artifact').as('artifact').select(' GENERATED11').select('r1').as('r1').select(' GENERATED11').select('genericCoordinates').as('genericCoordinates').select(' GENERATED11').select('r2').as('r2').select(' GENERATED11').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED11').select('r4').as('r4').select(' GENERATED11').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r0').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:12.500 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').has('uuid', eq('com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar')).inE().as('r1').outV().as('artifact').hasLabel('Artifact').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-tganr-1')).select('artifact', 'r1', 'genericCoordinates').project('artifact', 'r1', 'genericCoordinates').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).as(' GENERATED2').select('artifact').as('artifact').select(' GENERATED2').select('r1').as('r1').select(' GENERATED2').select('genericCoordinates').as('genericCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED6').select('artifact').as('artifact').select(' GENERATED6').select('r1').as('r1').select(' GENERATED6').select('genericCoordinates').as('genericCoordinates').select(' GENERATED6').select('r4').as('r4').select(' GENERATED6').select('tag').as('tag').select('genericCoordinates').is(neq(' cypher.null')).inE().as('r2').outV().as('layoutCoordinates').select('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED9').select('artifact').as('artifact').select(' GENERATED9').select('r1').as('r1').select(' GENERATED9').select('genericCoordinates').as('genericCoordinates').select(' GENERATED9').select('r2').as('r2').select(' GENERATED9').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED9').select('r4').as('r4').select(' GENERATED9').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:12.651 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:12.663 02-04-2020 | DEBUG | nusGraphID(31)(0)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,10001)/0:4] on partition(31)-namespace(0) (my rid is 7f00010117450-sbespalov1)
19:14:12.668 02-04-2020 | DEBUG | nusGraphID(31)(0)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(31)-namespace(0), exec time 5.485 ms, exec+q time 16.67 ms
19:14:12.669 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(31)-namespace(0) acquired block: [[1,10001)/0:4]
19:14:12.669 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | Starting id block renewal thread upon 0
19:14:12.692 02-04-2020 | DEBUG | nusGraphID(31)(3)[0] | o.j.d.idmanagement.ConsistentKeyIDAuthority | Acquired ID block [[1,80001)/0:4] on partition(31)-namespace(3) (my rid is 7f00010117450-sbespalov1)
19:14:12.692 02-04-2020 | DEBUG | nusGraphID(31)(3)[0] | o.j.graphdb.database.idassigner.StandardIDPool | Retrieved ID block from authority on partition(31)-namespace(3), exec time 475.3 μs, exec+q time 23.49 ms
19:14:12.693 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.database.idassigner.StandardIDPool | ID partition(31)-namespace(3) acquired block: [[1,80001)/0:4]
19:14:12.695 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [ArtifactIdGroup]-[4344]-[storage0-mgrpt-releases-drrswfagutsrn-1-com.artifacts.accepted:foo]
19:14:12.788 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [ArtifactIdGroup]-[4344]-[storage0-mgrpt-releases-drrswfagutsrn-1-com.artifacts.accepted:foo]
19:14:12.802 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [Artifact]-[24688]-[storage0-mgrpt-releases-drrswfagutsrn-1-com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:12.806 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [MavenArtifactCoordinates]-[8296]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:12.811 02-04-2020 | INFO | kJoinPool-1-worker-2 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [GenericArtifactCoordinates]-[12416]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom]
19:14:12.880 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | org.janusgraph.graphdb.database.StandardJanusGraph | Saving transaction. Added 28, removed 0
19:14:12.891 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.d.l.c.ExpectedValueCheckingStoreManager | Transaction org.janusgraph.diskstorage.locking.consistentkey.ExpectedValueCheckingTransaction@5ac9ca4a holds no locks: writing mutations using store transaction org.janusgraph.diskstorage.inmemory.InMemoryStoreManager$InMemoryTransaction@4f1869af
19:14:12.922 02-04-2020 | DEBUG | Thread-22 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.922 02-04-2020 | DEBUG | Thread-22 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.922 02-04-2020 | DEBUG | Thread-22 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.922 02-04-2020 | WARN | Thread-22 | o.c.s.services.support.ArtifactStoredEventListener | No [Artifact] for [/home/sbespalov/kingston/projects/strongbox/strongbox/strongbox-storage/strongbox-storage-layout-providers/strongbox-storage-maven-layout/strongbox-storage-maven-layout-provider/target/strongbox-vault/storages/storage0/mgrpt-releases-drrswfagutsrn-1/com/artifacts/accepted/foo/1.2.6/foo-1.2.6.pom].
19:14:12.924 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.924 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.924 02-04-2020 | DEBUG | kJoinPool-1-worker-2 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.935 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (aig:`ArtifactIdGroup`) WHERE aig.storageId=$storageId and aig.repositoryId=$repositoryId and aig.name=$artifactId WITH aig OPTIONAL MATCH (aig)-[r0]->(artifact:Artifact)-[r1]->(genericCoordinates:GenericArtifactCoordinates)<-[r2]-(layoutCoordinates) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH aig, r0, artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN aig, r0, artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-drrswirasinp-1, 2=com.artifacts.accepted:foo, repositoryId=mgrpt-releases-drrswirasinp-1, artifactId=com.artifacts.accepted:foo, storageId=storage0}
19:14:12.950 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:12.950 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:12.950 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:12.950 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Cypher: MATCH (genericCoordinates:GenericArtifactCoordinates)<-[r1]-(artifact:Artifact) WHERE genericCoordinates.uuid=$path and artifact.storageId=$storageId and artifact.repositoryId=$repositoryId WITH artifact, r1, genericCoordinates OPTIONAL MATCH (artifact)-[r4]->(tag:ArtifactTag) WITH artifact, r1, genericCoordinates, r4, tag MATCH (genericCoordinates)<-[r2]-(layoutCoordinates) WITH artifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag OPTIONAL MATCH (artifact)<-[r3]-(remoteArtifact) RETURN artifact, r3, remoteArtifact, r1, genericCoordinates, r2, layoutCoordinates, r4, tag with params {0=storage0, 1=mgrpt-releases-tganr-1, path=com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar, 2=com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar, repositoryId=mgrpt-releases-tganr-1, storageId=storage0}
19:14:13.080 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('aig').hasLabel('ArtifactIdGroup').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-drrswirasinp-1')).has('name', eq('com.artifacts.accepted:foo')).as('aig').choose(__.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r1').where(neq('r2')), __.select('r0').where(neq('r2')))), __.select('aig').is(neq(' cypher.null')).outE().as('r0').inV().as('artifact').hasLabel('Artifact').outE().as('r1').inV().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').inE().as('r2').outV().as('layoutCoordinates').where(__.and(__.select('r0').where(neq('r1')), __.select('r1').where(neq('r2')), __.select('r0').where(neq('r2')))), __.constant(' cypher.null').as('r0').as('artifact').as('r1').as('genericCoordinates').as('r2').as('layoutCoordinates')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).as(' GENERATED7').select('aig').as('aig').select(' GENERATED7').select('r0').as('r0').select(' GENERATED7').select('artifact').as('artifact').select(' GENERATED7').select('r1').as('r1').select(' GENERATED7').select('genericCoordinates').as('genericCoordinates').select(' GENERATED7').select('r2').as('r2').select(' GENERATED7').select('layoutCoordinates').as('layoutCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig')).by(__.select('r0')).by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED11').select('aig').as('aig').select(' GENERATED11').select('r0').as('r0').select(' GENERATED11').select('artifact').as('artifact').select(' GENERATED11').select('r1').as('r1').select(' GENERATED11').select('genericCoordinates').as('genericCoordinates').select(' GENERATED11').select('r2').as('r2').select(' GENERATED11').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED11').select('r4').as('r4').select(' GENERATED11').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('aig', 'r0', 'artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('aig').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r0').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:13.120 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.o.gremlin.neo4j.ogm.request.GremlinRequest | Gremlin: g.V().as('genericCoordinates').hasLabel('GenericArtifactCoordinates').has('uuid', eq('com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar')).inE().as('r1').outV().as('artifact').hasLabel('Artifact').has('storageId', eq('storage0')).has('repositoryId', eq('mgrpt-releases-tganr-1')).select('artifact', 'r1', 'genericCoordinates').project('artifact', 'r1', 'genericCoordinates').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).as(' GENERATED2').select('artifact').as('artifact').select(' GENERATED2').select('r1').as('r1').select(' GENERATED2').select('genericCoordinates').as('genericCoordinates').choose(__.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.select('artifact').is(neq(' cypher.null')).outE().as('r4').inV().as('tag').hasLabel('ArtifactTag'), __.constant(' cypher.null').as('r4').as('tag')).select('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED6').select('artifact').as('artifact').select(' GENERATED6').select('r1').as('r1').select(' GENERATED6').select('genericCoordinates').as('genericCoordinates').select(' GENERATED6').select('r4').as('r4').select(' GENERATED6').select('tag').as('tag').select('genericCoordinates').is(neq(' cypher.null')).inE().as('r2').outV().as('layoutCoordinates').select('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact')).by(__.select('r1')).by(__.select('genericCoordinates')).by(__.select('r2')).by(__.select('layoutCoordinates')).by(__.select('r4')).by(__.select('tag')).as(' GENERATED9').select('artifact').as('artifact').select(' GENERATED9').select('r1').as('r1').select(' GENERATED9').select('genericCoordinates').as('genericCoordinates').select(' GENERATED9').select('r2').as('r2').select(' GENERATED9').select('layoutCoordinates').as('layoutCoordinates').select(' GENERATED9').select('r4').as('r4').select(' GENERATED9').select('tag').as('tag').choose(__.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.select('artifact').is(neq(' cypher.null')).inE().as('r3').outV().as('remoteArtifact'), __.constant(' cypher.null').as('r3').as('remoteArtifact')).select('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').project('artifact', 'r3', 'remoteArtifact', 'r1', 'genericCoordinates', 'r2', 'layoutCoordinates', 'r4', 'tag').by(__.select('artifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r3').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('remoteArtifact').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r1').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('genericCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r2').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('layoutCoordinates').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens'))).by(__.select('r4').choose(neq(' cypher.null'), __.project(' cypher.element', ' cypher.inv', ' cypher.outv').by(__.valueMap().with('~tinkerpop.valueMap.tokens')).by(__.inV().id()).by(__.outV().id()))).by(__.select('tag').choose(neq(' cypher.null'), __.valueMap().with('~tinkerpop.valueMap.tokens')))
19:14:13.234 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.graphdb.transaction.StandardJanusGraphTx | Guava vertex cache size: requested=20000 effective=20000 (min=100)
19:14:13.234 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created dirty vertex map with initial size 32
19:14:13.234 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | o.j.g.transaction.vertexcache.GuavaVertexCache | Created vertex cache with max size 20000
19:14:13.317 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Fetched [ArtifactIdGroup]-[8256]-[storage0-mgrpt-releases-drrswirasinp-1-com.artifacts.accepted:foo]
19:14:13.327 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Delete [Artifact]-[4128]-[storage0-mgrpt-releases-tganr-1-com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar]
19:14:13.329 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [Artifact]-[16512]-[storage0-mgrpt-releases-drrswirasinp-1-com/artifacts/accepted/foo/1.2.6/foo-1.2.6.jar]
19:14:13.332 02-04-2020 | INFO | kJoinPool-1-worker-1 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Created [MavenArtifactCoordinates]-[28784]-[com/artifacts/accepted/foo/1.2.6/foo-1.2.6.jar]
19:14:13.333 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Delete [GenericArtifactCoordinates]-[8360]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar]
19:14:13.335 02-04-2020 | INFO | kJoinPool-1-worker-3 | o.c.strongbox.gremlin.dsl.EntityTraversalDsl | Delete [MavenArtifactCoordinates]-[8312]-[com/artifacts/in/releases/one/foo/1.2.3/foo-1.2.3.jar]
19:14:13.335 02-04-2020 | DEBUG | kJoinPool-1-worker-3 | org.janusgraph.graphdb.database.StandardJanusGraph | Saving transaction. Added 0, removed 30
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment