mvn test log for 2.x driver related IT failures

Pinpoint MongoDB 2.x Driver IT failure

#####testRunStarted
#####testStarted#####testConnection[:child:8](com.navercorp.pinpoint.plugin.mongodb.MongoDBIT_2_X_IT)
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
Extract /home/gowris/.embedmongo/linux/mongodb-linux-x86_64-3.0.2.tgz START
Extract /home/gowris/.embedmongo/linux/mongodb-linux-x86_64-3.0.2.tgz DONE
[mongod output]note: noprealloc may hurt performance in many applications
[mongod output] 2019-04-16T11:42:48.931+0530 I STORAGE  [DataFileSync] warning: --syncdelay 0 is not recommended and can have strange performance
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] MongoDB starting : pid=26917 port=27018 dbpath=/tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172 64-bit host=prinhyltphp0301
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] 
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] ** WARNING: soft rlimits too low. rlimits set to 63518 processes, 1048576 files. Number of processes should be at least 524288 : 0.5 times number of files.
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] db version v3.0.2
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] git version: 6201872043ecbbc0a4cc169b5482dcf385fc464f
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] build info: Linux build6.nj1.10gen.cc 2.6.32-431.3.1.el6.x86_64 #1 SMP Fri Jan 3 21:39:27 UTC 2014 x86_64 BOOST_LIB_VERSION=1_49
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] allocator: tcmalloc
[mongod output] 2019-04-16T11:42:49.046+0530 I CONTROL  [initandlisten] options: { net: { bindIp: "localhost", http: { enabled: false }, port: 27018 }, security: { authorization: "disabled" }, storage: { dbPath: "/tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172", journal: { enabled: false }, mmapv1: { preallocDataFiles: false, smallFiles: true }, syncPeriodSecs: 0.0 } }
[mongod output] 2019-04-16T11:42:49.046+0530 I INDEX    [initandlisten] allocating new ns file /tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172/local.ns, filling with zeroes...
[mongod output] 2019-04-16T11:42:49.291+0530 I STORAGE  [FileAllocator] allocating new datafile /tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172/local.0, filling with zeroes...
[mongod output] 2019-04-16T11:42:49.291+0530 I STORAGE  [FileAllocator] creating directory /tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172/_tmp
[mongod output] 2019-04-16T11:42:49.336+0530 I STORAGE  [FileAllocator] done allocating datafile /tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172/local.0, size: 16MB,  took 0.022 secs
[mongod output] 2019-04-16T11:42:49.340+0530 I NETWORK  [initandlisten] waiting for connections on port 27018
[mongod output] 2019-04-16T11:42:49.600+0530 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:44192 #1 (1 connection now open)
[mongod output] 2019-04-16T11:42:49.601+0530 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:44194 #2 (2 connections now open)
[mongod output] 2019-04-16T11:42:49.668+0530 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:44196 #3 (3 connections now open)
[mongod output] 2019-04-16T11:42:49.734+0530 I INDEX    [conn3] allocating new ns file /tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172/myMongoDbFake.ns, filling with zeroes...
[mongod output] 2019-04-16T11:42:49.992+0530 I STORAGE  [FileAllocator] allocating new datafile /tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172/myMongoDbFake.0, filling with zeroes...
[mongod output] 2019-04-16T11:42:50.015+0530 I STORAGE  [FileAllocator] done allocating datafile /tmp/embedmongo-db-18ee838f-587a-4739-8034-5d0670abb172/myMongoDbFake.0, size: 16MB,  took 0.022 secs
[mongod output] 2019-04-16T11:42:50.017+0530 I WRITE    [conn3] insert myMongoDbFake.customers query: { _id: ObjectId('5cb5726109dc006253036073'), name: "Roy", company: "Naver" } ninserted:1 keyUpdates:0 writeConflicts:0 numYields:0 locks:{ Global: { acquireCount: { w: 2 } }, MMAPV1Journal: { acquireCount: { w: 6 } }, Database: { acquireCount: { w: 1, W: 1 } }, Collection: { acquireCount: { W: 1 } }, Metadata: { acquireCount: { W: 4 } } } 283ms
[mongod output] 2019-04-16T11:42:50.017+0530 I COMMAND  [conn3] command myMongoDbFake.$cmd command: insert { insert: "customers", ordered: true, writeConcern: { w: "majority" }, documents: [ { _id: ObjectId('5cb5726109dc006253036073'), name: "Roy", company: "Naver" } ] } keyUpdates:0 writeConflicts:0 numYields:0 reslen:40 locks:{ Global: { acquireCount: { w: 2 } }, MMAPV1Journal: { acquireCount: { w: 6 } }, Database: { acquireCount: { w: 1, W: 1 } }, Collection: { acquireCount: { W: 1 } }, Metadata: { acquireCount: { W: 4 } } } 283ms
[mongod output] #####testFailure#####testConnection[:child:8](com.navercorp.pinpoint.plugin.mongodb.MongoDBIT_2_X_IT)#####java.lang.AssertionError#####Cannot find apiId of [com.mongodb.DBCollection.insert(com.mongodb.DBObject[])]#####com.navercorp.pinpoint.test.PluginVerifierExternalAdaptor,findApiId,PluginVerifierExternalAdaptor.java,803#####com.navercorp.pinpoint.test.PluginVerifierExternalAdaptor,findApiId,PluginVerifierExternalAdaptor.java,772#####com.navercorp.pinpoint.test.PluginVerifierExternalAdaptor,getApiId,PluginVerifierExternalAdaptor.java,299#####com.navercorp.pinpoint.test.PluginVerifierExternalAdaptor,resolveExpectedTrace,PluginVerifierExternalAdaptor.java,280#####com.navercorp.pinpoint.test.PluginVerifierExternalAdaptor,verifyTrace,PluginVerifierExternalAdaptor.java,248#####com.navercorp.pinpoint.plugin.mongodb.MongoDBITBase_2_X,insertData,MongoDBITBase_2_X.java,189#####com.navercorp.pinpoint.plugin.mongodb.MongoDBITBase_2_X,testConnection,MongoDBITBase_2_X.java,112#####sun.reflect.NativeMethodAccessorImpl,invoke0,NativeMethodAccessorImpl.java,-2#####sun.reflect.NativeMethodAccessorImpl,invoke,NativeMethodAccessorImpl.java,62#####sun.reflect.DelegatingMethodAccessorImpl,invoke,DelegatingMethodAccessorImpl.java,43#####java.lang.reflect.Method,invoke,Method.java,498#####org.junit.runners.model.FrameworkMethod$1,runReflectiveCall,FrameworkMethod.java,50#####org.junit.internal.runners.model.ReflectiveCallable,run,ReflectiveCallable.java,12#####org.junit.runners.model.FrameworkMethod,invokeExplosively,FrameworkMethod.java,47#####org.junit.internal.runners.statements.InvokeMethod,evaluate,InvokeMethod.java,17#####com.navercorp.pinpoint.test.plugin.ForkedPinpointPluginTestRunner$1,evaluate,ForkedPinpointPluginTestRunner.java,63#####org.junit.runners.ParentRunner,runLeaf,ParentRunner.java,325#####org.junit.runners.BlockJUnit4ClassRunner,runChild,BlockJUnit4ClassRunner.java,78#####org.junit.runners.BlockJUnit4ClassRunner,runChild,BlockJUnit4ClassRunner.java,57#####org.junit.runners.ParentRunner$3,run,ParentRunner.java,290#####org.junit.runners.ParentRunner$1,schedule,ParentRunner.java,71#####org.junit.runners.ParentRunner,runChildren,ParentRunner.java,288#####org.junit.runners.ParentRunner,access$000,ParentRunner.java,58#####org.junit.runners.ParentRunner$2,evaluate,ParentRunner.java,268#####org.junit.internal.runners.statements.RunBefores,evaluate,RunBefores.java,26#####org.junit.internal.runners.statements.RunAfters,evaluate,RunAfters.java,27#####org.junit.runners.ParentRunner,run,ParentRunner.java,363#####org.junit.runner.JUnitCore,run,JUnitCore.java,137#####com.navercorp.pinpoint.test.plugin.shared.SharedPinpointPluginTest$1,run,SharedPinpointPluginTest.java,163#####java.lang.Thread,run,Thread.java,748#####
#####testFinished#####testConnection[:child:8](com.navercorp.pinpoint.plugin.mongodb.MongoDBIT_2_X_IT)
#####testRunFinished
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.887 s - in com.navercorp.pinpoint.plugin.mongodb.MongoDBIT_2_X_IT