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