No login after upgrade for 4.4.4 to 4.5.0

Hello I tried to upgrade openfire on OpenSUSE Tumbleweed from 4.4.4 to 4.5.0 but I can’t login anymore. The users are read from Samba4 LDAP. I had a look at the logfiles but did not see any error note.
Any hints are welcome

Regrads

Thorsten

Almost certainly a side-effect of the LDAP changes in 4.5.0 - but you’ll have to review the logs to give us a clue as to what might be going wrong.

Greg

I found one entry in the error.log:

2020.01.14 08:06:24 org.jivesoftware.openfire.handler.IQHandler - Interner Serverfehler
java.lang.NullPointerException: null
        at java.util.regex.Matcher.quoteReplacement(Matcher.java:701) ~[?:1.8.0_202]
        at org.jivesoftware.openfire.ldap.LdapVCardProvider$VCard.treeWalk(LdapVCardProvider.java:563) ~[xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.ldap.LdapVCardProvider$VCard.treeWalk(LdapVCardProvider.java:567) ~[xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.ldap.LdapVCardProvider$VCard.getVCard(LdapVCardProvider.java:544) ~[xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.ldap.LdapVCardProvider.loadVCard(LdapVCardProvider.java:245) ~[xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.vcard.VCardManager.getOrLoadVCard(VCardManager.java:247) ~[xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.vcard.VCardManager.getVCard(VCardManager.java:240) ~[xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.handler.IQvCardHandler.handleIQ(IQvCardHandler.java:136) ~[xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.handler.IQHandler.process(IQHandler.java:62) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.IQRouter.handle(IQRouter.java:369) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.IQRouter.route(IQRouter.java:112) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.spi.PacketRouterImpl.route(PacketRouterImpl.java:74) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.net.StanzaHandler.processIQ(StanzaHandler.java:360) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.net.ClientStanzaHandler.processIQ(ClientStanzaHandler.java:95) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.net.StanzaHandler.process(StanzaHandler.java:314) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.net.StanzaHandler.process(StanzaHandler.java:201) [xmppserver-4.4.4.jar:4.4.4]
        at org.jivesoftware.openfire.nio.ConnectionHandler.messageReceived(ConnectionHandler.java:183) [xmppserver-4.4.4.jar:4.4.4]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:1015) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:650) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:49) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:1128) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:122) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:650) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:49) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:1128) [mina-core-2.1.3.jar:?]
        at org.apache.mina.filter.codec.ProtocolCodecFilter$ProtocolDecoderOutputImpl.flush(ProtocolCodecFilter.java:413) [mina-core-2.1.3.jar:?]
        at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:257) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:650) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:49) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:1128) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:106) [mina-core-2.1.3.jar:?]
        at org.apache.mina.core.session.IoEvent.run(IoEvent.java:89) [mina-core-2.1.3.jar:?]
        at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTask(OrderedThreadPoolExecutor.java:766) [mina-core-2.1.3.jar:?]
        at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTasks(OrderedThreadPoolExecutor.java:758) [mina-core-2.1.3.jar:?]
        at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.run(OrderedThreadPoolExecutor.java:697) [mina-core-2.1.3.jar:?]
        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_202]

And three lines in warn.log:

2020.01.14 08:06:23 org.jivesoftware.openfire.net.SocketUtil - Unable to create a socket connection to XMPP domain 'kobra' using remote host: kobra:5269. Cause: kobra (a full stacktrace is logged on debug level)
2020.01.14 08:06:23 org.jivesoftware.openfire.net.SocketUtil - Unable to create a socket connection to XMPP domain 'kobra': Unable to connect to any of its remote hosts.
2020.01.14 08:06:23 org.jivesoftware.openfire.session.LocalOutgoingServerSession[Authenticate local domain: 'srv-kb-l03.local' to remote domain: 'kobra'] - Unable to authenticate: Fail to create new session.

Regards Thorsten

The stack trace that you provide clearly indicates a reference to xmppserver-4.4.4.jar, which is a jar file that should only exist in Openfire 4.4.4. In Openfire 4.5.0, this file should have a different version number.

Could you verify what is in your lib folder? Was there any issue reported during the upgrade process?

Upps, I’m sorry. I somehow copied outdated lines from the logs. I switched back to 4.4.4 to keep my colleagues calm…
Okay, now I stopped openfire, run a fresh rpm --upgrade, dropped the logs and started openfire again.

This is the current all.log until the end of session 2:

2020.01.14 10:08:59 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Registering shutdown hook (standalone mode)
[...]
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Remote Users Existence
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for LDAP UserDN
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.ldap.LdapManager - Created new LdapManager() instance, fields:
	 host: [srv-kb-dc2.local]
	 port: 389
	 usernamefield: sAMAccountName
	 usernameSuffix: 
	 baseDN: DC=mydom,DC=de
	 alternateBaseDN: null
	 nameField: org.jivesoftware.admin.LdapUserTester$PropertyMapping@14658f7
	 emailField: mail
	 adminDN: CN=Administrator,CN=Users,DC=mydom,DC=de
	 adminPassword: ************
	 searchFilter: (&(objectClass=organizationalPerson)(objectClass=user)(!(objectClass=computer)))
	 subTreeSearch:true
	 ldapDebugEnabled: false
	 sslEnabled: false
	 startTlsEnabled: true
	 initialContextFactory: com.sun.jndi.ldap.LdapCtxFactory
	 connectionPoolEnabled: true
	 autoFollowReferrals: false
	 autoFollowAliasReferrals: true
	 groupNameField: cn
	 groupMemberField: member
	 groupDescriptionField: description
	 posixMode: false
	 groupSearchFilter: (objectClass=group)
	 flattenNestedGroups: false
	 findUsersFromGroupsEnabled: false

2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Components Sessions
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Connection Managers Sessions
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Incoming Server Sessions
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Sessions by Hostname
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Validated Domains
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Client Session Info Cache
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.ldap.LdapVCardProvider - LdapVCardProvider: Found vcard mapping: '<![CDATA[
<vCard xmlns="vcard-temp">
  <N>
    <GIVEN>{cn}</GIVEN>
  </N> 
  <EMAIL>
    <INTERNET/> 
    <USERID>{mail}</USERID>
  </EMAIL> 
  <FN>{displayName}</FN> 
  <ADR>
[...]
  </ADR> 
  <TEL>
    <HOME/> 
    <VOICE/> 
    <NUMBER>{homePhone}</NUMBER>
  </TEL> 
  <TEL>
    <HOME/> 
    <CELL/> 
    <NUMBER>{mobile}</NUMBER>
  </TEL> 
  <TEL>
    <WORK/> 
    <VOICE/> 
    <NUMBER>{telephoneNumber}</NUMBER>
  </TEL> 
  <TEL>
    <WORK/> 
    <CELL/> 
    <NUMBER>{mobile}</NUMBER>
  </TEL> 
  <TEL>
    <WORK/> 
    <FAX/> 
    <NUMBER>{facsimileTelephoneNumber}</NUMBER>
  </TEL> 
  <TEL>
    <WORK/> 
    <PAGER/> 
    <NUMBER>{pager}</NUMBER>
  </TEL> 
  <TITLE>{title}</TITLE> 
  <ORG>
    <ORGUNIT>{department}</ORGUNIT>
  </ORG>
</vCard>]]>
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.ldap.LdapVCardProvider - LdapVCardProvider: attributes size==17
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Directed Presences
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created local-only cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for PEPServiceManager
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for File Transfer
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Published Items
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Disco Server Features
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Disco Server Items
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Components
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (identity store for connection type 'SOCKET_S2S') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (trust store for connection type 'SOCKET_S2S') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (identity store for connection type 'SOCKET_C2S') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (trust store for connection type 'SOCKET_C2S') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (identity store for connection type 'BOSH_C2S') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (trust store for connection type 'BOSH_C2S') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (identity store for connection type 'WEBADMIN') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (trust store for connection type 'WEBADMIN') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (identity store for connection type 'COMPONENT') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (trust store for connection type 'COMPONENT') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (identity store for connection type 'CONNECTION_MANAGER') Initializing store...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.keystore.CertificateStoreManager - (trust store for connection type 'CONNECTION_MANAGER') Initializing store...
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created local-only cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Entity Capabilities
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created local-only cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Entity Capabilities Users
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.cluster.ClusterMonitor - Cluster monitor has been initialized
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Routing table
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Audit Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Roster Manager
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Group
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Group Metadata Cache
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Private user data storage
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Presence manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Session Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Packet Router
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP IQ Router
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Message Router
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Presence Router
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Multicast Packet Router
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Packet Transporter
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Packet Delivery
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Transport handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Offline Message Strategy
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Offline Message Store
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: VCard Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Resource Binding handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Session Establishment handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Server Ping Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XEP-0191 Blocking Command handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Private Storage Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Registration Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Roster Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XEP-0202: Entity Time
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP vCard Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Server Version Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Last Activity Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Presence subscription handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Presence update handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Flexible Offline Message Retrieval Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Personal Eventing Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.pep.IQPEPHandler - Starting executor service...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Personal Eventing 'pubsub#owner' Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Multicast DNS Service
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Shared Groups Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Ad-Hoc Commands Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Blocking Communication Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: File Transfer Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: SOCKS5 file transfer proxy
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: rtpbridge
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Publish Subscribe Service
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.openfire.pubsub.PubSubModule - Publish-Subscribe Domain: pubsub.srv-kb-l03.local
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Disco Info Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XMPP Disco Items Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Update manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Flash CrossDomain Handler
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Internal Component Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Multi user chat manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.muc.MultiUserChatManager - MultiUserChatManager: Registering MUC service conference
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.component.InternalComponentManager - InternalComponentManager: Registering component for domain: conference
2020.01.14 10:09:00 DEBUG [Flash Cross Domain]: org.jivesoftware.openfire.FlashCrossDomainHandler - Flash cross domain is listening on null on port 5229
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.openfire.muc.spi.MultiUserChatServiceImpl - Mehrbenutzerchat Domain: conference.srv-kb-l03.local
2020.01.14 10:09:00 DEBUG [archive-service-worker-2]: org.jivesoftware.openfire.archive.Archiver - Running with max work queue size 500, max purge interval PT1S, grace period PT0.05S.
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.muc.spi.MultiUserChatServiceImpl - Ignoring stanza addressed at conference service: <iq type="get" id="927-1" from="component.srv-kb-l03.local" to="conference.srv-kb-l03.local"><query xmlns="jabber:iq:version"/></iq>
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.component.InternalComponentManager - InternalComponentManager: Component registered for domain: conference
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: XEP-0280: Message Carbons
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: ArchiveManager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Certificate Store Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Entity Capabilities Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Software Version Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Software Server Version Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Connection Manager
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Received a request to start listeners. Have plugins been loaded?
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Plugins not yet loaded. Waiting for plugins to be loaded...
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.util.CertificateManager - CertificateManager: No server CertificateIdentityMapping's found. Loading default mappings
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.util.CertificateManager - CertificateManager: No client CertificateIdentityMapping's found. Loading default mappings
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.XMPPServer - Starting module: Cluster monitor
2020.01.14 10:09:00 DEBUG [main]: org.jivesoftware.openfire.cluster.ClusterMonitor - Cluster monitor has been started
2020.01.14 10:09:00 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Openfire 4.5.0 [14.01.2020 10:09:00]
2020.01.14 10:09:00 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.container.PluginManager - Loading plugin 'admin'...
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: org.jivesoftware.util.CertificateManager - CertificateManager: Subject Alternative Name Mapping returned [srv-kb-l03.local]
2020.01.14 10:09:01 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Creating new SslContextFactory instance
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.update.UpdateManager#ENABLED
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.update.UpdateManager#NOTIFY_ADMINS
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.update.UpdateManager#LAST_UPDATE_CHECK
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.update.UpdateManager#UPDATE_FREQUENCY
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.update.UpdateManager#PROXY_HOST
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.update.UpdateManager#PROXY_PORT
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.streammanagement.StreamManager#LOCATION_ENABLED
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.streammanagement.StreamManager#MAX_SERVER_ENABLED
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.streammanagement.StreamManager#ACTIVE
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - supportDeepBinding=false
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@1c29e94
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - supportDeepBinding=false
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Adding binding with key=comp obj=Reference Class Name: javax.naming.Context
Type: parser
Content: org.eclipse.jetty.jndi.java.javaNameParser
 for context=null as comp: javax.naming.Reference:Reference Class Name: javax.naming.Context
Type: parser
Content: org.eclipse.jetty.jndi.java.javaNameParser

2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name="comp"
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Using threadlocal classloader
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - supportDeepBinding=false
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Made context comp for classloader: WebAppClassLoader=21347285@145bbd5
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - supportDeepBinding=false
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Adding binding with key=env obj=org.eclipse.jetty.jndi.NamingContext@12fdf5e[name=env,parent=org.eclipse.jetty.jndi.NamingContext@19bb812,bindings.size=0] for context=comp as env: org.eclipse.jetty.jndi.NamingContext:org.eclipse.jetty.jndi.NamingContext@12fdf5e[name=env,parent=org.eclipse.jetty.jndi.NamingContext@19bb812,bindings.size=0]
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.group.GroupManager#GROUP_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.keystore.CertificateStoreWatcher#ENABLED
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.user.UserManager#USER_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.user.UserManager#REMOTE_DISCO_INFO_TIMEOUT
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.user.UserManager#USER_PROPERTY_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.spi.LegacyConnectionAcceptor#STARTUP_RETRY_DELAY
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@cd2dfa
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name="comp/env"
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Trying thread context classloader
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name="env"
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@631623
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name=""
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Null or empty name, returning copy of this context
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - supportDeepBinding=false
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name="__"
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@1c65268
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name="org.eclipse.jetty.server.Server@15f342a"
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@1d9fee9
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name="org.eclipse.jetty.webapp.WebAppContext@5d4d34"
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@c8ee81
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@927147
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: jndi - Looking up name="__/UserTransaction"
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.lockout.LockOutManager#LOCKOUT_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.XMPPServerInfo#XMPP_DOMAIN
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.ldap.LdapManager#LDAP_PAGE_SIZE
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.ldap.LdapVCardProvider#STORE_AVATAR_IN_DB
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.disco.IQDiscoInfoHandler#ENABLED
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.auth.AuthFactory#PASSWORD_KEY
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.auth.AuthFactory#AUTH_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.auth.HybridAuthProvider#PRIMARY_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.auth.HybridAuthProvider#SECONDARY_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.auth.HybridAuthProvider#TERTIARY_PROVIDER
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.sasl.ScramSha1SaslServer#ITERATION_COUNT
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.sasl.ExternalClientSaslServer#PROPERTY_SASL_EXTERNAL_CLIENT_SUPPRESS_MATCHING_REALMNAME
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.sasl.AnonymousSaslServer#ENABLED
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.SessionManager#CONFLICT_LIMIT
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.cluster.ClusterMonitor#ENABLED
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: org.apache.jasper.servlet.JasperInitializer - Initializing Jasper for context [Openfire]
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: org.apache.jasper.servlet.JasperInitializer - Jsp precompilation detected
2020.01.14 10:09:01 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.admin.GroupBasedAdminProvider#GROUP_NAME
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: org.apache.tomcat.util.digester.Digester - addRuleSet() with no namespace URI
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: org.apache.tomcat.util.digester.Digester - addRuleSet() with no namespace URI
2020.01.14 10:09:01 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.admin.DefaultAdminProvider - DefaultAdminProvider: Convert XML to provider.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.compiler.JspRuntimeContext - Parent class loader is: [WebAppClassLoader=Openfire@145bbd5]
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.compiler.JspRuntimeContext - Compilation classpath initialized: /opt/openfire/plugins/admin/webapp/WEB-INF/classes/:/tmp/jetty-0.0.0.0-9090-webapp-_-any-4022846754695624043.dir/jsp:/opt/openfire/plugins/admin/webapp/WEB-INF/classes
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.servlet.JspServlet - Scratch dir for the JSP engine is: [/tmp/jetty-0.0.0.0-9090-webapp-_-any-4022846754695624043.dir/jsp]
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.servlet.JspServlet - IMPORTANT: Do not modify the generated servlets
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.admin.DefaultAdminProvider#ADMIN_JIDS
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.admin.AdminManager#ADMIN_PROVIDER
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.muc.spi.IQMuclumbusSearchHandler#PROPERTY_ENABLED
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.vcard.VCardManager#VCARD_PROVIDER
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Favicon Misses
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.util.cache.CacheFactory - Created cache [org.jivesoftware.util.cache.DefaultLocalCacheStrategy] for Favicon Hits
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: jndi - InitialContextFactory.getInitialContext()
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: jndi - Created initial context delegate for local namespace:org.eclipse.jetty.jndi.local.localContextRoot@1eb5a41
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: jndi - Looking up name="comp"
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: jndi - Trying thread context classloader
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.net.SASLAuthentication#SKIP_PEER_CERT_REVALIDATION_CLIENT
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'ANONYMOUS' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'PLAIN' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'DIGEST-MD5' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'CRAM-MD5' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'SCRAM-SHA-1' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'JIVE-SHAREDSECRET' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'GSSAPI' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.net.SASLAuthentication - Support added for the 'EXTERNAL' SASL mechanism.
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.spi.EncryptionArtifactFactory#TRUST_MANAGER_CLASS
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.spi.EncryptionArtifactFactory#SSLCONTEXT_PROTOCOL
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.container.PluginServlet#ALLOW_LOCAL_FILE_READING
2020.01.14 10:09:02 INFO  [main]: org.jivesoftware.openfire.XMPPServer - Accessing SystemProperty field org.jivesoftware.openfire.security.SecurityAuditManager#AUDIT_PROVIDER
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.container.AdminConsolePlugin - Admin-Konsole lauscht auf:
  http://srv-kb-l03.local:9090
  https://srv-kb-l03.local:9091
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.container.PluginManager - Initialized plugin 'admin'.
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.container.PluginManager - Successfully loaded plugin 'admin'.
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginManager - Loading plugin 'search'...
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Loading servlet 'org.jivesoftware.openfire.plugin.search.advance_002duser_002dsearch_jsp' of plugin 'search'...
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Initializing servlet 'org.jivesoftware.openfire.plugin.search.advance_002duser_002dsearch_jsp' of plugin 'search'...
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Registering servlet 'org.jivesoftware.openfire.plugin.search.advance_002duser_002dsearch_jsp' of plugin 'search' URL patterns.
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Servlet 'org.jivesoftware.openfire.plugin.search.advance_002duser_002dsearch_jsp' of plugin 'search' loaded successfully.
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Loading servlet 'org.jivesoftware.openfire.plugin.search.search_002dprops_002dedit_002dform_jsp' of plugin 'search'...
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Initializing servlet 'org.jivesoftware.openfire.plugin.search.search_002dprops_002dedit_002dform_jsp' of plugin 'search'...
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Registering servlet 'org.jivesoftware.openfire.plugin.search.search_002dprops_002dedit_002dform_jsp' of plugin 'search' URL patterns.
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginServlet - Servlet 'org.jivesoftware.openfire.plugin.search.search_002dprops_002dedit_002dform_jsp' of plugin 'search' loaded successfully.
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.component.InternalComponentManager - InternalComponentManager: Registering component for domain: search
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.component.InternalComponentManager - InternalComponentManager: Component registered for domain: search
2020.01.14 10:09:02 DEBUG [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginManager - Initialized plugin 'search'.
2020.01.14 10:09:02 INFO  [pool-7-thread-2]: org.jivesoftware.openfire.container.PluginManager - Successfully loaded plugin 'search'.
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.container.PluginMonitor - Finished processing all plugins.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Received plugin monitor event! Plugins should now be loaded.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Received a request to start listeners. Have plugins been loaded?
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Starting listeners...
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_c2s] - Starting...
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.MINAConnectionAcceptor[socket_c2s] - Throttling read buffer for connections to max=10485760 bytes
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_c2s] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'SOCKET_C2S' (port 5222) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_c2s-legacyMode] - Starting...
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 0, accepts self-signed: false, checks validity: true
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 0, accepts self-signed: false, checks validity: true
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.MINAConnectionAcceptor[socket_c2s_ssl] - Throttling read buffer for connections to max=10485760 bytes
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_c2s-legacyMode] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'SOCKET_C2S' (port 5223) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[bosh_c2s] - Not starting a (MINA-based) connection acceptor, as connections of type BOSH_C2S depend on another IO technology.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'BOSH_C2S' (port 7070) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[bosh_c2s-legacyMode] - Not starting a (MINA-based) connection acceptor, as connections of type BOSH_C2S depend on another IO technology.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'BOSH_C2S' (port 7443) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_s2s] - Starting...
2020.01.14 10:09:02 WARN  [pool-6-thread-1]: org.jivesoftware.openfire.spi.LegacyConnectionAcceptor - Configuration allows for up to 16 threads, although implementation is limited to exactly one.
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_s2s] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'SOCKET_S2S' (port 5269) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_s2s-legacyMode] - Starting...
2020.01.14 10:09:02 WARN  [pool-6-thread-1]: org.jivesoftware.openfire.spi.LegacyConnectionAcceptor - Configuration allows for up to 16 threads, although implementation is limited to exactly one.
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[socket_s2s-legacyMode] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'SOCKET_S2S' (port 5270) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[component] - Starting...
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[component] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'COMPONENT' (port 5275) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[component-legacyMode] - Starting...
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 148, accepts self-signed: false, checks validity: false
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 148, accepts self-signed: false, checks validity: false
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[component-legacyMode] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'COMPONENT' (port 5276) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[connection_manager] - Starting...
2020.01.14 10:09:02 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event SESSION_OPENED to session 2
Queue : [SESSION_OPENED, ]

2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[connection_manager] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'CONNECTION_MANAGER' (port 5262) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[connection_manager-legacyMode] - Starting...
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a SESSION_OPENED event for session 2
2020.01.14 10:09:02 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event MESSAGE_RECEIVED to session 2
Queue : [MESSAGE_RECEIVED, ]

2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Event SESSION_OPENED has been fired for session 2
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a MESSAGE_RECEIVED event for session 2
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.codec.ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 148, accepts self-signed: false, checks validity: false
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 148, accepts self-signed: false, checks validity: false
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[connection_manager-legacyMode] - Started.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'CONNECTION_MANAGER' (port 5263) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[webadmin] - Not starting a (MINA-based) connection acceptor, as connections of type WEBADMIN depend on another IO technology.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'WEBADMIN' (port 9090) listener.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionListener[webadmin-legacyMode] - Not starting a (MINA-based) connection acceptor, as connections of type WEBADMIN depend on another IO technology.
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started 'WEBADMIN' (port 9091) listener.
2020.01.14 10:09:02 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event MESSAGE_SENT to session 2
Queue : [MESSAGE_SENT, ]

2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Trying to find a user's RDN based on their username: 'thorsten'. Field: 'sAMAccountName', Base DN: 'DC=mydom,DC=de' ...
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Creating a DirContext in LdapManager.getContext() for baseDN 'DC=mydom,DC=de'...
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - connection pooling was requested but has been disabled because of StartTLS.
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Created hashtable with context values, attempting to create context...
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.util.CertificateManager - CertificateManager: Subject Alternative Name Mapping returned [srv-kb-l03.local]
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... StartTlsRequest
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Creating new SslContextFactory instance
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.http.HttpSessionManager - Starting instance
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.compiler.JspRuntimeContext - Parent class loader is: [WebAppClassLoader=14078551@d6d257]
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.compiler.JspRuntimeContext - Compilation classpath initialized: /tmp/jetty-0.0.0.0-7070-spank-_-any-6712908742499888837.dir/jsp:null
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.servlet.JspServlet - Scratch dir for the JSP engine is: [/tmp/jetty-0.0.0.0-7070-spank-_-any-6712908742499888837.dir/jsp]
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.apache.jasper.servlet.JspServlet - IMPORTANT: Do not modify the generated servlets
2020.01.14 10:09:02 INFO  [pool-6-thread-1]: org.jivesoftware.openfire.http.HttpBindManager - HTTP bind service started
2020.01.14 10:09:02 DEBUG [pool-6-thread-1]: org.jivesoftware.openfire.spi.ConnectionManagerImpl - Started HTTP client listener.
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... peer host: srv-kb-dc2.local, CipherSuite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... context created successfully, returning.
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Starting LDAP search for username 'thorsten'...
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... search finished for username 'thorsten'.
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Processing relative LDAP SearchResult: 'CN=Thorsten Marquardt,OU=KoBra: null:null:{samaccountname=sAMAccountName: thorsten}'
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Creating a DirContext in LdapManager.getContext() for baseDN 'DC=mydom,DC=de'...
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - connection pooling was requested but has been disabled because of StartTLS.
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Created hashtable with context values, attempting to create context...
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... StartTlsRequest
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... peer host: srv-kb-dc2.local, CipherSuite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
2020.01.14 10:09:02 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... context created successfully, returning.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Trying to find a user's RDN based on their username: 'tim'. Field: 'sAMAccountName', Base DN: 'DC=mydom,DC=de' ...
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Creating a DirContext in LdapManager.getContext() for baseDN 'DC=mydom,DC=de'...
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - connection pooling was requested but has been disabled because of StartTLS.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Created hashtable with context values, attempting to create context...
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... StartTlsRequest
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... peer host: srv-kb-dc2.local, CipherSuite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... context created successfully, returning.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Starting LDAP search for username 'tim'...
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... search finished for username 'tim'.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Processing relative LDAP SearchResult: 'CN=Tim Hassenewert,OU=KoBra: null:null:{samaccountname=sAMAccountName: tim}'
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Creating a DirContext in LdapManager.getContext() for baseDN 'DC=mydom,DC=de'...
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - connection pooling was requested but has been disabled because of StartTLS.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - Created hashtable with context values, attempting to create context...
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... StartTlsRequest
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... peer host: srv-kb-dc2.local, CipherSuite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.ldap.LdapManager - ... context created successfully, returning.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Event MESSAGE_RECEIVED has been fired for session 2
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event MESSAGE_SENT to session 2
Queue : [MESSAGE_SENT, , MESSAGE_SENT, ]

2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a MESSAGE_SENT event for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Event MESSAGE_SENT has been fired for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a MESSAGE_SENT event for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Event MESSAGE_SENT has been fired for session 2
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event MESSAGE_RECEIVED to session 2
Queue : [MESSAGE_RECEIVED, ]

2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a MESSAGE_RECEIVED event for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.codec.ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 0, accepts self-signed: false, checks validity: true
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Configured TrustManager class: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Attempting to instantiate 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager' using the three-argument constructor that is properietary to Openfire.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.keystore.OpenfireX509TrustManager - Constructed trust manager. Number of trusted issuers: 0, accepts self-signed: false, checks validity: true
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.jivesoftware.openfire.spi.EncryptionArtifactFactory - Successfully instantiated 'class org.jivesoftware.openfire.keystore.OpenfireX509TrustManager'.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.ssl.SslFilter - Adding the SSL Filter tls to the chain
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](no sslEngine) Initializing the SSL Handler
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](no sslEngine) SSL Handler Initialization done.
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...) : Starting the first handshake
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_WRAP state
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_UNWRAP state
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Writing Message : WriteRequest: HeapBuffer[pos=0 lim=50 cap=64: 3C 70 72 6F 63 65 65 64 20 78 6D 6C 6E 73 3D 22...]
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Event MESSAGE_RECEIVED has been fired for session 2
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Message received : HeapBuffer[pos=0 lim=242 cap=1024: 16 03 03 00 ED 01 00 00 E9 03 03 5E 1D 85 2F 28...]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) Processing the received message
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_UNWRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_TASK state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_WRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Writing Message : WriteRequest: HeapBuffer[pos=0 lim=1300 cap=2115: 16 03 03 05 0F 02 00 00 4D 03 03 5E 1D 85 2F C6...]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_UNWRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Processing the SSL Data 
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Message received : HeapBuffer[pos=0 lim=75 cap=1024: 16 03 03 00 46 10 00 00 42 41 04 E5 C0 DD E2 99...]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) Processing the received message
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_UNWRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_TASK state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_UNWRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Processing the SSL Data 
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Message received : HeapBuffer[pos=0 lim=91 cap=512: 14 03 03 00 01 01 16 03 03 00 50 C9 94 B3 91 92...]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) Processing the received message
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_UNWRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_WRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Writing Message : WriteRequest: HeapBuffer[pos=0 lim=6 cap=8: 14 03 03 00 01 01]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the NEED_WRAP state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](ssl...): Writing Message : WriteRequest: HeapBuffer[pos=0 lim=85 cap=132: 16 03 03 00 50 EF 5F 55 D6 0C CE E1 56 AD A2 48...]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](ssl...) processing the FINISHED state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](SSL) is now secured
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](SSL) processing the FINISHED state
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](SSL) is now secured
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](SSL): Processing the SSL Data 
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](SSL): Message received : HeapBuffer[pos=0 lim=261 cap=512: 17 03 03 01 00 C1 C1 8E 0F 9B 73 19 43 6D 67 1B...]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](SSL) Processing the received message
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](SSL): Processing the SSL Data 
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event MESSAGE_RECEIVED to session 2
Queue : [MESSAGE_RECEIVED, ]

2020.01.14 10:09:03 DEBUG [socket_c2s-thread-3]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a MESSAGE_RECEIVED event for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-3]: org.apache.mina.filter.codec.ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](SSL): Writing Message : WriteRequest: HeapBuffer[pos=0 lim=621 cap=1024: 3C 3F 78 6D 6C 20 76 65 72 73 69 6F 6E 3D 27 31...]
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-3]: org.apache.mina.core.filterchain.IoFilterEvent - Event MESSAGE_RECEIVED has been fired for session 2
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event MESSAGE_SENT to session 2
Queue : [MESSAGE_SENT, ]

2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a MESSAGE_SENT event for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-2]: org.apache.mina.core.filterchain.IoFilterEvent - Event MESSAGE_SENT has been fired for session 2
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](SSL): Message received : HeapBuffer[pos=0 lim=165 cap=512: 17 03 03 00 A0 09 8A B4 E8 08 8F 8A E1 49 A0 BB...]
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslHandler - Session Server[2](SSL) Processing the received message
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.ssl.SslFilter - Session Server[2](SSL): Processing the SSL Data 
2020.01.14 10:09:03 DEBUG [NioProcessor-3]: org.apache.mina.filter.executor.OrderedThreadPoolExecutor - Adding event MESSAGE_RECEIVED to session 2
Queue : [MESSAGE_RECEIVED, ]

2020.01.14 10:09:03 DEBUG [socket_c2s-thread-3]: org.apache.mina.core.filterchain.IoFilterEvent - Firing a MESSAGE_RECEIVED event for session 2
2020.01.14 10:09:03 DEBUG [socket_c2s-thread-3]: org.apache.mina.filter.codec.ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2