See
<https://builds.apache.org/job/Atlas-0.8-AllTests/31/display/redirect?page=changes>
Changes:
[madhan] ATLAS-2878: avoid retrieval of entiyWithExtInfo when extInfo is not
------------------------------------------
[...truncated 37.40 MB...]
2018-09-19 01:00:55,594 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], hive_column.comment) ==> null
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,594 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo position (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,594 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=position, dataType={name=int, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5225472] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,594 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], hive_column.position) ==> 1
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,595 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], hive_column.position) ==> 1
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,595 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo table (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,595 DEBUG - [NotificationHookConsumer thread-0:] ~ Finding
edge for Titan0Vertex [id=5225472] -> label __hive_column.table
(GraphToTypedInstanceMapper:196)
2018-09-19 01:00:55,595 DEBUG - [NotificationHookConsumer thread-0:] ~ Finding
edges for vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6] with label __hive_column.table
(GraphHelper:289)
2018-09-19 01:00:55,596 DEBUG - [NotificationHookConsumer thread-0:] ~ Found
edge[id=4rd6o-34000-gutx-33zls label=__hive_column.table from vertex[id=5225472
type=hive_column guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6] -> to
vertex[id=5224960 type=hive_table guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0]]
(GraphHelper:348)
2018-09-19 01:00:55,596 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], __guid) ==>
40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0 (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,596 DEBUG - [NotificationHookConsumer thread-0:] ~ Found
vertex Titan0Vertex [id=5224960] for label __hive_column.table with guid
40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0 (GraphToTypedInstanceMapper:211)
2018-09-19 01:00:55,596 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], __version) ==> 0
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,596 DEBUG - [NotificationHookConsumer thread-0:] ~ Found
non-composite, adding id (type: hive_table, id:
40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0) (GraphToTypedInstanceMapper:229)
2018-09-19 01:00:55,597 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo qualifiedName (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,597 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=qualifiedName, dataType={name=string, description=null},
multiplicity={lower=1, upper=1, isUnique=false}, isComposite=false,
isUnique=true, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5225472] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,597 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], Referenceable.qualifiedName) ==>
dbhrexbihs5l.tablevglokegm1z.name@primary (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,597 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], Referenceable.qualifiedName) ==>
dbhrexbihs5l.tablevglokegm1z.name@primary (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,598 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo replicatedFrom (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,598 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5225472] to array replicatedFrom
(GraphToTypedInstanceMapper:243)
2018-09-19 01:00:55,598 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo replicatedTo (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,598 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5225472] to array replicatedTo
(GraphToTypedInstanceMapper:243)
2018-09-19 01:00:55,598 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo name (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,599 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=name, dataType={name=string, description=null},
multiplicity={lower=1, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5225472] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,599 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], Asset.name) ==> name
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,599 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], Asset.name) ==> name
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,599 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo description (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,599 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=description, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5225472] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,600 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], Asset.description) ==> null
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,600 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo owner (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,600 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=owner, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5225472] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,600 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], Asset.owner) ==> jenkins
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,601 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5225472 type=hive_column
guid=174638c5-b90d-401e-91cc-7d3ce4e2cac6], Asset.owner) ==> jenkins
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,601 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo parameters (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,601 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5224960] to array parameters
(GraphToTypedInstanceMapper:307)
2018-09-19 01:00:55,602 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo viewOriginalText (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,602 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=viewOriginalText, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,602 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], hive_table.viewOriginalText) ==>
null (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,602 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo viewExpandedText (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,602 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=viewExpandedText, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,603 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], hive_table.viewExpandedText) ==>
null (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,603 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo tableType (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,603 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=tableType, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,603 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], hive_table.tableType) ==>
MANAGED_TABLE (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,604 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], hive_table.tableType) ==>
MANAGED_TABLE (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,604 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo temporary (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,604 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=temporary, dataType={name=boolean, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,604 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], hive_table.temporary) ==> false
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,604 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], hive_table.temporary) ==> false
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,605 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo qualifiedName (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,605 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=qualifiedName, dataType={name=string, description=null},
multiplicity={lower=1, upper=1, isUnique=false}, isComposite=false,
isUnique=true, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,605 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], Referenceable.qualifiedName) ==>
dbhrexbihs5l.tablevglokegm1z@primary (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,605 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], Referenceable.qualifiedName) ==>
dbhrexbihs5l.tablevglokegm1z@primary (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,605 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo replicatedFrom (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,606 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5224960] to array replicatedFrom
(GraphToTypedInstanceMapper:243)
2018-09-19 01:00:55,606 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo replicatedTo (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,606 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5224960] to array replicatedTo
(GraphToTypedInstanceMapper:243)
2018-09-19 01:00:55,606 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo name (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,606 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=name, dataType={name=string, description=null},
multiplicity={lower=1, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,607 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], Asset.name) ==> tablevglokegm1z
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,607 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], Asset.name) ==> tablevglokegm1z
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,607 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo description (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,607 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=description, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,607 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], Asset.description) ==> null
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,608 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo owner (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,608 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=owner, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224960] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,608 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], Asset.owner) ==> jenkins
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,608 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224960 type=hive_table
guid=40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0], Asset.owner) ==> jenkins
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,609 DEBUG - [NotificationHookConsumer thread-0:] ~ graph
commit (GraphTransactionInterceptor:65)
2018-09-19 01:00:55,609 DEBUG - [NotificationHookConsumer thread-0:] ~ ==>
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:187)
2018-09-19 01:00:55,609 DEBUG - [NotificationHookConsumer thread-0:] ~ <==
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:197)
2018-09-19 01:00:55,609 DEBUG - [NotificationHookConsumer thread-0:] ~
Retrieving entities with guids=[62c42a5a-1914-41bf-bc3c-3885903452b1]
(GraphBackedMetadataRepository:173)
2018-09-19 01:00:55,609 DEBUG - [NotificationHookConsumer thread-0:] ~ graph
commit (GraphTransactionInterceptor:65)
2018-09-19 01:00:55,609 DEBUG - [NotificationHookConsumer thread-0:] ~ ==>
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:187)
2018-09-19 01:00:55,610 DEBUG - [NotificationHookConsumer thread-0:] ~ <==
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:197)
2018-09-19 01:00:55,610 DEBUG - [NotificationHookConsumer thread-0:] ~
Retrieving entities with guids=[174638c5-b90d-401e-91cc-7d3ce4e2cac6]
(GraphBackedMetadataRepository:173)
2018-09-19 01:00:55,610 DEBUG - [NotificationHookConsumer thread-0:] ~ graph
commit (GraphTransactionInterceptor:65)
2018-09-19 01:00:55,610 DEBUG - [NotificationHookConsumer thread-0:] ~ ==>
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:187)
2018-09-19 01:00:55,610 DEBUG - [NotificationHookConsumer thread-0:] ~ <==
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:197)
2018-09-19 01:00:55,610 DEBUG - [NotificationHookConsumer thread-0:] ~
Retrieving entities with guids=[9207617e-02d3-47b3-a1e4-69c88b8dc792]
(GraphBackedMetadataRepository:173)
2018-09-19 01:00:55,611 DEBUG - [NotificationHookConsumer thread-0:] ~ graph
commit (GraphTransactionInterceptor:65)
2018-09-19 01:00:55,611 DEBUG - [NotificationHookConsumer thread-0:] ~ ==>
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:187)
2018-09-19 01:00:55,611 DEBUG - [NotificationHookConsumer thread-0:] ~ <==
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:197)
2018-09-19 01:00:55,614 DEBUG - [NotificationHookConsumer thread-0:] ~ Sending
message for topic ATLAS_ENTITIES:
{"msgSourceIP":"67.195.81.155","msgCreatedBy":"","msgCreationTime":1537318855613,"message":{"entity":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"0049ce49-b5af-4244-b22a-f6ba48b174f2","version":0,"typeName":"hive_process","state":"ACTIVE"},"typeName":"hive_process","values":{"queryId":"jenkins_20180919010052_60b4a2b4-e542-43fa-967e-3384426b72f2","name":"create
table dbhrexbihs5l.tablevglokegm1z as select * from
table56jr7h2mvw","startTime":"2018-09-19T01:00:52.222Z","queryPlan":"Not
Supported","operationType":"CREATETABLE_AS_SELECT","outputs":[{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"DataSet","state":"ACTIVE"}],"endTime":"2018-09-19T01:00:54.507Z","recentQueries":["create
table dbhrexbihs5l.tablevglokegm1z as select * from
table56jr7h2mvw"],"inputs":[{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"ac1432a8-c864-45d5-873d-16b98991b323","version":0,"typeName":"DataSet","state":"ACTIVE"}],"qualifiedName":"dbhrexbihs5l.tablevglokegm1z@primary:1537318853000","queryText":"create
table dbhrexbihs5l.tablevglokegm1z as select * from
table56jr7h2mvw","userName":"jenkins"},"traitNames":[],"traits":{},"systemAttributes":{}},"operationType":"ENTITY_CREATE","traits":[]},"version":{"version":"1.0.0"},"msgCompressionKind":"NONE","msgSplitIdx":1,"msgSplitCount":1}
(KafkaNotification:198)
2018-09-19 01:00:55,615 DEBUG - [NotificationHookConsumer thread-0:] ~ Sending
message for topic ATLAS_ENTITIES:
{"msgSourceIP":"67.195.81.155","msgCreatedBy":"","msgCreationTime":1537318855613,"message":{"entity":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"hive_table","state":"ACTIVE"},"typeName":"hive_table","values":{"tableType":"MANAGED_TABLE","name":"tablevglokegm1z","createTime":"2018-09-19T01:00:53.000Z","temporary":false,"db":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"bec557d4-f3d3-4a74-8d33-d33e35a15672","version":0,"typeName":"hive_db","state":"ACTIVE"},"retention":0,"qualifiedName":"dbhrexbihs5l.tablevglokegm1z@primary","columns":[{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"62c42a5a-1914-41bf-bc3c-3885903452b1","version":0,"typeName":"hive_column","state":"ACTIVE"},"typeName":"hive_column","values":{"name":"id","qualifiedName":"dbhrexbihs5l.tablevglokegm1z.id@primary","position":0,"owner":"jenkins","type":"int","table":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"hive_table","state":"ACTIVE"}},"traitNames":[],"traits":{},"systemAttributes":{"createdBy":"jenkins","modifiedBy":"jenkins","createdTime":"2018-09-19T01:00:55.033Z","modifiedTime":"2018-09-19T01:00:55.033Z"}},{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"174638c5-b90d-401e-91cc-7d3ce4e2cac6","version":0,"typeName":"hive_column","state":"ACTIVE"},"typeName":"hive_column","values":{"name":"name","qualifiedName":"dbhrexbihs5l.tablevglokegm1z.name@primary","position":1,"owner":"jenkins","type":"string","table":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"hive_table","state":"ACTIVE"}},"traitNames":[],"traits":{},"systemAttributes":{"createdBy":"jenkins","modifiedBy":"jenkins","createdTime":"2018-09-19T01:00:55.033Z","modifiedTime":"2018-09-19T01:00:55.033Z"}}],"lastAccessTime":"2018-09-19T01:00:53.000Z","owner":"jenkins","sd":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"9207617e-02d3-47b3-a1e4-69c88b8dc792","version":0,"typeName":"hive_storagedesc","state":"ACTIVE"},"typeName":"hive_storagedesc","values":{"location":"<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/target/metastore/dbhrexbihs5l.db/tablevglokegm1z","serdeInfo";>:{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Struct","typeName":"hive_serde","values":{"serializationLib":"org.apache.hadoop.hive.serde2.lazy.LazySimpleSerDe","parameters":{"serialization.format":"1"}}},"qualifiedName":"dbhrexbihs5l.tablevglokegm1z@primary_storage","outputFormat":"org.apache.hadoop.hive.ql.io.HiveIgnoreKeyTextOutputFormat","compressed":false,"numBuckets":-1,"inputFormat":"org.apache.hadoop.mapred.TextInputFormat","storedAsSubDirectories":false,"table":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"hive_table","state":"ACTIVE"}},"traitNames":[],"traits":{},"systemAttributes":{"createdBy":"jenkins","modifiedBy":"jenkins","createdTime":"2018-09-19T01:00:55.033Z","modifiedTime":"2018-09-19T01:00:55.033Z"}},"parameters":{"rawDataSize":"0","numFiles":"1","transient_lastDdlTime":"1537318854","totalSize":"0","COLUMN_STATS_ACCURATE":"true","numRows":"0"}},"traitNames":[],"traits":{},"systemAttributes":{}},"operationType":"ENTITY_CREATE","traits":[]},"version":{"version":"1.0.0"},"msgCompressionKind":"NONE","msgSplitIdx":1,"msgSplitCount":1}
(KafkaNotification:198)
2018-09-19 01:00:55,616 DEBUG - [NotificationHookConsumer thread-0:] ~ Sending
message for topic ATLAS_ENTITIES:
{"msgSourceIP":"67.195.81.155","msgCreatedBy":"","msgCreationTime":1537318855614,"message":{"entity":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"62c42a5a-1914-41bf-bc3c-3885903452b1","version":0,"typeName":"hive_column","state":"ACTIVE"},"typeName":"hive_column","values":{"name":"id","qualifiedName":"dbhrexbihs5l.tablevglokegm1z.id@primary","position":0,"owner":"jenkins","type":"int","table":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"hive_table","state":"ACTIVE"}},"traitNames":[],"traits":{},"systemAttributes":{}},"operationType":"ENTITY_CREATE","traits":[]},"version":{"version":"1.0.0"},"msgCompressionKind":"NONE","msgSplitIdx":1,"msgSplitCount":1}
(KafkaNotification:198)
2018-09-19 01:00:55,616 DEBUG - [NotificationHookConsumer thread-0:] ~ Sending
message for topic ATLAS_ENTITIES:
{"msgSourceIP":"67.195.81.155","msgCreatedBy":"","msgCreationTime":1537318855614,"message":{"entity":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"174638c5-b90d-401e-91cc-7d3ce4e2cac6","version":0,"typeName":"hive_column","state":"ACTIVE"},"typeName":"hive_column","values":{"name":"name","qualifiedName":"dbhrexbihs5l.tablevglokegm1z.name@primary","position":1,"owner":"jenkins","type":"string","table":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"hive_table","state":"ACTIVE"}},"traitNames":[],"traits":{},"systemAttributes":{}},"operationType":"ENTITY_CREATE","traits":[]},"version":{"version":"1.0.0"},"msgCompressionKind":"NONE","msgSplitIdx":1,"msgSplitCount":1}
(KafkaNotification:198)
2018-09-19 01:00:55,616 DEBUG - [NotificationHookConsumer thread-0:] ~ Sending
message for topic ATLAS_ENTITIES:
{"msgSourceIP":"67.195.81.155","msgCreatedBy":"","msgCreationTime":1537318855614,"message":{"entity":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"9207617e-02d3-47b3-a1e4-69c88b8dc792","version":0,"typeName":"hive_storagedesc","state":"ACTIVE"},"typeName":"hive_storagedesc","values":{"location":"<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/target/metastore/dbhrexbihs5l.db/tablevglokegm1z","serdeInfo";>:{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Struct","typeName":"hive_serde","values":{"serializationLib":"org.apache.hadoop.hive.serde2.lazy.LazySimpleSerDe","parameters":{"serialization.format":"1"}}},"qualifiedName":"dbhrexbihs5l.tablevglokegm1z@primary_storage","outputFormat":"org.apache.hadoop.hive.ql.io.HiveIgnoreKeyTextOutputFormat","compressed":false,"numBuckets":-1,"inputFormat":"org.apache.hadoop.mapred.TextInputFormat","storedAsSubDirectories":false,"table":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"40ef1eb5-82a5-4aa0-bc4f-51524a6e55f0","version":0,"typeName":"hive_table","state":"ACTIVE"}},"traitNames":[],"traits":{},"systemAttributes":{}},"operationType":"ENTITY_CREATE","traits":[]},"version":{"version":"1.0.0"},"msgCompressionKind":"NONE","msgSplitIdx":1,"msgSplitCount":1}
(KafkaNotification:198)
2018-09-19 01:00:55,665 DEBUG - [NotificationHookConsumer thread-0:] ~ Sent
message for topic - ATLAS_ENTITIES, partition - 0, offset - 1266
(KafkaNotification:214)
2018-09-19 01:00:55,720 DEBUG - [NotificationHookConsumer thread-0:] ~ Sent
message for topic - ATLAS_ENTITIES, partition - 0, offset - 1267
(KafkaNotification:214)
2018-09-19 01:00:55,762 DEBUG - [NotificationHookConsumer thread-0:] ~ Sent
message for topic - ATLAS_ENTITIES, partition - 0, offset - 1268
(KafkaNotification:214)
2018-09-19 01:00:55,820 DEBUG - [NotificationHookConsumer thread-0:] ~ Sent
message for topic - ATLAS_ENTITIES, partition - 0, offset - 1269
(KafkaNotification:214)
2018-09-19 01:00:55,864 DEBUG - [NotificationHookConsumer thread-0:] ~ Sent
message for topic - ATLAS_ENTITIES, partition - 0, offset - 1270
(KafkaNotification:214)
2018-09-19 01:00:55,865 DEBUG - [NotificationHookConsumer thread-0:] ~
Retrieving entities with guids=[bec557d4-f3d3-4a74-8d33-d33e35a15672]
(GraphBackedMetadataRepository:173)
2018-09-19 01:00:55,865 DEBUG - [NotificationHookConsumer thread-0:] ~
Executing: OrCondition [andExprs=AndExpr [predicates=[InPredicate
[propertyName=__guid, values=[bec557d4-f3d3-4a74-8d33-d33e35a15672]]]]]
(TitanGraphQuery:130)
2018-09-19 01:00:55,866 DEBUG - [NotificationHookConsumer thread-0:] ~ Found a
vertex vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672] with __guid =
bec557d4-f3d3-4a74-8d33-d33e35a15672 (GraphHelper:463)
2018-09-19 01:00:55,866 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
graph root vertex Titan0Vertex [id=5224448] to typed instance for guid
bec557d4-f3d3-4a74-8d33-d33e35a15672 (GraphToTypedInstanceMapper:83)
2018-09-19 01:00:55,867 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], __typeName) ==> hive_db
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,867 DEBUG - [NotificationHookConsumer thread-0:] ~ Found
createdBy : jenkins modifiedBy : jenkins createdTime: Wed Sep 19 01:00:52 UTC
2018 modifedTime: Wed Sep 19 01:00:55 UTC 2018 (GraphToTypedInstanceMapper:96)
2018-09-19 01:00:55,868 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], __version) ==> 0
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,868 DEBUG - [NotificationHookConsumer thread-0:] ~ Created
id (type: hive_db, id: bec557d4-f3d3-4a74-8d33-d33e35a15672) for instance type
hive_db (GraphToTypedInstanceMapper:103)
2018-09-19 01:00:55,868 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
vertex Titan0Vertex [id=5224448] to instance hive_db for fields
(GraphToTypedInstanceMapper:132)
2018-09-19 01:00:55,868 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo clusterName (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,868 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=clusterName, dataType={name=string, description=null},
multiplicity={lower=1, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224448] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,869 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], hive_db.clusterName) ==> primary
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,869 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], hive_db.clusterName) ==> primary
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,869 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo location (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,869 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=location, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224448] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,870 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], hive_db.location) ==>
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/target/metastore/dbhrexbihs5l.db>
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,870 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], hive_db.location) ==>
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/target/metastore/dbhrexbihs5l.db>
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,870 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo parameters (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,870 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5224448] to array parameters
(GraphToTypedInstanceMapper:307)
2018-09-19 01:00:55,871 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo ownerType (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,871 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo qualifiedName (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,871 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=qualifiedName, dataType={name=string, description=null},
multiplicity={lower=1, upper=1, isUnique=false}, isComposite=false,
isUnique=true, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224448] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,872 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], Referenceable.qualifiedName) ==>
dbhrexbihs5l@primary (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,872 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], Referenceable.qualifiedName) ==>
dbhrexbihs5l@primary (AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,872 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo replicatedFrom (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,872 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5224448] to array replicatedFrom
(GraphToTypedInstanceMapper:243)
2018-09-19 01:00:55,872 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo replicatedTo (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,873 DEBUG - [NotificationHookConsumer thread-0:] ~ mapping
vertex Titan0Vertex [id=5224448] to array replicatedTo
(GraphToTypedInstanceMapper:243)
2018-09-19 01:00:55,873 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo name (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,873 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=name, dataType={name=string, description=null},
multiplicity={lower=1, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224448] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,873 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], Asset.name) ==> dbhrexbihs5l
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,874 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], Asset.name) ==> dbhrexbihs5l
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,874 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo description (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,874 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=description, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=false, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224448] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,874 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], Asset.description) ==> null
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,875 DEBUG - [NotificationHookConsumer thread-0:] ~ Mapping
attributeInfo owner (GraphToTypedInstanceMapper:145)
2018-09-19 01:00:55,875 DEBUG - [NotificationHookConsumer thread-0:] ~ Adding
primitive {name=owner, dataType={name=string, description=null},
multiplicity={lower=0, upper=1, isUnique=false}, isComposite=false,
isUnique=false, isIndexable=true, reverseAttributeName=null} from vertex
Titan0Vertex [id=5224448] (GraphToTypedInstanceMapper:393)
2018-09-19 01:00:55,875 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], Asset.owner) ==> jenkins
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,875 DEBUG - [NotificationHookConsumer thread-0:] ~
getProperty(vertex[id=5224448 type=hive_db
guid=bec557d4-f3d3-4a74-8d33-d33e35a15672], Asset.owner) ==> jenkins
(AtlasGraphUtilsV1:225)
2018-09-19 01:00:55,876 DEBUG - [NotificationHookConsumer thread-0:] ~ graph
commit (GraphTransactionInterceptor:65)
2018-09-19 01:00:55,876 DEBUG - [NotificationHookConsumer thread-0:] ~ ==>
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:187)
2018-09-19 01:00:55,876 DEBUG - [NotificationHookConsumer thread-0:] ~ <==
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:197)
2018-09-19 01:00:55,877 DEBUG - [NotificationHookConsumer thread-0:] ~ Sending
message for topic ATLAS_ENTITIES:
{"msgSourceIP":"67.195.81.155","msgCreatedBy":"","msgCreationTime":1537318855877,"message":{"entity":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Reference","id":{"jsonClass":"org.apache.atlas.typesystem.json.InstanceSerialization$_Id","id":"bec557d4-f3d3-4a74-8d33-d33e35a15672","version":0,"typeName":"hive_db","state":"ACTIVE"},"typeName":"hive_db","values":{"name":"dbhrexbihs5l","location":"<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/target/metastore/dbhrexbihs5l.db","ownerType";>:{"value":"USER","ordinal":1},"qualifiedName":"dbhrexbihs5l@primary","owner":"jenkins","clusterName":"primary"},"traitNames":[],"traits":{},"systemAttributes":{}},"operationType":"ENTITY_UPDATE","traits":[]},"version":{"version":"1.0.0"},"msgCompressionKind":"NONE","msgSplitIdx":1,"msgSplitCount":1}
(KafkaNotification:198)
2018-09-19 01:00:55,901 DEBUG - [NotificationHookConsumer thread-0:] ~ Sent
message for topic - ATLAS_ENTITIES, partition - 0, offset - 1271
(KafkaNotification:214)
2018-09-19 01:00:55,902 DEBUG - [NotificationHookConsumer thread-0:] ~ graph
commit (GraphTransactionInterceptor:65)
2018-09-19 01:00:55,902 DEBUG - [NotificationHookConsumer thread-0:] ~ ==>
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:187)
2018-09-19 01:00:55,902 DEBUG - [NotificationHookConsumer thread-0:] ~ <==
releaseLockedObjects(): lockedGuids.size: 0
(GraphTransactionInterceptor$ObjectUpdateSynchronizer:197)
2018-09-19 01:00:55,902 INFO - [NotificationHookConsumer thread-0:] ~
commiting the offset ==>> 146 (AtlasKafkaConsumer:91)
2018-09-19 01:00:55,935 DEBUG - [NotificationHookConsumer thread-0:] ~
PERF|ENTITY_CREATE_V2|902 (AtlasPerfTracer:77)
[INFO] Stopped ServerConnector@7f144fa7{HTTP/1.1}{0.0.0.0:31000}
[INFO] Closing Spring root WebApplicationContext
2018-09-19 01:00:59,387 DEBUG - [ShutdownMonitor:] ~ ==>
AtlasAuthorizationFilter destroy (AtlasAuthorizationFilter:78)
2018-09-19 01:00:59,387 DEBUG - [ShutdownMonitor:] ~ ==> +SimpleAtlasAuthorizer
cleanUp (SimpleAtlasAuthorizer:327)
2018-09-19 01:00:59,387 DEBUG - [ShutdownMonitor:] ~ <== +SimpleAtlasAuthorizer
cleanUp (SimpleAtlasAuthorizer:338)
2018-09-19 01:00:59,388 INFO - [ShutdownMonitor:] ~ Stopping service
org.apache.atlas.notification.NotificationHookConsumer (Services:69)
2018-09-19 01:00:59,388 INFO - [ShutdownMonitor:] ~ ==> stopConsumerThreads()
(NotificationHookConsumer:185)
2018-09-19 01:00:59,388 INFO - [ShutdownMonitor:] ~ ==> HookConsumer
shutdown() (NotificationHookConsumer$HookConsumer:587)
2018-09-19 01:00:59,389 INFO - [ShutdownMonitor:] ~
[atlas-hook-consumer-thread], Shutting down (Logging$class:68)
2018-09-19 01:00:59,390 INFO - [NotificationHookConsumer thread-0:] ~ closing
NotificationConsumer (NotificationHookConsumer$HookConsumer:328)
2018-09-19 01:00:59,397 INFO - [NotificationHookConsumer thread-0:] ~ <==
HookConsumer doWork() (NotificationHookConsumer$HookConsumer:332)
2018-09-19 01:00:59,398 INFO - [NotificationHookConsumer thread-0:] ~
[atlas-hook-consumer-thread], Stopped (Logging$class:68)
2018-09-19 01:00:59,399 INFO - [ShutdownMonitor:] ~
[atlas-hook-consumer-thread], Shutdown completed (Logging$class:68)
2018-09-19 01:00:59,399 INFO - [ShutdownMonitor:] ~ <== HookConsumer
shutdown() (NotificationHookConsumer$HookConsumer:602)
2018-09-19 01:00:59,399 INFO - [ShutdownMonitor:] ~ <== stopConsumerThreads()
(NotificationHookConsumer:194)
2018-09-19 01:00:59,400 INFO - [ShutdownMonitor:] ~ ==>
KafkaNotification.close() (KafkaNotification:167)
2018-09-19 01:00:59,404 INFO - [ShutdownMonitor:] ~ <==
KafkaNotification.close() (KafkaNotification:175)
2018-09-19 01:00:59,404 INFO - [ShutdownMonitor:] ~ Stopping service
org.apache.atlas.kafka.KafkaNotification (Services:69)
2018-09-19 01:00:59,404 INFO - [ShutdownMonitor:] ~ ==>
KafkaNotification.stop() (KafkaNotification:139)
2018-09-19 01:00:59,404 INFO - [ShutdownMonitor:] ~ <==
KafkaNotification.stop() (KafkaNotification:141)
2018-09-19 01:00:59,405 INFO - [ShutdownMonitor:] ~ Stopping service
org.apache.atlas.kafka.EmbeddedKafkaServer (Services:69)
2018-09-19 01:00:59,405 INFO - [ShutdownMonitor:] ~ ==>
EmbeddedKafkaServer.stop(isEmbedded=true) (EmbeddedKafkaServer:91)
2018-09-19 01:01:01,834 INFO - [ShutdownMonitor:] ~ <==
EmbeddedKafka.stop(isEmbedded=true) (EmbeddedKafkaServer:101)
2018-09-19 01:01:01,834 INFO - [ShutdownMonitor:] ~ Stopping service
org.apache.atlas.web.service.ActiveInstanceElectorService (Services:69)
2018-09-19 01:01:01,834 INFO - [ShutdownMonitor:] ~ HA is not enabled, no need
to stop leader election service (ActiveInstanceElectorService:126)
[INFO] Shutting down log4j
[INFO] Not cleaning cached beans: no such field
javax.el.BeanELResolver.properties
[INFO] Stopped
o.e.j.m.p.JettyWebAppContext@3ff30a63{/,<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/webapp/target/atlas-webapp-0.8.3-SNAPSHOT/,UNAVAILABLE}{/home/jenkins/jenkins-slave/workspace/Atlas-0.8-AllTests/webapp/target/atlas-webapp-0.8.3-SNAPSHOT.war}>
Build timed out (after 540 minutes). Marking the build as failed.
Build was aborted
[CHECKSTYLE] Collecting checkstyle analysis files...
[CHECKSTYLE] Searching for all files in
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/> that match the pattern
**/target/checkstyle-result.xml
[CHECKSTYLE] Parsing 27 files in
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/>
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/addons/hdfs-model/target/checkstyle-result.xml>
with 0 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/addons/hive-bridge-shim/target/checkstyle-result.xml>
with 15 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/authorization/target/checkstyle-result.xml>
with 486 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/catalog/target/checkstyle-result.xml>
with 2623 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/client/client-v1/target/checkstyle-result.xml>
with 3499 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/client/client-v2/target/checkstyle-result.xml>
with 3829 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/client/common/target/checkstyle-result.xml>
with 4359 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/client/target/checkstyle-result.xml>
with 4359 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/common/target/checkstyle-result.xml>
with 5324 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/dashboardv2/target/checkstyle-result.xml>
with 5324 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/docs/target/checkstyle-result.xml>
with 5324 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/api/target/checkstyle-result.xml>
with 5597 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/common/target/checkstyle-result.xml>
with 5784 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/graphdb-impls/target/checkstyle-result.xml>
with 5784 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/target/checkstyle-result.xml>
with 5784 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/titan0/target/checkstyle-result.xml>
with 8239 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/titan1/target/checkstyle-result.xml>
with 9206 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/intg/target/checkstyle-result.xml>
with 17075 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/notification/target/checkstyle-result.xml>
with 18883 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/plugin-classloader/target/checkstyle-result.xml>
with 18996 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/repository/target/checkstyle-result.xml>
with 39692 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/server-api/target/checkstyle-result.xml>
with 40125 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/shaded/hbase-client-shaded/target/checkstyle-result.xml>
with 40125 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/shaded/hbase-server-shaded/target/checkstyle-result.xml>
with 40125 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/target/checkstyle-result.xml>
with 40125 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/typesystem/target/checkstyle-result.xml>
with 44378 unique warnings and 0 duplicates.
[CHECKSTYLE] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/webapp/target/checkstyle-result.xml>
with 53050 unique warnings and 0 duplicates.
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=16456800b2c11969cc17de3a6423b955a958a5b2,
workspace=<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/>
> git rev-parse 16456800b2c11969cc17de3a6423b955a958a5b2^{commit} # timeout=10
<Git Blamer> Skipping file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/titan1/src/main/java/org/apache/atlas/repository/graphdb/titan1/graphson/AtlasGraphSONMode.java,>
no result found.
<Git Blamer> Skipping file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/typesystem/src/main/java/org/apache/atlas/typesystem/IStruct.java,>
no result found.
[CHECKSTYLE] Computing warning deltas based on reference build #26
[FINDBUGS] Collecting findbugs analysis files...
[FINDBUGS] Searching for all files in
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/> that match the pattern
**/target/findbugs.xml
[FINDBUGS] Parsing 18 files in
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/>
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/addons/hive-bridge-shim/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/authorization/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/catalog/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/client/client-v1/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/client/client-v2/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/client/common/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/common/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/api/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/common/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/titan0/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/graphdb/titan1/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/intg/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/notification/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/plugin-classloader/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/repository/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/server-api/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/typesystem/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
[FINDBUGS] Successfully parsed file
<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/webapp/target/findbugs.xml>
with 0 unique warnings and 0 duplicates.
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=16456800b2c11969cc17de3a6423b955a958a5b2,
workspace=<https://builds.apache.org/job/Atlas-0.8-AllTests/ws/>
[FINDBUGS] Computing warning deltas based on reference build #26
Archiving artifacts
TestNG Reports Processing: START
Looking for TestNG results report in workspace using pattern:
**/target/surefire-reports/testng-results.xml,**/target/failsafe-reports/testng-results.xml
Saving reports...
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-1.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-10.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-11.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-12.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-2.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-3.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-4.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-5.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-6.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-7.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-8.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results-9.xml'
Processing
'/x1/jenkins/jenkins-home/jobs/Atlas-0.8-AllTests/builds/31/testng/testng-results.xml'
TestNG Reports Processing: FINISH
