See <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/195/display/redirect?page=changes>
Changes: [madhan] ATLAS-2530 trim query string before using it as name attribute for Hive ------------------------------------------ [...truncated 43.04 MB...] 2018-04-05 23:02:48,540 DEBUG - [qtp1973014247-825:] ~ Authorizer result :: true (AtlasAuthorizationFilter:149) 2018-04-05 23:02:48,540 DEBUG - [qtp1973014247-825:] ~ Access is allowed so forwarding the request!!! (AtlasAuthorizationFilter:155) 2018-04-05 23:02:48,540 INFO - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Audit: admin/127.0.0.1-127.0.0.1 performed request GET http://localhost:31000/api/atlas/v2/entity/uniqueAttribute/type/hdfs_path?attr:qualifiedName=pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02 (127.0.0.1) at time 2018-04-05T23:02Z (AuditFilter:117) 2018-04-05 23:02:48,541 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ ==> getByUniqueAttribute(hdfs_path, {qualifiedName=pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02}) (AtlasEntityStoreV1:123) 2018-04-05 23:02:48,541 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Executing: OrCondition [andExprs=AndExpr [predicates=[HasTerm [propertyName=__typeName, op=EQUAL, value=hdfs_path], HasTerm [propertyName=__state, op=EQUAL, value=ACTIVE], HasTerm [propertyName=Referenceable.qualifiedName, op=EQUAL, value=pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02]]]] (TitanGraphQuery:130) 2018-04-05 23:02:48,543 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ findByUniqueAttributes(type=hdfs_path, attrName=qualifiedName, attrValue=pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02: found vertex Titan0Vertex [id=2681088] (AtlasGraphUtilsV1:249) 2018-04-05 23:02:48,543 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping graph vertex to atlas entity for guid 0ee40cf4-9243-47e3-92fe-367ec5e52cdf (EntityGraphRetriever:176) 2018-04-05 23:02:48,544 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping system attributes for type null (EntityGraphRetriever:268) 2018-04-05 23:02:48,544 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ ==> AtlasTypeRegistry.getType(hdfs_path) (AtlasTypeRegistry:79) 2018-04-05 23:02:48,544 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ <== AtlasTypeRegistry.getType(hdfs_path): org.apache.atlas.type.AtlasEntityType@196bf05f (AtlasTypeRegistry:105) 2018-04-05 23:02:48,545 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity Asset.owner (EntityGraphRetriever:391) 2018-04-05 23:02:48,545 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property Asset.owner from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,545 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.modifiedTime (EntityGraphRetriever:391) 2018-04-05 23:02:48,546 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.modifiedTime from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,546 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.modifiedTime from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,546 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.isFile (EntityGraphRetriever:391) 2018-04-05 23:02:48,547 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.isFile from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,547 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.isFile from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,547 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity hdfs_path.numberOfReplicas (EntityGraphRetriever:391) 2018-04-05 23:02:48,548 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property hdfs_path.numberOfReplicas from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,548 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property hdfs_path.numberOfReplicas from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,548 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity Referenceable.qualifiedName (EntityGraphRetriever:391) 2018-04-05 23:02:48,548 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property Referenceable.qualifiedName from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,549 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property Referenceable.qualifiedName from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,549 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity Asset.description (EntityGraphRetriever:391) 2018-04-05 23:02:48,549 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property Asset.description from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,550 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity hdfs_path.extendedAttributes (EntityGraphRetriever:391) 2018-04-05 23:02:48,550 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.path (EntityGraphRetriever:391) 2018-04-05 23:02:48,550 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.path from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,551 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.path from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,551 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.posixPermissions (EntityGraphRetriever:391) 2018-04-05 23:02:48,551 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Finding edges for vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] with label __fs_path.posixPermissions (GraphHelper:293) 2018-04-05 23:02:48,552 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Found null (GraphHelper:366) 2018-04-05 23:02:48,552 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.createTime (EntityGraphRetriever:391) 2018-04-05 23:02:48,552 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.createTime from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,552 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.createTime from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,553 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.fileSize (EntityGraphRetriever:391) 2018-04-05 23:02:48,553 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.fileSize from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,553 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.fileSize from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,554 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity hdfs_path.clusterName (EntityGraphRetriever:391) 2018-04-05 23:02:48,554 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property hdfs_path.clusterName from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,554 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property hdfs_path.clusterName from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,555 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity Asset.name (EntityGraphRetriever:391) 2018-04-05 23:02:48,555 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property Asset.name from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,555 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property Asset.name from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,555 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.isSymlink (EntityGraphRetriever:391) 2018-04-05 23:02:48,556 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.isSymlink from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,556 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.isSymlink from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,556 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Mapping vertex Titan0Vertex [id=2681088] to atlas entity fs_path.group (EntityGraphRetriever:391) 2018-04-05 23:02:48,557 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ Reading property fs_path.group from vertex[id=2681088 type=hdfs_path guid=0ee40cf4-9243-47e3-92fe-367ec5e52cdf] (GraphHelper:433) 2018-04-05 23:02:48,557 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ <== getByUniqueAttribute(hdfs_path, {qualifiedName=pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02}): AtlasEntityWithExtInfo{entity=AtlasEntity{AtlasStruct{typeName='hdfs_path', attributes=[owner:null, modifiedTime:Thu Jan 01 00:00:00 UTC 1970, isFile:false, numberOfReplicas:0, qualifiedName:pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02, description:null, extendedAttributes:null, path:pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02, posixPermissions:null, createTime:Thu Jan 01 00:00:00 UTC 1970, fileSize:0, clusterName:primary, name:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02, isSymlink:false, group:null]}guid='0ee40cf4-9243-47e3-92fe-367ec5e52cdf', status=ACTIVE, createdBy='jenkins', updatedBy='jenkins', createTime=2018-04-05T23:02:47.176Z, updateTime=2018-04-05T23:02:47.176Z, version=0, classifications=[], },AtlasEntityExtInfo{referredEntities={}}} (AtlasEntityStoreV1:138) 2018-04-05 23:02:48,557 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ graph commit (GraphTransactionInterceptor:65) 2018-04-05 23:02:48,558 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ ==> releaseLockedObjects(): lockedGuids.size: 0 (GraphTransactionInterceptor$ObjectUpdateSynchronizer:187) 2018-04-05 23:02:48,558 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ <== releaseLockedObjects(): lockedGuids.size: 0 (GraphTransactionInterceptor$ObjectUpdateSynchronizer:197) 2018-04-05 23:02:48,558 DEBUG - [qtp1973014247-825 - f0cf88b9-9ed3-41bf-964a-9d03712dea13:] ~ PERF|EntityREST.getByUniqueAttributes(hdfs_path,{qualifiedName=pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02})|17 (AtlasPerfTracer:77) 127.0.0.1 - - [05/Apr/2018:23:02:48 +0000] "GET /api/atlas/v2/entity/uniqueAttribute/type/hdfs_path?attr:qualifiedName=pfile:/home/jenkins/jenkins-slave/workspace/atlas-0.8-integrationtests/addons/hive-bridge/target/somedfspath2-data-aeflnsch02 HTTP/1.1" 200 - "-" "Java/1.8.0_152" Tests run: 46, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 426.747 sec <<< FAILURE! - in TestSuite testCreateTableHiveProcessNameAttribute(org.apache.atlas.hive.bridge.HiveMetastoreBridgeIT) Time elapsed: 0.047 sec <<< FAILURE! java.lang.AssertionError: expected [0] but found [40000] at org.testng.Assert.fail(Assert.java:94) at org.testng.Assert.failNotEquals(Assert.java:496) at org.testng.Assert.assertEquals(Assert.java:125) at org.testng.Assert.assertEquals(Assert.java:372) at org.testng.Assert.assertEquals(Assert.java:382) at org.apache.atlas.hive.HiveITBase.runCommandWithDelay(HiveITBase.java:152) at org.apache.atlas.hive.HiveITBase.runCommandWithDelay(HiveITBase.java:146) at org.apache.atlas.hive.HiveITBase.runCommand(HiveITBase.java:138) at org.apache.atlas.hive.bridge.HiveMetastoreBridgeIT.testCreateTableHiveProcessNameAttribute(HiveMetastoreBridgeIT.java:105) Results : Failed tests: HiveMetastoreBridgeIT.testCreateTableHiveProcessNameAttribute:105->HiveITBase.runCommand:138->HiveITBase.runCommandWithDelay:146->HiveITBase.runCommandWithDelay:152 expected [0] but found [40000] Tests run: 46, Failures: 1, Errors: 0, Skipped: 0 [INFO] [INFO] --- jetty-maven-plugin:9.2.12.v20150709:stop (stop-jetty) @ hive-bridge --- [INFO] Waiting 10 seconds for jetty to stop [INFO] Stopped ServerConnector@7282b262{HTTP/1.1}{0.0.0.0:31000} [INFO] Closing Spring root WebApplicationContext 2018-04-05 23:02:49,228 DEBUG - [ShutdownMonitor:] ~ ==> AtlasAuthorizationFilter destroy (AtlasAuthorizationFilter:78) 2018-04-05 23:02:49,229 DEBUG - [ShutdownMonitor:] ~ ==> +SimpleAtlasAuthorizer cleanUp (SimpleAtlasAuthorizer:327) 2018-04-05 23:02:49,229 DEBUG - [ShutdownMonitor:] ~ <== +SimpleAtlasAuthorizer cleanUp (SimpleAtlasAuthorizer:338) 2018-04-05 23:02:49,229 INFO - [ShutdownMonitor:] ~ Stopping service org.apache.atlas.notification.NotificationHookConsumer (Services:69) 2018-04-05 23:02:49,229 INFO - [ShutdownMonitor:] ~ ==> stopConsumerThreads() (NotificationHookConsumer:185) 2018-04-05 23:02:49,230 INFO - [ShutdownMonitor:] ~ ==> HookConsumer shutdown() (NotificationHookConsumer$HookConsumer:560) 2018-04-05 23:02:49,231 INFO - [ShutdownMonitor:] ~ [atlas-hook-consumer-thread], Shutting down (Logging$class:68) 2018-04-05 23:02:49,232 INFO - [NotificationHookConsumer thread-0:] ~ closing NotificationConsumer (NotificationHookConsumer$HookConsumer:325) 2018-04-05 23:02:49,238 INFO - [NotificationHookConsumer thread-0:] ~ <== HookConsumer doWork() (NotificationHookConsumer$HookConsumer:329) 2018-04-05 23:02:49,239 INFO - [NotificationHookConsumer thread-0:] ~ [atlas-hook-consumer-thread], Stopped (Logging$class:68) 2018-04-05 23:02:49,241 INFO - [ShutdownMonitor:] ~ [atlas-hook-consumer-thread], Shutdown completed (Logging$class:68) 2018-04-05 23:02:49,241 INFO - [ShutdownMonitor:] ~ <== HookConsumer shutdown() (NotificationHookConsumer$HookConsumer:575) 2018-04-05 23:02:49,241 INFO - [ShutdownMonitor:] ~ <== stopConsumerThreads() (NotificationHookConsumer:194) 2018-04-05 23:02:49,242 INFO - [ShutdownMonitor:] ~ ==> KafkaNotification.close() (KafkaNotification:163) 2018-04-05 23:02:49,246 INFO - [ShutdownMonitor:] ~ <== KafkaNotification.close() (KafkaNotification:171) 2018-04-05 23:02:49,246 INFO - [ShutdownMonitor:] ~ Stopping service org.apache.atlas.kafka.KafkaNotification (Services:69) 2018-04-05 23:02:49,246 INFO - [ShutdownMonitor:] ~ ==> KafkaNotification.stop() (KafkaNotification:135) 2018-04-05 23:02:49,246 INFO - [ShutdownMonitor:] ~ <== KafkaNotification.stop() (KafkaNotification:137) 2018-04-05 23:02:49,247 INFO - [ShutdownMonitor:] ~ Stopping service org.apache.atlas.kafka.EmbeddedKafkaServer (Services:69) 2018-04-05 23:02:49,247 INFO - [ShutdownMonitor:] ~ ==> EmbeddedKafkaServer.stop(isEmbedded=true) (EmbeddedKafkaServer:91) 2018-04-05 23:02:51,633 INFO - [ShutdownMonitor:] ~ <== EmbeddedKafka.stop(isEmbedded=true) (EmbeddedKafkaServer:101) 2018-04-05 23:02:51,633 INFO - [ShutdownMonitor:] ~ Stopping service org.apache.atlas.web.service.ActiveInstanceElectorService (Services:69) 2018-04-05 23:02:51,633 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@23b6fd0c{/,<https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/webapp/target/atlas-webapp-0.8.3-SNAPSHOT/,UNAVAILABLE}{/home/jenkins/jenkins-slave/workspace/Atlas-0.8-IntegrationTests/webapp/target/atlas-webapp-0.8.3-SNAPSHOT.war}> [INFO] Server reports itself as stopped [INFO] [INFO] --- maven-source-plugin:2.4:jar-no-fork (attach-sources) @ hive-bridge --- [INFO] Building jar: <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/addons/hive-bridge/target/hive-bridge-0.8.3-SNAPSHOT-sources.jar> [INFO] [INFO] --- maven-source-plugin:2.4:test-jar-no-fork (attach-sources) @ hive-bridge --- [INFO] Building jar: <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/addons/hive-bridge/target/hive-bridge-0.8.3-SNAPSHOT-test-sources.jar> [INFO] [INFO] --- maven-failsafe-plugin:2.18.1:verify (verify) @ hive-bridge --- [INFO] Failsafe report directory: <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/addons/hive-bridge/target/failsafe-reports> [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Apache Atlas Server Build Tools .................... SUCCESS [ 9.011 s] [INFO] apache-atlas ....................................... SUCCESS [ 47.740 s] [INFO] Apache Atlas Integration ........................... SUCCESS [ 51.429 s] [INFO] Apache Atlas Common ................................ SUCCESS [ 9.879 s] [INFO] Apache Atlas Typesystem ............................ SUCCESS [ 50.598 s] [INFO] Apache Atlas Client ................................ SUCCESS [ 0.853 s] [INFO] atlas-client-common ................................ SUCCESS [ 7.999 s] [INFO] atlas-client-v1 .................................... SUCCESS [ 8.179 s] [INFO] Apache Atlas Server API ............................ SUCCESS [ 6.625 s] [INFO] Apache Atlas Notification .......................... SUCCESS [ 11.748 s] [INFO] atlas-client-v2 .................................... SUCCESS [ 6.240 s] [INFO] Apache Atlas Graph Database Projects ............... SUCCESS [ 0.553 s] [INFO] Apache Atlas Graph Database API .................... SUCCESS [ 5.669 s] [INFO] Graph Database Common Code ......................... SUCCESS [ 5.887 s] [INFO] Apache Atlas Titan 1.0.0 GraphDB Impl .............. SUCCESS [ 40.991 s] [INFO] Shaded version of Apache hbase client .............. SUCCESS [ 8.851 s] [INFO] Apache Atlas Titan 0.5.4 Graph DB Impl ............. SUCCESS [ 40.692 s] [INFO] Apache Atlas Graph Database Implementation Dependencies SUCCESS [ 0.649 s] [INFO] Shaded version of Apache hbase server .............. SUCCESS [ 23.566 s] [INFO] Apache Atlas Repository ............................ SUCCESS [01:24 min] [INFO] Apache Atlas Authorization ......................... SUCCESS [ 6.788 s] [INFO] Apache Atlas Business Catalog ...................... SUCCESS [ 11.121 s] [INFO] Apache Atlas UI .................................... SUCCESS [01:22 min] [INFO] Apache Atlas Web Application ....................... SUCCESS [05:14 min] [INFO] Apache Atlas Documentation ......................... SUCCESS [ 3.447 s] [INFO] Apache Atlas FileSystem Model ...................... SUCCESS [ 1.583 s] [INFO] Apache Atlas Plugin Classloader .................... SUCCESS [ 6.350 s] [INFO] Apache Atlas Hive Bridge Shim ...................... SUCCESS [ 4.960 s] [INFO] Apache Atlas Hive Bridge ........................... FAILURE [08:12 min] [INFO] Apache Atlas Falcon Bridge Shim .................... SKIPPED [INFO] Apache Atlas Falcon Bridge ......................... SKIPPED [INFO] Apache Atlas Sqoop Bridge Shim ..................... SKIPPED [INFO] Apache Atlas Sqoop Bridge .......................... SKIPPED [INFO] Apache Atlas Storm Bridge Shim ..................... SKIPPED [INFO] Apache Atlas Storm Bridge .......................... SKIPPED [INFO] Apache Atlas Hbase Bridge Shim ..................... SKIPPED [INFO] Apache Atlas Hbase Bridge .......................... SKIPPED [INFO] Apache Atlas Migration Exporter .................... SKIPPED [INFO] Apache Atlas Distribution .......................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 22:41 min [INFO] Finished at: 2018-04-05T23:02:52Z [INFO] Final Memory: 586M/3612M [INFO] ------------------------------------------------------------------------ log4j:WARN No appenders could be found for logger (org.apache.atlas.security.InMemoryJAASConfiguration). log4j:WARN Please initialize the log4j system properly. log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info. [ERROR] Failed to execute goal org.apache.maven.plugins:maven-failsafe-plugin:2.18.1:verify (verify) on project hive-bridge: There are test failures. [ERROR] [ERROR] Please refer to <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/addons/hive-bridge/target/failsafe-reports> for the individual test results. [ERROR] -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException [ERROR] [ERROR] After correcting the problems, you can resume the build with the command [ERROR] mvn <goals> -rf :hive-bridge Build step 'Execute shell' marked build as failure [CHECKSTYLE] Collecting checkstyle analysis files... [CHECKSTYLE] Searching for all files in <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/> that match the pattern **/target/checkstyle-result.xml [CHECKSTYLE] Parsing 27 files in <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/> [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/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-IntegrationTests/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-IntegrationTests/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-IntegrationTests/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-IntegrationTests/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-IntegrationTests/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-IntegrationTests/ws/client/common/target/checkstyle-result.xml> with 4319 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/client/target/checkstyle-result.xml> with 4319 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/common/target/checkstyle-result.xml> with 5278 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/dashboardv2/target/checkstyle-result.xml> with 5278 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/docs/target/checkstyle-result.xml> with 5278 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/graphdb/api/target/checkstyle-result.xml> with 5551 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/graphdb/common/target/checkstyle-result.xml> with 5738 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/graphdb/graphdb-impls/target/checkstyle-result.xml> with 5738 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/graphdb/target/checkstyle-result.xml> with 5738 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/graphdb/titan0/target/checkstyle-result.xml> with 8193 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/graphdb/titan1/target/checkstyle-result.xml> with 9160 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/intg/target/checkstyle-result.xml> with 16080 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/notification/target/checkstyle-result.xml> with 17837 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/plugin-classloader/target/checkstyle-result.xml> with 17950 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/repository/target/checkstyle-result.xml> with 36978 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/server-api/target/checkstyle-result.xml> with 37368 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/shaded/hbase-client-shaded/target/checkstyle-result.xml> with 37368 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/shaded/hbase-server-shaded/target/checkstyle-result.xml> with 37368 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/target/checkstyle-result.xml> with 37368 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/typesystem/target/checkstyle-result.xml> with 41609 unique warnings and 0 duplicates. [CHECKSTYLE] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/webapp/target/checkstyle-result.xml> with 50017 unique warnings and 0 duplicates. <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=4cfeb418571ea1c1ad10be7726a144b3d57d0b70, workspace=<https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/> > git rev-parse 4cfeb418571ea1c1ad10be7726a144b3d57d0b70^{commit} # timeout=10 [FINDBUGS] Collecting findbugs analysis files... [FINDBUGS] Searching for all files in <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/> that match the pattern **/target/findbugs.xml [FINDBUGS] Parsing 18 files in <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/> [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/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-IntegrationTests/ws/authorization/target/findbugs.xml> with 0 unique warnings and 0 duplicates. [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/catalog/target/findbugs.xml> with 0 unique warnings and 0 duplicates. [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/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-IntegrationTests/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-IntegrationTests/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-IntegrationTests/ws/common/target/findbugs.xml> with 0 unique warnings and 0 duplicates. [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/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-IntegrationTests/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-IntegrationTests/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-IntegrationTests/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-IntegrationTests/ws/intg/target/findbugs.xml> with 0 unique warnings and 0 duplicates. [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/notification/target/findbugs.xml> with 0 unique warnings and 0 duplicates. [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/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-IntegrationTests/ws/repository/target/findbugs.xml> with 0 unique warnings and 0 duplicates. [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/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-IntegrationTests/ws/typesystem/target/findbugs.xml> with 0 unique warnings and 0 duplicates. [FINDBUGS] Successfully parsed file <https://builds.apache.org/job/Atlas-0.8-IntegrationTests/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=4cfeb418571ea1c1ad10be7726a144b3d57d0b70, workspace=<https://builds.apache.org/job/Atlas-0.8-IntegrationTests/ws/> Archiving artifacts [Fast Archiver] No prior successful build to compare, so performing full copy of 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-IntegrationTests/builds/195/testng/testng-results-1.xml' Processing '/x1/jenkins/jenkins-home/jobs/Atlas-0.8-IntegrationTests/builds/195/testng/testng-results.xml' 0.552486% of tests failed, which exceeded threshold of 0%. Marking build as UNSTABLE TestNG Reports Processing: FINISH
