Uploaded image for project: 'Openfire'
  1. Openfire
  2. OF-1884

StreamManagement failing when debug logging enabled

    XMLWordPrintable

    Details

    • Type: Improvement
    • Status: Resolved
    • Priority: Minor
    • Resolution: Fixed
    • Affects Version/s: 4.4.2
    • Fix Version/s: 4.5.0
    • Component/s: Core
    • Labels:
      None

      Description

      Introduced by commit 6855d9d461e76978e59f4b8f9ff17d5a97c96f71 / OF-1835. Whenever a detached session is being resumed, and debug logging is enabled, this stack trace is logged. It's caused by attempting to log the session instance (which only happens when the debug log level is enabled):

      2019.10.01 16:28:38 ERROR [socket_c2s-thread-3]: org.jivesoftware.openfire.session.LocalSession - Attempt to read connection of detached session with address guus@laptop-guus/Conversations.qpvf and streamID 6efxji7gj5: 
      java.lang.NullPointerException: null
              at org.jivesoftware.openfire.session.LocalSession.getConnection(LocalSession.java:206) [xmppserver-4.5.0-SNAPSHOT.jar:4.5.0-SNAPSHOT]
              at org.jivesoftware.openfire.session.LocalClientSession.toString(LocalClientSession.java:997) [xmppserver-4.5.0-SNAPSHOT.jar:4.5.0-SNAPSHOT]
              at org.apache.logging.log4j.message.ParameterFormatter.tryObjectToString(ParameterFormatter.java:607) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.message.ParameterFormatter.recursiveDeepToString(ParameterFormatter.java:458) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.message.ParameterFormatter.formatMessage2(ParameterFormatter.java:189) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.message.ReusableParameterizedMessage.formatTo(ReusableParameterizedMessage.java:329) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.core.impl.MutableLogEvent.setMessage(MutableLogEvent.java:213) [log4j-core-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.core.impl.ReusableLogEventFactory.createEvent(ReusableLogEventFactory.java:80) [log4j-core-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:404) [log4j-core-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.core.config.AwaitCompletionReliabilityStrategy.log(AwaitCompletionReliabilityStrategy.java:63) [log4j-core-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.core.Logger.logMessage(Logger.java:146) [log4j-core-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.spi.AbstractLogger.tryLogMessage(AbstractLogger.java:2170) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.spi.AbstractLogger.logMessageTrackRecursion(AbstractLogger.java:2125) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.spi.AbstractLogger.logMessageSafely(AbstractLogger.java:2108) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.spi.AbstractLogger.logMessage(AbstractLogger.java:2025) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.log4j.spi.AbstractLogger.logIfEnabled(AbstractLogger.java:1898) [log4j-api-2.11.2.jar:2.11.2]
              at org.apache.logging.slf4j.Log4jLogger.debug(Log4jLogger.java:129) [log4j-slf4j-impl-2.11.2.jar:2.11.2]
              at org.jivesoftware.openfire.streammanagement.StreamManager.startResume(StreamManager.java:296) [xmppserver-4.5.0-SNAPSHOT.jar:4.5.0-SNAPSHOT]
              at org.jivesoftware.openfire.streammanagement.StreamManager.process(StreamManager.java:135) [xmppserver-4.5.0-SNAPSHOT.jar:4.5.0-SNAPSHOT]
              at org.jivesoftware.openfire.net.StanzaHandler.process(StanzaHandler.java:198) [xmppserver-4.5.0-SNAPSHOT.jar:4.5.0-SNAPSHOT]
              at org.jivesoftware.openfire.nio.ConnectionHandler.messageReceived(ConnectionHandler.java:183) [xmppserver-4.5.0-SNAPSHOT.jar:4.5.0-SNAPSHOT]
              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_201]
      
      

        Attachments

          Activity

            People

            Assignee:
            guus Guus der Kinderen
            Reporter:
            guus Guus der Kinderen
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved: