Test Results Report - PEP support

Passed Tests
tigase.tests.pubsub.TestPEP
testAccessModel_PEP_default	2.161s	2020-06-24 22:50:33 \| 2020-06-24 22:50:33 \| null / [TestClass name=class tigase.tests.pubsub.TestPEP] 2020-06-24 22:50:33 \| ------------------------------------ 2020-06-24 22:50:33 \| [Mutex] waiting for: [subscription:25251fbe-d413-4796-9210-cfa7fb6e1a5a:success] 2020-06-24 22:50:33 \| [Mutex] received everything. 2020-06-24 22:50:33 \| [Mutex] isItemNotified: subscription:25251fbe-d413-4796-9210-cfa7fb6e1a5a:success :: true 2020-06-24 22:50:33 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=441 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:33 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=441 thread=1] Terminating XMPP Stream 2020-06-24 22:50:33 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:33 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:33 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:33 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=443 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:33 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=443 thread=1] Terminating XMPP Stream 2020-06-24 22:50:33 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:33 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:33 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:34 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=2824] Proceeding TLS 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=2824] Start handshake 2020-06-24 22:50:34 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=2828] Proceeding TLS 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=2828] Start handshake 2020-06-24 22:50:34 \| [Mutex] waiting for: [node:create] 2020-06-24 22:50:34 \| [Mutex] received everything. 2020-06-24 22:50:34 \| [Mutex] isItemNotified: node:create:success :: true 2020-06-24 22:50:34 \| [Mutex] waiting for: [node:item:publish] 2020-06-24 22:50:34 \| [Mutex] received everything. 2020-06-24 22:50:34 \| [Mutex] isItemNotified: node:item:publish:success :: true 2020-06-24 22:50:34 \| [Mutex] waiting for: [user:node:item:received] 2020-06-24 22:50:34 \| [Mutex] waiting for: [user:node:item:received] 2020-06-24 22:50:34 \| [Mutex] received everything. 2020-06-24 22:50:34 \| [Mutex] isItemNotified: user:node:item:received :: true 2020-06-24 22:50:34 \| [Mutex] received everything. 2020-06-24 22:50:34 \| [Mutex] isItemNotified: user2:node:item:received :: true 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Terminating XMPP Stream 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=2824] Connector state changed: disconnecting->disconnected 2020-06-24 22:50:34 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:34 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Terminating XMPP Stream 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:34 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=2828] Connector state changed: disconnecting->disconnected 2020-06-24 22:50:34 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:34 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:35 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Proceeding TLS 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Start handshake 2020-06-24 22:50:35 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Proceeding TLS 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Start handshake 2020-06-24 22:50:35 \| [Mutex] received everything. 2020-06-24 22:50:35 \| [Mutex] isItemNotified: user:node:item:received :: true 2020-06-24 22:50:35 \| [Mutex] waiting for: [user2:node:item:received] 2020-06-24 22:50:35 \| [Mutex] received everything. 2020-06-24 22:50:35 \| [Mutex] isItemNotified: user2:node:item:received :: true 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Connector state changed: connected->disconnected 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Stream terminated 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Connector state changed: connected->disconnected 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Stream terminated 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:35 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testAccessModel_Presence	2.017s	2020-06-24 22:50:36 \| 2020-06-24 22:50:36 \| null / [TestClass name=class tigase.tests.pubsub.TestPEP] 2020-06-24 22:50:36 \| ------------------------------------ 2020-06-24 22:50:36 \| [Mutex] waiting for: [subscription:2aea7a1d-b8b4-4ddd-8ffb-987fdee24f60:success] 2020-06-24 22:50:36 \| [Mutex] received everything. 2020-06-24 22:50:36 \| [Mutex] isItemNotified: subscription:2aea7a1d-b8b4-4ddd-8ffb-987fdee24f60:success :: true 2020-06-24 22:50:36 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:36 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Terminating XMPP Stream 2020-06-24 22:50:36 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:36 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:36 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:36 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=451 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:36 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=451 thread=1] Terminating XMPP Stream 2020-06-24 22:50:36 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:36 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:36 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:37 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Proceeding TLS 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Start handshake 2020-06-24 22:50:37 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Proceeding TLS 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Start handshake 2020-06-24 22:50:37 \| [Mutex] waiting for: [node:create] 2020-06-24 22:50:37 \| [Mutex] received everything. 2020-06-24 22:50:37 \| [Mutex] isItemNotified: node:create:success :: true 2020-06-24 22:50:37 \| [Mutex] waiting for: [node:item:publish] 2020-06-24 22:50:37 \| [Mutex] received everything. 2020-06-24 22:50:37 \| [Mutex] isItemNotified: node:item:publish:success :: true 2020-06-24 22:50:37 \| [Mutex] waiting for: [user:node:item:received] 2020-06-24 22:50:37 \| [Mutex] received everything. 2020-06-24 22:50:37 \| [Mutex] isItemNotified: user:node:item:received :: true 2020-06-24 22:50:37 \| [Mutex] waiting for: [user2:node:item:received] 2020-06-24 22:50:37 \| [Mutex] received everything. 2020-06-24 22:50:37 \| [Mutex] isItemNotified: user2:node:item:received :: true 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Terminating XMPP Stream 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Connector state changed: disconnecting->disconnected 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Stream terminated 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:37 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:37 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Terminating XMPP Stream 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Connector state changed: disconnecting->disconnected 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Stream terminated 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:37 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:37 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:37 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:38 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Proceeding TLS 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Start handshake 2020-06-24 22:50:38 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Proceeding TLS 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Start handshake 2020-06-24 22:50:38 \| [Mutex] received everything. 2020-06-24 22:50:38 \| [Mutex] isItemNotified: user:node:item:received :: true 2020-06-24 22:50:38 \| [Mutex] waiting for: [user2:node:item:received] 2020-06-24 22:50:38 \| [Mutex] received everything. 2020-06-24 22:50:38 \| [Mutex] isItemNotified: user2:node:item:received :: true 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Connector state changed: connected->disconnected 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Stream terminated 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Connector state changed: connected->disconnected 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Stream terminated 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:38 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testAccessModel_WhiteList	4.228s	2020-06-24 22:50:39 \| 2020-06-24 22:50:39 \| null / [TestClass name=class tigase.tests.pubsub.TestPEP] 2020-06-24 22:50:39 \| ------------------------------------ 2020-06-24 22:50:39 \| [Mutex] waiting for: [subscription:341cc5d9-7b58-4126-8ca9-0f37dc1cb0a8:success] 2020-06-24 22:50:39 \| [Mutex] received everything. 2020-06-24 22:50:39 \| [Mutex] isItemNotified: subscription:341cc5d9-7b58-4126-8ca9-0f37dc1cb0a8:success :: true 2020-06-24 22:50:39 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:39 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Terminating XMPP Stream 2020-06-24 22:50:39 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:39 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:39 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:39 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=459 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:39 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=459 thread=1] Terminating XMPP Stream 2020-06-24 22:50:39 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:39 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:39 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:40 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=2920] Proceeding TLS 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=2920] Start handshake 2020-06-24 22:50:40 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=2924] Proceeding TLS 2020-06-24 22:50:40 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=2924] Start handshake 2020-06-24 22:50:40 \| [Mutex] waiting for: [node:create] 2020-06-24 22:50:40 \| [Mutex] received everything. 2020-06-24 22:50:40 \| [Mutex] isItemNotified: node:create:success :: true 2020-06-24 22:50:40 \| [Mutex] waiting for: [node:item:publish] 2020-06-24 22:50:40 \| [Mutex] received everything. 2020-06-24 22:50:40 \| [Mutex] isItemNotified: node:item:publish:success :: true 2020-06-24 22:50:40 \| [Mutex] waiting for: [user:node:item:received] 2020-06-24 22:50:40 \| [Mutex] received everything. 2020-06-24 22:50:40 \| [Mutex] isItemNotified: user:node:item:received :: true 2020-06-24 22:50:41 \| [Mutex] isItemNotified: user2:node:item:received :: false 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Terminating XMPP Stream 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=2920] Connector state changed: disconnecting->disconnected 2020-06-24 22:50:41 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:41 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Connector state changed: connected->disconnecting 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Terminating XMPP Stream 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:41 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=2924] Connector state changed: disconnecting->disconnected 2020-06-24 22:50:41 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-06-24 22:50:41 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-06-24 22:50:42 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Proceeding TLS 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Start handshake 2020-06-24 22:50:42 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Connector state changed: null->connecting 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] DNS entry stored in session object: localhost:5222 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Preparing connection to [localhost:5222] 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Connector state changed: connecting->connected 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Whitespace ping period is setted to nullms 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Proceeding TLS 2020-06-24 22:50:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Start handshake 2020-06-24 22:50:42 \| [Mutex] received everything. 2020-06-24 22:50:42 \| [Mutex] isItemNotified: user:node:item:received :: true 2020-06-24 22:50:43 \| [Mutex] isItemNotified: user2:node:item:received :: false 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Connector state changed: connected->disconnected 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Stream terminated 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Connector state changed: connected->disconnected 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Stream terminated 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-06-24 22:50:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testSupportAdvertisement	0.007s	2020-06-24 22:50:44 \| 2020-06-24 22:50:44 \| null / [TestClass name=class tigase.tests.pubsub.TestPEP] 2020-06-24 22:50:44 \| ------------------------------------ 2020-06-24 22:50:44 \| [Mutex] waiting for: [discovery:completed] 2020-06-24 22:50:44 \| [Mutex] waiting for: [discovery:completed] 2020-06-24 22:50:44 \| [Mutex] waiting for: [discovery:completed] 2020-06-24 22:50:44 \| [Mutex] waiting for: [discovery:completed] 2020-06-24 22:50:44 \| [Mutex] received everything. 2020-06-24 22:50:44 \| [Mutex] isItemNotified: discovery:completed:success :: true 2020-06-24 22:50:44 \| [Mutex] isItemNotified: discovery:identity:pubsub:pep :: true 2020-06-24 22:50:44 \| [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#publish :: true 2020-06-24 22:50:44 \| [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#subscribe :: true 2020-06-24 22:50:44 \| [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#auto-subscribe :: true 2020-06-24 22:50:44 \| [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#auto-create :: true 2020-06-24 22:50:44 \| [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#access-presence :: true

tigase.tests.pubsub.TestPEP

testAccessModel_PEP_default

2.161s

2020-06-24 22:50:33 |

2020-06-24 22:50:33 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-24 22:50:33 | ------------------------------------

2020-06-24 22:50:33 | [Mutex] waiting for: [subscription:25251fbe-d413-4796-9210-cfa7fb6e1a5a:success]

2020-06-24 22:50:33 | [Mutex] received everything.

2020-06-24 22:50:33 | [Mutex] isItemNotified: subscription:25251fbe-d413-4796-9210-cfa7fb6e1a5a:success :: true

2020-06-24 22:50:33 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=441 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:33 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=441 thread=1] Terminating XMPP Stream

2020-06-24 22:50:33 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:33 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:33 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:33 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=443 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:33 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=443 thread=1] Terminating XMPP Stream

2020-06-24 22:50:33 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:33 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:33 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:34 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=2824] Proceeding TLS

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=2824] Start handshake

2020-06-24 22:50:34 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=2828] Proceeding TLS

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=2828] Start handshake

2020-06-24 22:50:34 | [Mutex] waiting for: [node:create]

2020-06-24 22:50:34 | [Mutex] received everything.

2020-06-24 22:50:34 | [Mutex] isItemNotified: node:create:success :: true

2020-06-24 22:50:34 | [Mutex] waiting for: [node:item:publish]

2020-06-24 22:50:34 | [Mutex] received everything.

2020-06-24 22:50:34 | [Mutex] isItemNotified: node:item:publish:success :: true

2020-06-24 22:50:34 | [Mutex] waiting for: [user:node:item:received]

2020-06-24 22:50:34 | [Mutex] waiting for: [user:node:item:received]

2020-06-24 22:50:34 | [Mutex] received everything.

2020-06-24 22:50:34 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-24 22:50:34 | [Mutex] received everything.

2020-06-24 22:50:34 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Terminating XMPP Stream

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=2824] Connector state changed: disconnecting->disconnected

2020-06-24 22:50:34 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:34 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=1] Terminating XMPP Stream

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:34 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=445 thread=2828] Connector state changed: disconnecting->disconnected

2020-06-24 22:50:34 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:34 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:35 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Proceeding TLS

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Start handshake

2020-06-24 22:50:35 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Proceeding TLS

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Start handshake

2020-06-24 22:50:35 | [Mutex] received everything.

2020-06-24 22:50:35 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-24 22:50:35 | [Mutex] waiting for: [user2:node:item:received]

2020-06-24 22:50:35 | [Mutex] received everything.

2020-06-24 22:50:35 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Connector state changed: connected->disconnected

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=2834] Stream terminated

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Connector state changed: connected->disconnected

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2838] Stream terminated

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:35 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testAccessModel_Presence

2.017s

2020-06-24 22:50:36 |

2020-06-24 22:50:36 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-24 22:50:36 | ------------------------------------

2020-06-24 22:50:36 | [Mutex] waiting for: [subscription:2aea7a1d-b8b4-4ddd-8ffb-987fdee24f60:success]

2020-06-24 22:50:36 | [Mutex] received everything.

2020-06-24 22:50:36 | [Mutex] isItemNotified: subscription:2aea7a1d-b8b4-4ddd-8ffb-987fdee24f60:success :: true

2020-06-24 22:50:36 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:36 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Terminating XMPP Stream

2020-06-24 22:50:36 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:36 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:36 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:36 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=451 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:36 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=451 thread=1] Terminating XMPP Stream

2020-06-24 22:50:36 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:36 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:36 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Proceeding TLS

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Start handshake

2020-06-24 22:50:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Proceeding TLS

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Start handshake

2020-06-24 22:50:37 | [Mutex] waiting for: [node:create]

2020-06-24 22:50:37 | [Mutex] received everything.

2020-06-24 22:50:37 | [Mutex] isItemNotified: node:create:success :: true

2020-06-24 22:50:37 | [Mutex] waiting for: [node:item:publish]

2020-06-24 22:50:37 | [Mutex] received everything.

2020-06-24 22:50:37 | [Mutex] isItemNotified: node:item:publish:success :: true

2020-06-24 22:50:37 | [Mutex] waiting for: [user:node:item:received]

2020-06-24 22:50:37 | [Mutex] received everything.

2020-06-24 22:50:37 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-24 22:50:37 | [Mutex] waiting for: [user2:node:item:received]

2020-06-24 22:50:37 | [Mutex] received everything.

2020-06-24 22:50:37 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Terminating XMPP Stream

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Connector state changed: disconnecting->disconnected

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=2872] Stream terminated

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Terminating XMPP Stream

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Connector state changed: disconnecting->disconnected

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2876] Stream terminated

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Proceeding TLS

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Start handshake

2020-06-24 22:50:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Proceeding TLS

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Start handshake

2020-06-24 22:50:38 | [Mutex] received everything.

2020-06-24 22:50:38 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-24 22:50:38 | [Mutex] waiting for: [user2:node:item:received]

2020-06-24 22:50:38 | [Mutex] received everything.

2020-06-24 22:50:38 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Connector state changed: connected->disconnected

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2884] Stream terminated

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Connector state changed: connected->disconnected

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2888] Stream terminated

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:38 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testAccessModel_WhiteList

4.228s

2020-06-24 22:50:39 |

2020-06-24 22:50:39 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-24 22:50:39 | ------------------------------------

2020-06-24 22:50:39 | [Mutex] waiting for: [subscription:341cc5d9-7b58-4126-8ca9-0f37dc1cb0a8:success]

2020-06-24 22:50:39 | [Mutex] received everything.

2020-06-24 22:50:39 | [Mutex] isItemNotified: subscription:341cc5d9-7b58-4126-8ca9-0f37dc1cb0a8:success :: true

2020-06-24 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Terminating XMPP Stream

2020-06-24 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=459 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=459 thread=1] Terminating XMPP Stream

2020-06-24 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:40 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=2920] Proceeding TLS

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=2920] Start handshake

2020-06-24 22:50:40 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=2924] Proceeding TLS

2020-06-24 22:50:40 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=2924] Start handshake

2020-06-24 22:50:40 | [Mutex] waiting for: [node:create]

2020-06-24 22:50:40 | [Mutex] received everything.

2020-06-24 22:50:40 | [Mutex] isItemNotified: node:create:success :: true

2020-06-24 22:50:40 | [Mutex] waiting for: [node:item:publish]

2020-06-24 22:50:40 | [Mutex] received everything.

2020-06-24 22:50:40 | [Mutex] isItemNotified: node:item:publish:success :: true

2020-06-24 22:50:40 | [Mutex] waiting for: [user:node:item:received]

2020-06-24 22:50:40 | [Mutex] received everything.

2020-06-24 22:50:40 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-24 22:50:41 | [Mutex] isItemNotified: user2:node:item:received :: false

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Terminating XMPP Stream

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=2920] Connector state changed: disconnecting->disconnected

2020-06-24 22:50:41 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:41 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Connector state changed: connected->disconnecting

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=1] Terminating XMPP Stream

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=461 thread=2924] Connector state changed: disconnecting->disconnected

2020-06-24 22:50:41 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-24 22:50:41 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-24 22:50:42 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Proceeding TLS

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Start handshake

2020-06-24 22:50:42 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Connector state changed: null->connecting

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] DNS entry stored in session object: localhost:5222

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Preparing connection to [localhost:5222]

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Connector state changed: connecting->connected

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Whitespace ping period is setted to nullms

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Proceeding TLS

2020-06-24 22:50:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Start handshake

2020-06-24 22:50:42 | [Mutex] received everything.

2020-06-24 22:50:42 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-24 22:50:43 | [Mutex] isItemNotified: user2:node:item:received :: false

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Connector state changed: connected->disconnected

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=2930] Stream terminated

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Connector state changed: connected->disconnected

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2934] Stream terminated

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-24 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testSupportAdvertisement

0.007s

2020-06-24 22:50:44 |

2020-06-24 22:50:44 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-24 22:50:44 | ------------------------------------

2020-06-24 22:50:44 | [Mutex] waiting for: [discovery:completed]

2020-06-24 22:50:44 | [Mutex] waiting for: [discovery:completed]

2020-06-24 22:50:44 | [Mutex] waiting for: [discovery:completed]

2020-06-24 22:50:44 | [Mutex] waiting for: [discovery:completed]

2020-06-24 22:50:44 | [Mutex] received everything.

2020-06-24 22:50:44 | [Mutex] isItemNotified: discovery:completed:success :: true

2020-06-24 22:50:44 | [Mutex] isItemNotified: discovery:identity:pubsub:pep :: true

2020-06-24 22:50:44 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#publish :: true

2020-06-24 22:50:44 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#subscribe :: true

2020-06-24 22:50:44 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#auto-subscribe :: true

2020-06-24 22:50:44 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#auto-create :: true

2020-06-24 22:50:44 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#access-presence :: true