PEP support

2020-06-18 23:53:00 |

2020-06-18 23:53:00 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-18 23:53:00 | ------------------------------------

2020-06-18 23:53:00 | [Mutex] waiting for: [subscription:c9a2276e-aee3-43ff-9d9f-ed31761fc05d:success]

2020-06-18 23:53:00 | [Mutex] received everything.

2020-06-18 23:53:00 | [Mutex] isItemNotified: subscription:c9a2276e-aee3-43ff-9d9f-ed31761fc05d:success :: true

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=444 thread=1] Terminating XMPP Stream

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:00 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:00 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=446 thread=1] Terminating XMPP Stream

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:00 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:00 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:00 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2842] Proceeding TLS

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2842] Start handshake

2020-06-18 23:53:00 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=2846] Proceeding TLS

2020-06-18 23:53:00 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=2846] Start handshake

2020-06-18 23:53:01 | [Mutex] waiting for: [node:create]

2020-06-18 23:53:01 | [Mutex] received everything.

2020-06-18 23:53:01 | [Mutex] isItemNotified: node:create:success :: true

2020-06-18 23:53:01 | [Mutex] waiting for: [node:item:publish]

2020-06-18 23:53:01 | [Mutex] received everything.

2020-06-18 23:53:01 | [Mutex] isItemNotified: node:item:publish:success :: true

2020-06-18 23:53:01 | [Mutex] waiting for: [user:node:item:received]

2020-06-18 23:53:01 | [Mutex] waiting for: [user:node:item:received]

2020-06-18 23:53:01 | [Mutex] received everything.

2020-06-18 23:53:01 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-18 23:53:01 | [Mutex] received everything.

2020-06-18 23:53:01 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=1] Terminating XMPP Stream

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2842] Connector  state changed: disconnecting->disconnected

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=447 thread=2842] Stream terminated

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:01 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:01 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=1] Terminating XMPP Stream

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=448 thread=2846] Connector  state changed: disconnecting->disconnected

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:01 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:01 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:01 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=2853] Proceeding TLS

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=2853] Start handshake

2020-06-18 23:53:01 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=2857] Proceeding TLS

2020-06-18 23:53:01 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=2857] Start handshake

2020-06-18 23:53:02 | [Mutex] received everything.

2020-06-18 23:53:02 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-18 23:53:02 | [Mutex] waiting for: [user2:node:item:received]

2020-06-18 23:53:02 | [Mutex] received everything.

2020-06-18 23:53:02 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=2853] Connector  state changed: connected->disconnected

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=449 thread=2853] Stream terminated

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=2857] Connector  state changed: connected->disconnected

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=450 thread=2857] Stream terminated

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:02 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:03 |

2020-06-18 23:53:03 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-18 23:53:03 | ------------------------------------

2020-06-18 23:53:03 | [Mutex] waiting for: [subscription:a12c889e-f158-4830-88a2-ae0a4fbeadaa:success]

2020-06-18 23:53:03 | [Mutex] received everything.

2020-06-18 23:53:03 | [Mutex] isItemNotified: subscription:a12c889e-f158-4830-88a2-ae0a4fbeadaa:success :: true

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=452 thread=1] Terminating XMPP Stream

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:03 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Terminating XMPP Stream

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:03 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2891] Proceeding TLS

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2891] Start handshake

2020-06-18 23:53:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=2895] Proceeding TLS

2020-06-18 23:53:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=2895] Start handshake

2020-06-18 23:53:04 | [Mutex] waiting for: [node:create]

2020-06-18 23:53:04 | [Mutex] received everything.

2020-06-18 23:53:04 | [Mutex] isItemNotified: node:create:success :: true

2020-06-18 23:53:04 | [Mutex] waiting for: [node:item:publish]

2020-06-18 23:53:04 | [Mutex] waiting for: [node:item:publish]

2020-06-18 23:53:04 | [Mutex] received everything.

2020-06-18 23:53:04 | [Mutex] isItemNotified: node:item:publish:success :: true

2020-06-18 23:53:04 | [Mutex] waiting for: [user:node:item:received]

2020-06-18 23:53:04 | [Mutex] received everything.

2020-06-18 23:53:04 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-18 23:53:04 | [Mutex] received everything.

2020-06-18 23:53:04 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=1] Terminating XMPP Stream

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2891] Connector  state changed: disconnecting->disconnected

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=455 thread=2891] Stream terminated

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:04 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=1] Terminating XMPP Stream

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=2895] Connector  state changed: disconnecting->disconnected

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=456 thread=2895] Stream terminated

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:04 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=2903] Proceeding TLS

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=2903] Start handshake

2020-06-18 23:53:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=2907] Proceeding TLS

2020-06-18 23:53:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=2907] Start handshake

2020-06-18 23:53:05 | [Mutex] received everything.

2020-06-18 23:53:05 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-18 23:53:05 | [Mutex] waiting for: [user2:node:item:received]

2020-06-18 23:53:05 | [Mutex] received everything.

2020-06-18 23:53:05 | [Mutex] isItemNotified: user2:node:item:received :: true

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=2903] Connector  state changed: connected->disconnected

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=457 thread=2903] Stream terminated

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=2907] Connector  state changed: connected->disconnected

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=458 thread=2907] Stream terminated

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:05 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:06 |

2020-06-18 23:53:06 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-18 23:53:06 | ------------------------------------

2020-06-18 23:53:06 | [Mutex] waiting for: [subscription:c03ad109-e2a5-4a68-9e1d-74df123ce60d:success]

2020-06-18 23:53:06 | [Mutex] received everything.

2020-06-18 23:53:06 | [Mutex] isItemNotified: subscription:c03ad109-e2a5-4a68-9e1d-74df123ce60d:success :: true

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=460 thread=1] Terminating XMPP Stream

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:06 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:06 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=462 thread=1] Terminating XMPP Stream

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:06 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:06 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:06 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Connector  state changed: null->connecting

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

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2939] Proceeding TLS

2020-06-18 23:53:06 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2939] Start handshake

2020-06-18 23:53:07 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=2943] Proceeding TLS

2020-06-18 23:53:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=2943] Start handshake

2020-06-18 23:53:07 | [Mutex] waiting for: [node:create]

2020-06-18 23:53:07 | [Mutex] received everything.

2020-06-18 23:53:07 | [Mutex] isItemNotified: node:create:success :: true

2020-06-18 23:53:07 | [Mutex] waiting for: [node:item:publish]

2020-06-18 23:53:07 | [Mutex] received everything.

2020-06-18 23:53:07 | [Mutex] isItemNotified: node:item:publish:success :: true

2020-06-18 23:53:07 | [Mutex] waiting for: [user:node:item:received]

2020-06-18 23:53:07 | [Mutex] received everything.

2020-06-18 23:53:07 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-18 23:53:08 | [Mutex] isItemNotified: user2:node:item:received :: false

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=1] Terminating XMPP Stream

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2939] Connector  state changed: disconnecting->disconnected

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=463 thread=2939] Stream terminated

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:08 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:08 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] Connector  state changed: connected->disconnecting

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=1] Terminating XMPP Stream

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=464 thread=2943] Connector  state changed: disconnecting->disconnected

2020-06-18 23:53:08 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-18 23:53:08 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-18 23:53:08 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=2950] Proceeding TLS

2020-06-18 23:53:08 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=2950] Start handshake

2020-06-18 23:53:09 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=1] Connector  state changed: null->connecting

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=1] DNS entry stored in session object: localhost:5222

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=1] Preparing connection to [localhost:5222]

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=1] Connector  state changed: connecting->connected

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=1] Whitespace ping period is setted to nullms

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=2954] Proceeding TLS

2020-06-18 23:53:09 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=2954] Start handshake

2020-06-18 23:53:09 | [Mutex] received everything.

2020-06-18 23:53:09 | [Mutex] isItemNotified: user:node:item:received :: true

2020-06-18 23:53:10 | [Mutex] isItemNotified: user2:node:item:received :: false

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=2950] Connector  state changed: connected->disconnected

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=465 thread=2950] Stream terminated

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=2954] Connector  state changed: connected->disconnected

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=466 thread=2954] Stream terminated

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:10 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-18 23:53:11 |

2020-06-18 23:53:11 | null / [TestClass name=class tigase.tests.pubsub.TestPEP]

2020-06-18 23:53:11 | ------------------------------------

2020-06-18 23:53:11 | [Mutex] waiting for: [discovery:completed]

2020-06-18 23:53:11 | [Mutex] waiting for: [discovery:completed]

2020-06-18 23:53:11 | [Mutex] received everything.

2020-06-18 23:53:11 | [Mutex] isItemNotified: discovery:completed:success :: true

2020-06-18 23:53:11 | [Mutex] isItemNotified: discovery:identity:pubsub:pep :: true

2020-06-18 23:53:11 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#publish :: true

2020-06-18 23:53:11 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#subscribe :: true

2020-06-18 23:53:11 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#auto-subscribe :: true

2020-06-18 23:53:11 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#auto-create :: true

2020-06-18 23:53:11 | [Mutex] isItemNotified: discovery:feature:http://jabber.org/protocol/pubsub#access-presence :: true