Node and item manipulation using PubSub procotol - #4229

Test duration: 64.964s

Failed Configuration
tigase.tests.pubsub.TestPubSub
tearDownClass 30.014s Method arguments: org.testng.TestRunner@6885ed19
2020-08-03 00:00:39 | [Mutex] waiting for: [deleted:node:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe]

2020-08-03 00:01:09 | [Mutex] timeout. Not received [deleted:node:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe]

2020-08-03 00:01:09 | [Mutex] isItemNotified: deleted:node:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe :: false

java.lang.AssertionError: Removal of node Node 071deba7-ce78-4d09-a9ed-0fcc10984efe on localhost failed
org.testng.AssertJUnit.fail(AssertJUnit.java:59)
org.testng.AssertJUnit.assertTrue(AssertJUnit.java:24)
tigase.tests.utils.PubSubManager.deleteNode(PubSubManager.java:91)
tigase.tests.utils.PubSubManager.deleteNode(PubSubManager.java:67)
tigase.tests.utils.PubSubManager.lambda$scopeFinished$2(PubSubManager.java:128)
java.base/java.util.concurrent.CopyOnWriteArrayList.forEach(CopyOnWriteArrayList.java:804)
java.base/java.util.concurrent.CopyOnWriteArraySet.forEach(CopyOnWriteArraySet.java:425)
tigase.tests.utils.PubSubManager.scopeFinished(PubSubManager.java:126)
tigase.tests.utils.AbstractManager.scopeFinished(AbstractManager.java:36)
tigase.tests.AbstractTest.tearDownClass(AbstractTest.java:806)
jdk.internal.reflect.GeneratedMethodAccessor7.invoke(Unknown Source)
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
java.base/java.lang.reflect.Method.invoke(Method.java:566)
org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:124)
org.testng.internal.MethodInvocationHelper.invokeMethodConsideringTimeout(MethodInvocationHelper.java:59)
org.testng.internal.Invoker.invokeConfigurationMethod(Invoker.java:458)
org.testng.internal.Invoker.invokeConfigurations(Invoker.java:222)
org.testng.internal.Invoker.invokeConfigurations(Invoker.java:142)
org.testng.internal.TestMethodWorker.invokeAfterClassMethods(TestMethodWorker.java:214)
org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)
org.testng.TestRunner.privateRun(TestRunner.java:648)
org.testng.TestRunner.run(TestRunner.java:505)
org.testng.SuiteRunner.runTest(SuiteRunner.java:455)
org.testng.SuiteRunner.runSequentially(SuiteRunner.java:450)
org.testng.SuiteRunner.privateRun(SuiteRunner.java:415)
org.testng.SuiteRunner.run(SuiteRunner.java:364)
org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:84)
org.testng.TestNG.runSuitesSequentially(TestNG.java:1208)
org.testng.TestNG.runSuitesLocally(TestNG.java:1137)
org.testng.TestNG.runSuites(TestNG.java:1049)
org.testng.TestNG.run(TestNG.java:1017)
org.apache.maven.surefire.testng.TestNGExecutor.run(TestNGExecutor.java:283)
org.apache.maven.surefire.testng.TestNGXmlTestSuite.execute(TestNGXmlTestSuite.java:75)
org.apache.maven.surefire.testng.TestNGProvider.invoke(TestNGProvider.java:120)
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
Failed Tests
tigase.tests.pubsub.TestPubSub
publishItemsToNodes 30.007s
2020-08-03 00:00:09 |

2020-08-03 00:00:09 | null / [TestClass name=class tigase.tests.pubsub.TestPubSub]

2020-08-03 00:00:09 | ------------------------------------

2020-08-03 00:00:09 | [Mutex] waiting for: [published:item:item-7e4ba662-2704-4c5e-9dad-f8918f3a801f:admin@test-domain.com]

2020-08-03 00:00:39 | [Mutex] timeout. Not received [published:item:item-7e4ba662-2704-4c5e-9dad-f8918f3a801f:admin@test-domain.com]

2020-08-03 00:00:39 | [Mutex] isItemNotified: published:item:item-7e4ba662-2704-4c5e-9dad-f8918f3a801f:admin@test-domain.com :: false

java.lang.AssertionError:
org.testng.AssertJUnit.fail(AssertJUnit.java:59)
org.testng.AssertJUnit.assertTrue(AssertJUnit.java:24)
org.testng.AssertJUnit.assertTrue(AssertJUnit.java:33)
tigase.tests.pubsub.TestPubSub.publishItemToNode(TestPubSub.java:266)
tigase.tests.pubsub.TestPubSubAbstract.publishItemsToNodes(TestPubSubAbstract.java:185)
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
java.base/java.lang.reflect.Method.invoke(Method.java:566)
org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:124)
org.testng.internal.Invoker.invokeMethod(Invoker.java:583)
org.testng.internal.Invoker.invokeTestMethod(Invoker.java:719)
org.testng.internal.Invoker.invokeTestMethods(Invoker.java:989)
org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:125)
org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:109)
org.testng.TestRunner.privateRun(TestRunner.java:648)
org.testng.TestRunner.run(TestRunner.java:505)
org.testng.SuiteRunner.runTest(SuiteRunner.java:455)
org.testng.SuiteRunner.runSequentially(SuiteRunner.java:450)
org.testng.SuiteRunner.privateRun(SuiteRunner.java:415)
org.testng.SuiteRunner.run(SuiteRunner.java:364)
org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:84)
org.testng.TestNG.runSuitesSequentially(TestNG.java:1208)
org.testng.TestNG.runSuitesLocally(TestNG.java:1137)
org.testng.TestNG.runSuites(TestNG.java:1049)
org.testng.TestNG.run(TestNG.java:1017)
org.apache.maven.surefire.testng.TestNGExecutor.run(TestNGExecutor.java:283)
org.apache.maven.surefire.testng.TestNGXmlTestSuite.execute(TestNGXmlTestSuite.java:75)
org.apache.maven.surefire.testng.TestNGProvider.invoke(TestNGProvider.java:120)
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
Skipped Tests
tigase.tests.pubsub.TestPubSub
deleteNodes 0.000s Depends on method(s): tigase.tests.pubsub.TestPubSubAbstract.deleteSubnodes
deleteSubnodes 0.000s Depends on method(s): tigase.tests.pubsub.TestPubSubAbstract.unsubscribeNodes
retractItemsFromNodes 0.000s Depends on method(s): tigase.tests.pubsub.TestPubSubAbstract.retrieveItemsFromNodes
retrieveItemsFromNodes 0.000s Depends on method(s): tigase.tests.pubsub.TestPubSubAbstract.publishItemsToNodes
retrieveUserSubscriptions 0.000s Depends on method(s): tigase.tests.pubsub.TestPubSubAbstract.publishItemsToNodes
retrieveUserSubscriptionsWithRegex 0.000s Depends on method(s): tigase.tests.pubsub.TestPubSubAbstract.publishItemsToNodes
unsubscribeNodes 0.000s Depends on method(s): tigase.tests.pubsub.TestPubSubAbstract.retractItemsFromNodes
Passed Tests
tigase.tests.pubsub.TestPubSub
configureNodes 1.051s
2020-08-03 00:00:06 |

2020-08-03 00:00:06 | null / [TestClass name=class tigase.tests.pubsub.TestPubSub]

2020-08-03 00:00:06 | ------------------------------------

2020-08-03 00:00:06 | [Mutex] waiting for: [configured:node:node-071deba7-ce78-4d09-a9ed-0fcc10984efe:node-96c1fe30-f534-423a-8461-3a042aa9904a]

2020-08-03 00:00:06 | [Mutex] received everything.

2020-08-03 00:00:06 | [Mutex] isItemNotified: configured:node:node-071deba7-ce78-4d09-a9ed-0fcc10984efe:node-96c1fe30-f534-423a-8461-3a042aa9904a :: true

2020-08-03 00:00:07 | [Mutex] waiting for: [received:nodes:4a666d52-fdfe-412e-b896-14e5e25c00cd:localhost]

2020-08-03 00:00:07 | [Mutex] received everything.

2020-08-03 00:00:07 | [Mutex] isItemNotified: received:node:4a666d52-fdfe-412e-b896-14e5e25c00cd:localhost:node-071deba7-ce78-4d09-a9ed-0fcc10984efe:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe :: true

2020-08-03 00:00:07 | [Mutex] waiting for: [received:nodes:33642181-3f1e-41a8-83dd-dc528aa1905d:localhost]

2020-08-03 00:00:07 | [Mutex] received everything.

2020-08-03 00:00:07 | [Mutex] isItemNotified: received:node:33642181-3f1e-41a8-83dd-dc528aa1905d:localhost:node-071deba7-ce78-4d09-a9ed-0fcc10984efe:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe :: false

createNodes 1.067s
2020-08-03 00:00:04 |

2020-08-03 00:00:04 | null / [TestClass name=class tigase.tests.pubsub.TestPubSub]

2020-08-03 00:00:04 | ------------------------------------

2020-08-03 00:00:04 | [Mutex] waiting for: [created:node:node-071deba7-ce78-4d09-a9ed-0fcc10984efe:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe]

2020-08-03 00:00:04 | [Mutex] received everything.

2020-08-03 00:00:04 | [Mutex] isItemNotified: created:node:node-071deba7-ce78-4d09-a9ed-0fcc10984efe:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe :: true

2020-08-03 00:00:05 | [Mutex] waiting for: [received:nodes:f1f6f0a9-bbf1-41bf-9a9f-4854acdff3d8:localhost]

2020-08-03 00:00:05 | [Mutex] received everything.

2020-08-03 00:00:05 | [Mutex] isItemNotified: received:node:f1f6f0a9-bbf1-41bf-9a9f-4854acdff3d8:localhost:node-071deba7-ce78-4d09-a9ed-0fcc10984efe:Node 071deba7-ce78-4d09-a9ed-0fcc10984efe :: true

createSubnodes 1.023s
2020-08-03 00:00:05 |

2020-08-03 00:00:05 | null / [TestClass name=class tigase.tests.pubsub.TestPubSub]

2020-08-03 00:00:05 | ------------------------------------

2020-08-03 00:00:05 | [Mutex] waiting for: [created:node:node-96c1fe30-f534-423a-8461-3a042aa9904a:Node 96c1fe30-f534-423a-8461-3a042aa9904a]

2020-08-03 00:00:05 | [Mutex] received everything.

2020-08-03 00:00:05 | [Mutex] isItemNotified: created:node:node-96c1fe30-f534-423a-8461-3a042aa9904a:Node 96c1fe30-f534-423a-8461-3a042aa9904a :: true

2020-08-03 00:00:06 | [Mutex] waiting for: [received:nodes:c7be16da-bb16-4877-bded-8e695f9ca406:localhost]

2020-08-03 00:00:06 | [Mutex] received everything.

2020-08-03 00:00:06 | [Mutex] isItemNotified: received:node:c7be16da-bb16-4877-bded-8e695f9ca406:localhost:node-96c1fe30-f534-423a-8461-3a042aa9904a:Node 96c1fe30-f534-423a-8461-3a042aa9904a :: true

subscribeNodes 1.020s
2020-08-03 00:00:07 |

2020-08-03 00:00:07 | null / [TestClass name=class tigase.tests.pubsub.TestPubSub]

2020-08-03 00:00:07 | ------------------------------------

2020-08-03 00:00:07 | [Mutex] waiting for: [subscribed:nodes:0c71442d-3772-4141-a619-d9f02e8ed429:admin@test-domain.com]

2020-08-03 00:00:08 | [Mutex] received everything.

2020-08-03 00:00:08 | [Mutex] isItemNotified: subscribed:nodes:0c71442d-3772-4141-a619-d9f02e8ed429:admin@test-domain.com :: true

testSupportAdvertisement 0.011s
2020-08-03 00:00:04 |

2020-08-03 00:00:04 | null / [TestClass name=class tigase.tests.pubsub.TestPubSub]

2020-08-03 00:00:04 | ------------------------------------

2020-08-03 00:00:04 | [Mutex] waiting for: [discovery:completed]

2020-08-03 00:00:04 | [Mutex] waiting for: [discovery:completed]

2020-08-03 00:00:04 | [Mutex] waiting for: [discovery:completed]

2020-08-03 00:00:04 | [Mutex] received everything.

2020-08-03 00:00:04 | [Mutex] isItemNotified: discovery:completed:success :: true

2020-08-03 00:00:04 | [Mutex] isItemNotified: discovery:identity:pubsub:service :: true

2020-08-03 00:00:04 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#publish :: true

2020-08-03 00:00:04 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#subscribe :: true