Messages from multiple senders interleaved on one line

Hello all, I'm new to logback, and I'm trying out a configuration that is, more or less, a word-for-word transcription of an example configuration from the documentation; you can view the configuration here: http://logback.pastebin.com/m1a6f2875 I'm having problems where I'm getting interleaved messages on a single line. I don't believe this is expected behavior, and it certainly makes it harder to understand what's going on. I've tried searching the mailing list and bug list to no avail. Here's an example from one log file: 2009-09-30 15:45:31,649 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:45:33,2302009-09-30 15:45:33,230 [ [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [ INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:34,230 [main] INFO Eventweb - All stopped and here's an example from another log file: 2009-09-30 15:47:10,061 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21]Thread-17] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,378 [Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:12,378 [main] INFO Eventweb - All stopped Can anyone tell me why I'm getting interleaved messages, and what I might be able to do to prevent this from happening? Thanks, - Michael

Hello Michael, It's either a very serious bug in logback, or there is something else going on, such as another process writing to the same file. Could you please enter a bug report describing the problem? Mark the bug as critical. Is the duplication occurring in ConsoleAppener or in RollingFileAppender? Which version of logback is this? TIA, Michael Olson wrote:
Hello all,
I'm new to logback, and I'm trying out a configuration that is, more or less, a word-for-word transcription of an example configuration from the documentation; you can view the configuration here: http://logback.pastebin.com/m1a6f2875
I'm having problems where I'm getting interleaved messages on a single line. I don't believe this is expected behavior, and it certainly makes it harder to understand what's going on. I've tried searching the mailing list and bug list to no avail. Here's an example from one log file: 2009-09-30 15:45:31,649 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:45:33,2302009-09-30 15:45:33,230 [ [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [ INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:34,230 [main] INFO Eventweb - All stopped
and here's an example from another log file: 2009-09-30 15:47:10,061 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21]Thread-17] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,378 [Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:12,378 [main] INFO Eventweb - All stopped
Can anyone tell me why I'm getting interleaved messages, and what I might be able to do to prevent this from happening?
Thanks, - Michael
------------------------------------------------------------------------
_______________________________________________ Logback-user mailing list Logback-user@qos.ch http://qos.ch/mailman/listinfo/logback-user
-- Ceki Gülcü Logback: The reliable, generic, fast and flexible logging framework for Java. http://logback.qos.ch

This kind of problem could happen if you have multiple web application loading the same logback library and obviously the same configuration file pointing to the same file. Both web applications would try to log to the same file at the same moment, which would cause messages to be append to each other. I think I got this problem, solved by either placing the logback libraries in each application (copying), using a different config file or by either setting up a context selector variable using JNDI, cf logback manual on the context selection. -----Message d'origine----- De : logback-user-bounces@qos.ch [mailto:logback-user-bounces@qos.ch] De la part de Ceki Gulcu Envoyé : jeudi 1 octobre 2009 11:15 À : logback users list Objet : Re: [logback-user] Messages from multiple senders interleaved on one line Hello Michael, It's either a very serious bug in logback, or there is something else going on, such as another process writing to the same file. Could you please enter a bug report describing the problem? Mark the bug as critical. Is the duplication occurring in ConsoleAppener or in RollingFileAppender? Which version of logback is this? TIA, Michael Olson wrote:
Hello all,
I'm new to logback, and I'm trying out a configuration that is, more or less, a word-for-word transcription of an example configuration from the documentation; you can view the configuration here: http://logback.pastebin.com/m1a6f2875
I'm having problems where I'm getting interleaved messages on a single line. I don't believe this is expected behavior, and it certainly makes it harder to understand what's going on. I've tried searching the mailing list and bug list to no avail. Here's an example from one log file: 2009-09-30 15:45:31,649 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:45:33,2302009-09-30 15:45:33,230 [ [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [ INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:34,230 [main] INFO Eventweb - All stopped
and here's an example from another log file: 2009-09-30 15:47:10,061 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21]Thread-17] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,378 [Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:12,378 [main] INFO Eventweb - All stopped
Can anyone tell me why I'm getting interleaved messages, and what I might be able to do to prevent this from happening?
Thanks, - Michael
------------------------------------------------------------------------
_______________________________________________ Logback-user mailing list Logback-user@qos.ch http://qos.ch/mailman/listinfo/logback-user
-- Ceki Gülcü Logback: The reliable, generic, fast and flexible logging framework for Java. http://logback.qos.ch _______________________________________________ Logback-user mailing list Logback-user@qos.ch http://qos.ch/mailman/listinfo/logback-user

The bug has been submitted here: http://jira.qos.ch/browse/LBCLASSIC-158 On Thu, Oct 1, 2009 at 5:21 AM, Johan Bos <johan.bos@c6.eu> wrote:
This kind of problem could happen if you have multiple web application loading the same logback library and obviously the same configuration file pointing to the same file.
Both web applications would try to log to the same file at the same moment, which would cause messages to be append to each other.
I think I got this problem, solved by either placing the logback libraries in each application (copying), using a different config file or by either setting up a context selector variable using JNDI, cf logback manual on the context selection.
-----Message d'origine----- De : logback-user-bounces@qos.ch [mailto:logback-user-bounces@qos.ch] De la part de Ceki Gulcu Envoyé : jeudi 1 octobre 2009 11:15 À : logback users list Objet : Re: [logback-user] Messages from multiple senders interleaved on one line
Hello Michael,
It's either a very serious bug in logback, or there is something else going on, such as another process writing to the same file. Could you please enter a bug report describing the problem? Mark the bug as critical.
Is the duplication occurring in ConsoleAppener or in RollingFileAppender? Which version of logback is this?
TIA,
Michael Olson wrote:
Hello all,
I'm new to logback, and I'm trying out a configuration that is, more or less, a word-for-word transcription of an example configuration from the documentation; you can view the configuration here: http://logback.pastebin.com/m1a6f2875
I'm having problems where I'm getting interleaved messages on a single line. I don't believe this is expected behavior, and it certainly makes it harder to understand what's going on. I've tried searching the mailing list and bug list to no avail. Here's an example from one log file: 2009-09-30 15:45:31,649 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:45:33,2302009-09-30 15:45:33,230 [ [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [ INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:33,231 [2009-09-30 15:45:33,231Thread-17] [Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:45:34,230 [main] INFO Eventweb - All stopped
and here's an example from another log file: 2009-09-30 15:47:10,061 [Thread-5] INFO CalaisSubmitter - CalaisFeedSubmitter clean shutdown 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21]Thread-17] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,3782009-09-30 15:47:11,378 [ [2009-09-30 15:47:11,378 [Thread-15]Thread-21] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:11,378 [Thread-19] INFO PostItemSubmitter - Shutdown aborted queue operation 2009-09-30 15:47:12,378 [main] INFO Eventweb - All stopped
Can anyone tell me why I'm getting interleaved messages, and what I might be able to do to prevent this from happening?
Thanks, - Michael
------------------------------------------------------------------------
_______________________________________________ Logback-user mailing list Logback-user@qos.ch http://qos.ch/mailman/listinfo/logback-user
-- Ceki Gülcü Logback: The reliable, generic, fast and flexible logging framework for Java. http://logback.qos.ch _______________________________________________ Logback-user mailing list Logback-user@qos.ch http://qos.ch/mailman/listinfo/logback-user
_______________________________________________ Logback-user mailing list Logback-user@qos.ch http://qos.ch/mailman/listinfo/logback-user
participants (4)
-
Ceki Gulcu
-
Johan Bos
-
Michael Olson
-
Michael Olson