[INFO] Running org.onap.policy.drools.controller.internal.MavenDroolsControllerUpgradesTest
2019-11-22 07:53:14.872 [main] DEBUG o.a.m.i.embedder.MavenEmbedderUtils.buildComponentProvider(56) - Not in OSGi: using plexus based maven parser
2019-11-22 07:53:15.037 [main] DEBUG o.e.a.i.i.DefaultLocalRepositoryProvider.newLocalRepositoryManager(116) - Using manager EnhancedLocalRepositoryManager with priority 10.0 for /home/jenkins/.m2/repository
2019-11-22 07:53:15.039 [main] DEBUG o.e.a.i.i.DefaultLocalRepositoryProvider.newLocalRepositoryManager(116) - Using manager EnhancedLocalRepositoryManager with priority 10.0 for /home/jenkins/.m2/repository
2019-11-22 07:53:15.045 [main] DEBUG o.e.a.i.i.DefaultDependencyCollector.collectDependencies(286) - Dependency collection stats: {ConflictMarker.analyzeTime=0, ConflictMarker.markTime=0, ConflictMarker.nodeCount=1, ConflictIdSorter.graphTime=0, ConflictIdSorter.topsortTime=0, ConflictIdSorter.conflictIdCount=0, ConflictIdSorter.conflictIdCycleCount=0, ConflictResolver.totalTime=0, ConflictResolver.conflictItemCount=0, DefaultDependencyCollector.collectTime=0, DefaultDependencyCollector.transformTime=0}
2019-11-22 07:53:15.148 [main] DEBUG o.d.c.k.b.impl.KieRepositoryImpl.addKieModule(111) - KieModule was added: MemoryKieModule[releaseId=org.onap.policy.drools.test:rules:1.0.0]
2019-11-22 07:53:15.151 [main] DEBUG o.e.a.i.i.EnhancedLocalRepositoryManager.update(124) - Writing tracking file /home/jenkins/.m2/repository/org/onap/policy/drools/test/rules/1.0.0/_remote.repositories
2019-11-22 07:53:15.151 [main] DEBUG o.e.a.i.i.EnhancedLocalRepositoryManager.update(124) - Writing tracking file /home/jenkins/.m2/repository/org/onap/policy/drools/test/rules/1.0.0/_remote.repositories
2019-11-22 07:53:15.156 [main] DEBUG o.a.m.i.embedder.MavenEmbedderUtils.buildComponentProvider(56) - Not in OSGi: using plexus based maven parser
2019-11-22 07:53:15.263 [main] DEBUG o.e.a.i.i.DefaultLocalRepositoryProvider.newLocalRepositoryManager(116) - Using manager EnhancedLocalRepositoryManager with priority 10.0 for /home/jenkins/.m2/repository
2019-11-22 07:53:15.265 [main] DEBUG o.e.a.i.i.DefaultLocalRepositoryProvider.newLocalRepositoryManager(116) - Using manager EnhancedLocalRepositoryManager with priority 10.0 for /home/jenkins/.m2/repository
2019-11-22 07:53:15.271 [main] DEBUG o.e.a.i.i.DefaultDependencyCollector.collectDependencies(286) - Dependency collection stats: {ConflictMarker.analyzeTime=0, ConflictMarker.markTime=0, ConflictMarker.nodeCount=1, ConflictIdSorter.graphTime=0, ConflictIdSorter.topsortTime=0, ConflictIdSorter.conflictIdCount=0, ConflictIdSorter.conflictIdCycleCount=0, ConflictResolver.totalTime=0, ConflictResolver.conflictItemCount=0, DefaultDependencyCollector.collectTime=0, DefaultDependencyCollector.transformTime=0}
2019-11-22 07:53:15.358 [main] DEBUG o.d.c.k.b.impl.KieRepositoryImpl.addKieModule(111) - KieModule was added: MemoryKieModule[releaseId=org.onap.policy.drools.test:rules:2.0.0]
2019-11-22 07:53:15.361 [main] DEBUG o.e.a.i.i.EnhancedLocalRepositoryManager.update(124) - Writing tracking file /home/jenkins/.m2/repository/org/onap/policy/drools/test/rules/2.0.0/_remote.repositories
2019-11-22 07:53:15.361 [main] DEBUG o.e.a.i.i.EnhancedLocalRepositoryManager.update(124) - Writing tracking file /home/jenkins/.m2/repository/org/onap/policy/drools/test/rules/2.0.0/_remote.repositories
2019-11-22 07:53:15.368 [main] INFO o.o.p.d.c.i.MavenDroolsController.<init>(144) - drools-controller instantiation [org.onap.policy.drools.test:rules:1.0.0]
2019-11-22 07:53:15.388 [main] INFO o.o.p.d.c.i.MavenDroolsController.initCoders(259) - INIT-CODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:1.0.0:, alive=false, locked=, modelClassLoaderHash=0]
2019-11-22 07:53:15.388 [main] INFO o.o.p.d.c.i.MavenDroolsController.initCoders(259) - INIT-CODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:1.0.0:, alive=false, locked=, modelClassLoaderHash=0]
2019-11-22 07:53:15.388 [main] INFO o.o.p.d.c.i.MavenDroolsController.start(398) - START: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:1.0.0:, alive=false, locked=, modelClassLoaderHash=1305203549]
2019-11-22 07:53:15.398 [Session org.onap.policy.drools.test:rules:1.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.objectInserted(193) - RULE SETUP.1: inserting Version( version=1 )
2019-11-22 07:53:15.398 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(168) - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2019-11-22 07:53:15.399 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(169) - Controller: org.onap.policy.drools.test:rules:1.0.0
2019-11-22 07:53:15.399 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(171) - .....................................................................
2019-11-22 07:53:15.399 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(172) - KIE-BASES: [kbRules]
2019-11-22 07:53:15.399 [Session org.onap.policy.drools.test:rules:1.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.afterMatchFired(231) - RULE SETUP.1: afterMatchFired
2019-11-22 07:53:15.400 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(173) - KIE-PACKAGE-NAMES: [org.onap.policy.drools.core, rules]
2019-11-22 07:53:15.401 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(174) - KIE-RULE-NAMES: [run-drools-runnable, SETUP.1, VERSION.12]
2019-11-22 07:53:15.406 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(175) - FACTS: [Version( version=1 )]
2019-11-22 07:53:15.406 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(176) - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2019-11-22 07:53:15.407 [main] INFO o.o.p.d.c.i.MavenDroolsController.updateToVersion(188) - updating version -> [org.onap.policy.drools.test:rules:2.0.0]
2019-11-22 07:53:15.471 [main] WARN o.o.p.d.c.i.MavenDroolsController.updateToVersion(208) - MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1305203549] UPGRADE results: []
2019-11-22 07:53:15.472 [main] INFO o.o.p.d.c.i.MavenDroolsController.removeCoders(458) - MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1305203549]: REMOVE-CODERS
2019-11-22 07:53:15.472 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.matchCreated(216) - RULE SETUP.2: matchCreated
2019-11-22 07:53:15.472 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.beforeMatchFired(226) - RULE SETUP.2: beforeMatchFired
2019-11-22 07:53:15.472 [main] INFO o.o.p.d.c.i.MavenDroolsController.removeDecoders(346) - REMOVE-DECODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1305203549]
2019-11-22 07:53:15.473 [main] INFO o.o.p.d.c.i.MavenDroolsController.removeEncoders(364) - REMOVE-ENCODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1305203549]
2019-11-22 07:53:15.473 [main] INFO o.o.p.d.c.i.MavenDroolsController.initCoders(259) - INIT-CODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1305203549]
2019-11-22 07:53:15.473 [main] INFO o.o.p.d.c.i.MavenDroolsController.initCoders(259) - INIT-CODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1305203549]
2019-11-22 07:53:15.475 [main] INFO o.o.p.d.c.i.MavenDroolsController.updateToVersion(220) - UPDATE-TO-VERSION: completed MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1871827490]
2019-11-22 07:53:15.475 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.objectInserted(193) - RULE SETUP.2: inserting Version( version=2 )
2019-11-22 07:53:15.476 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.afterMatchFired(231) - RULE SETUP.2: afterMatchFired
2019-11-22 07:53:15.477 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.matchCreated(216) - RULE VERSION.2: matchCreated
2019-11-22 07:53:15.477 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.beforeMatchFired(226) - RULE VERSION.2: beforeMatchFired
2019-11-22 07:53:15.490 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.objectDeleted(205) - RULE VERSION.2: deleting Version( version=1 )
2019-11-22 07:53:15.491 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.objectInserted(193) - RULE VERSION.2: inserting Version( version=12 )
2019-11-22 07:53:15.492 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.afterMatchFired(231) - RULE VERSION.2: afterMatchFired
2019-11-22 07:53:15.492 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(168) - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2019-11-22 07:53:15.493 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.matchCreated(216) - RULE VERSION.12: matchCreated
2019-11-22 07:53:15.493 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.beforeMatchFired(226) - RULE VERSION.12: beforeMatchFired
2019-11-22 07:53:15.492 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(169) - Controller: org.onap.policy.drools.test:rules:2.0.0
2019-11-22 07:53:15.494 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(171) - .....................................................................
2019-11-22 07:53:15.495 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(172) - KIE-BASES: [kbRules]
2019-11-22 07:53:15.496 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(173) - KIE-PACKAGE-NAMES: [org.onap.policy.drools.core, rules]
2019-11-22 07:53:15.496 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(174) - KIE-RULE-NAMES: [run-drools-runnable, SETUP.1, VERSION.12, SETUP.2, VERSION.2]
2019-11-22 07:53:15.498 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(175) - FACTS: [Version( version=12 ), Version( version=2 )]
2019-11-22 07:53:15.498 [main] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.summary(176) - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2019-11-22 07:53:15.499 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.objectDeleted(205) - RULE VERSION.12: deleting Version( version=12 )
2019-11-22 07:53:15.499 [Session org.onap.policy.drools.test:rules:2.0.0:rules] INFO o.o.p.d.c.i.MavenDroolsControllerUpgradesTest.afterMatchFired(231) - RULE VERSION.12: afterMatchFired
2019-11-22 07:53:15.501 [main] INFO o.o.p.d.c.i.MavenDroolsController.halt(442) - MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1871827490]: HALT
2019-11-22 07:53:15.501 [main] INFO o.o.p.d.c.i.MavenDroolsController.stop(413) - STOP: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=true, locked=, modelClassLoaderHash=1871827490]
2019-11-22 07:53:15.502 [main] INFO o.o.p.d.c.i.MavenDroolsController.removeCoders(458) - MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=false, locked=, modelClassLoaderHash=1871827490]: REMOVE-CODERS
2019-11-22 07:53:15.502 [main] INFO o.o.p.d.c.i.MavenDroolsController.removeDecoders(346) - REMOVE-DECODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=false, locked=, modelClassLoaderHash=1871827490]
2019-11-22 07:53:15.503 [main] INFO o.o.p.d.c.i.MavenDroolsController.removeEncoders(364) - REMOVE-ENCODERS: MavenDroolsController [policyContainer=org.onap.policy.drools.test:rules:2.0.0:, alive=false, locked=, modelClassLoaderHash=1871827490]
[ERROR] Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.574 s <<< FAILURE! - in org.onap.policy.drools.controller.internal.MavenDroolsControllerUpgradesTest
[ERROR] upgrades(org.onap.policy.drools.controller.internal.MavenDroolsControllerUpgradesTest) Time elapsed: 0.148 s <<< FAILURE!
java.lang.AssertionError: expected:<2> but was:<1>
at org.onap.policy.drools.controller.internal.MavenDroolsControllerUpgradesTest.assertKie(MavenDroolsControllerUpgradesTest.java:183)
at org.onap.policy.drools.controller.internal.MavenDroolsControllerUpgradesTest.upgrades(MavenDroolsControllerUpgradesTest.java:154)

[INFO] Running org.onap.policy.drools.controller.DroolsControllerFactoryTest