You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@geronimo.apache.org by "Aaron Mulder (JIRA)" <de...@geronimo.apache.org> on 2006/01/20 05:17:41 UTC

[jira] Created: (GERONIMO-1509) Maven logging output multipled

Maven logging output multipled
------------------------------

         Key: GERONIMO-1509
         URL: http://issues.apache.org/jira/browse/GERONIMO-1509
     Project: Geronimo
        Type: Bug
  Components: transaction manager  
    Versions: 1.0    
    Reporter: Aaron Mulder
    Priority: Minor
     Fix For: 1.0.1


When I run a new Maven build on the 1.0 branch...

It starts out printing each Maven log message once...
By the time it gets to the Transaction module, it's printing each one twice:

23:09:54,922 INFO  [ReactorTag] +----------------------------------------
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
| Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
+----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------

Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:

multiproject:install-callback:
    [echo] Running jar:install for Geronimo :: Transaction
java:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:compile:
<depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
    [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:jar-resources:

test:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports

test:test-resources:

test:compile:
    [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes

test:test:
    [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
    [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
    [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
    [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
    [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
    [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
    [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
    [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
    [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
    [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp

jar:jar:
    [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar

jar:install:
    [echo] Installing...
Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
.................... (73K)
Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
.................... (12K)

+----------------------------------------

build:end:

23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
| Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
+----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------


-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
   http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see:
   http://www.atlassian.com/software/jira


[jira] Closed: (GERONIMO-1509) Maven logging output multipled

Posted by "Jason Dillon (JIRA)" <ji...@apache.org>.
     [ https://issues.apache.org/jira/browse/GERONIMO-1509?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Jason Dillon closed GERONIMO-1509.
----------------------------------

       Resolution: Won't Fix
    Fix Version/s:     (was: 1.x)
         Assignee: Jason Dillon

Fixed in 1.2 and 2.0, 1.1.x is dormant.

> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: https://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Assignee: Jason Dillon
>            Priority: Minor
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> {noformat}
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> {noformat}
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> {noformat}
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> {noformat}

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


[jira] Commented: (GERONIMO-1509) Maven logging output multipled

Posted by "Paul McMahan (JIRA)" <de...@geronimo.apache.org>.
    [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=comments#action_12422671 ] 
            
Paul McMahan commented on GERONIMO-1509:
----------------------------------------

I still see the multiplied output when I do a top down build on a fresh checkout of branches/1.1 using maven 1.1b2.   Only seems to happen to me under these circumstances.

> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Priority: Minor
>             Fix For: 1.x
>
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira

        

[jira] Commented: (GERONIMO-1509) Maven logging output multipled

Posted by "John Sisson (JIRA)" <de...@geronimo.apache.org>.
    [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=comments#action_12378552 ] 

John Sisson commented on GERONIMO-1509:
---------------------------------------

May be related to the following velocity issue:

http://marc.theaimsgroup.com/?t=114496678800005&r=1&w=2

> Maven logging output multipled
> ------------------------------
>
>          Key: GERONIMO-1509
>          URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>      Project: Geronimo
>         Type: Bug
>     Security: public(Regular issues) 
>   Components: buildsystem
>     Versions: 1.0
>     Reporter: Aaron Mulder
>     Priority: Minor
>      Fix For: 1.x

>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
   http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see:
   http://www.atlassian.com/software/jira


[jira] Commented: (GERONIMO-1509) Maven logging output multipled

Posted by "Paul McMahan (JIRA)" <de...@geronimo.apache.org>.
    [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=comments#action_12422687 ] 
            
Paul McMahan commented on GERONIMO-1509:
----------------------------------------

>  A rebuild of branches/1.1 (ie. not fresh) produces normal output?

As I recall yes that is the case.  Unfortunately my dev environment is completely hosed right now so I can't verify, sorry.

> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Priority: Minor
>             Fix For: 1.x
>
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> {noformat}
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> {noformat}
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> {noformat}
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> {noformat}

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira

        

[jira] Updated: (GERONIMO-1509) Maven logging output multipled

Posted by "Matt Hogstrom (JIRA)" <de...@geronimo.apache.org>.
     [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=all ]

Matt Hogstrom updated GERONIMO-1509:
------------------------------------

    Fix Version: 1.x
                     (was: 1.0.1)

This is more of a build issue.  I'm moving out of 1.0.1 so it doesn't get tracked with this release.

> Maven logging output multipled
> ------------------------------
>
>          Key: GERONIMO-1509
>          URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>      Project: Geronimo
>         Type: Bug
>   Components: transaction manager
>     Versions: 1.0
>     Reporter: Aaron Mulder
>     Priority: Minor
>      Fix For: 1.x

>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
   http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see:
   http://www.atlassian.com/software/jira


[jira] Commented: (GERONIMO-1509) Maven logging output multipled

Posted by "Jason Dillon (JIRA)" <de...@geronimo.apache.org>.
    [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=comments#action_12422676 ] 
            
Jason Dillon commented on GERONIMO-1509:
----------------------------------------

A rebuild of branches/1.1 (ie. not fresh) produces normal output?

> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Priority: Minor
>             Fix For: 1.x
>
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> {noformat}
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> {noformat}

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira

        

[jira] Updated: (GERONIMO-1509) Maven logging output multipled

Posted by "David Jencks (JIRA)" <de...@geronimo.apache.org>.
     [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=all ]

David Jencks updated GERONIMO-1509:
-----------------------------------

    Component: buildsystem
                   (was: transaction manager)

Until determined otherwise, this is a build system issue.  Hopefully it will go away with the m2 build

> Maven logging output multipled
> ------------------------------
>
>          Key: GERONIMO-1509
>          URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>      Project: Geronimo
>         Type: Bug
>     Security: public(Regular issues) 
>   Components: buildsystem
>     Versions: 1.0
>     Reporter: Aaron Mulder
>     Priority: Minor
>      Fix For: 1.x

>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
   http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see:
   http://www.atlassian.com/software/jira


[jira] Updated: (GERONIMO-1509) Maven logging output multipled

Posted by "Jason Dillon (JIRA)" <de...@geronimo.apache.org>.
     [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=all ]

Jason Dillon updated GERONIMO-1509:
-----------------------------------

    Description: 
When I run a new Maven build on the 1.0 branch...

It starts out printing each Maven log message once...
By the time it gets to the Transaction module, it's printing each one twice:

{noformat}
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
| Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
+----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------
{noformat}

Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:

{noformat}
multiproject:install-callback:
    [echo] Running jar:install for Geronimo :: Transaction
java:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:compile:
<depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
    [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:jar-resources:

test:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports

test:test-resources:

test:compile:
    [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes

test:test:
    [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
    [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
    [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
    [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
    [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
    [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
    [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
    [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
    [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
    [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp

jar:jar:
    [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar

jar:install:
    [echo] Installing...
Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
.................... (73K)
Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
.................... (12K)

+----------------------------------------

build:end:

23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
| Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
+----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
{noformat}

  was:
When I run a new Maven build on the 1.0 branch...

It starts out printing each Maven log message once...
By the time it gets to the Transaction module, it's printing each one twice:

{noformat}
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
| Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
+----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------

Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:

multiproject:install-callback:
    [echo] Running jar:install for Geronimo :: Transaction
java:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:compile:
<depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
    [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:jar-resources:

test:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports

test:test-resources:

test:compile:
    [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes

test:test:
    [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
    [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
    [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
    [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
    [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
    [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
    [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
    [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
    [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
    [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp

jar:jar:
    [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar

jar:install:
    [echo] Installing...
Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
.................... (73K)
Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
.................... (12K)

+----------------------------------------

build:end:

23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
| Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
+----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
{noformat}


> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Priority: Minor
>             Fix For: 1.x
>
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> {noformat}
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> {noformat}
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> {noformat}
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> {noformat}

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira

        

[jira] Commented: (GERONIMO-1509) Maven logging output multipled

Posted by "Jason Dillon (JIRA)" <de...@geronimo.apache.org>.
    [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=comments#action_12422688 ] 
            
Jason Dillon commented on GERONIMO-1509:
----------------------------------------

Np, just trying to clarify.

I don't believe that this will be fixed, unless someone stumbles into the cause...

> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Priority: Minor
>             Fix For: 1.x
>
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> {noformat}
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> {noformat}
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> {noformat}
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> {noformat}

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira

        

[jira] Updated: (GERONIMO-1509) Maven logging output multipled

Posted by "Jason Dillon (JIRA)" <de...@geronimo.apache.org>.
     [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=all ]

Jason Dillon updated GERONIMO-1509:
-----------------------------------

    Description: 
When I run a new Maven build on the 1.0 branch...

It starts out printing each Maven log message once...
By the time it gets to the Transaction module, it's printing each one twice:

{noformat}
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
| Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
+----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------

Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:

multiproject:install-callback:
    [echo] Running jar:install for Geronimo :: Transaction
java:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:compile:
<depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
    [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:jar-resources:

test:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports

test:test-resources:

test:compile:
    [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes

test:test:
    [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
    [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
    [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
    [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
    [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
    [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
    [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
    [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
    [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
    [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp

jar:jar:
    [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar

jar:install:
    [echo] Installing...
Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
.................... (73K)
Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
.................... (12K)

+----------------------------------------

build:end:

23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
| Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
+----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
{noformat}

  was:
When I run a new Maven build on the 1.0 branch...

It starts out printing each Maven log message once...
By the time it gets to the Transaction module, it's printing each one twice:

23:09:54,922 INFO  [ReactorTag] +----------------------------------------
23:09:54,922 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
| Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
+----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------
23:09:54,924 INFO  [ReactorTag] +----------------------------------------

Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:

multiproject:install-callback:
    [echo] Running jar:install for Geronimo :: Transaction
java:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:compile:
<depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
    [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes

java:jar-resources:

test:prepare-filesystem:
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
    [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports

test:test-resources:

test:compile:
    [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes

test:test:
    [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
    [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
    [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
    [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
    [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
    [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
    [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
    [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
    [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
    [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp

jar:jar:
    [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar

jar:install:
    [echo] Installing...
Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
.................... (73K)
Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
.................... (12K)

+----------------------------------------

build:end:

23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
23:10:26,145 INFO  [ReactorTag] +----------------------------------------
| geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
| Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
+----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------
23:10:26,153 INFO  [ReactorTag] +----------------------------------------



> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Priority: Minor
>             Fix For: 1.x
>
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> {noformat}
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> {noformat}

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira

        

[jira] Commented: (GERONIMO-1509) Maven logging output multipled

Posted by "Jason Dillon (JIRA)" <de...@geronimo.apache.org>.
    [ http://issues.apache.org/jira/browse/GERONIMO-1509?page=comments#action_12422561 ] 
            
Jason Dillon commented on GERONIMO-1509:
----------------------------------------

Is this still an issue?

> Maven logging output multipled
> ------------------------------
>
>                 Key: GERONIMO-1509
>                 URL: http://issues.apache.org/jira/browse/GERONIMO-1509
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: buildsystem
>    Affects Versions: 1.0
>            Reporter: Aaron Mulder
>            Priority: Minor
>             Fix For: 1.x
>
>
> When I run a new Maven build on the 1.0 branch...
> It starts out printing each Maven log message once...
> By the time it gets to the Transaction module, it's printing each one twice:
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,922 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> 23:09:54,922 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Transaction
> | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> 23:09:54,923 INFO  [ReactorTag] | Memory: 24M/66M
> +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> 23:09:54,924 INFO  [ReactorTag] +----------------------------------------
> Something right there causes it to really get out of hand (see below).  I wonder if the Transaction tests are manipulating the Log4J configuration?  It looks like there are 8 transaction tests and the log output goes from repeated twice to repeated 10 times (a difference of 8... kind of makes you go "hmmm"...).  Someone should look into this.  If we ID the issue in the transaction tests, maybe we can figure out what's causing it to get from 1 to 2 and so on.  Anyway, here's the suspicious output from the transaction build:
> multiproject:install-callback:
>     [echo] Running jar:install for Geronimo :: Transaction
> java:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:compile:
> <depend closure="false" srcdir="1.4" dump="false" destdir="/data/cvs/geronimo-1.0-branch/modules/transaction/target/classes"></depend>    [echo] Compiling to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
>     [javac] Compiling 40 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/classes
> java:jar-resources:
> test:prepare-filesystem:
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
>     [mkdir] Created dir: /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports
> test:test-resources:
> test:compile:
>     [javac] Compiling 15 source files to /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-classes
> test:test:
>     [junit] Running org.apache.geronimo.transaction.context.TransactionContextManagerTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
>     [junit] Running org.apache.geronimo.transaction.log.HOWLLogTest
>     [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 8.094 sec
>     [junit] Running org.apache.geronimo.transaction.manager.HOWLLogRecoveryTest
>     [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.954 sec
>     [junit] Running org.apache.geronimo.transaction.manager.MockLogRecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
>     [junit] Running org.apache.geronimo.transaction.manager.ProtocolTest
>     [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
>     [junit] Running org.apache.geronimo.transaction.manager.RecoveryTest
>     [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.073 sec
>     [junit] Running org.apache.geronimo.transaction.manager.TransactionManagerImplTest
>     [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 15.164 sec
>     [junit] Running org.apache.geronimo.transaction.manager.XidImporterTest
>     [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.114 sec
> Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
> 23:10:25,440 INFO  [PostGoalTag] Running post goal: test:test
>     [touch] Creating /data/cvs/geronimo-1.0-branch/modules/transaction/target/test-reports/tstamp
> jar:jar:
>     [jar] Building jar: /data/cvs/geronimo-1.0-branch/modules/transaction/target/geronimo-transaction-1.0.1-SNAPSHOT.jar
> jar:install:
>     [echo] Installing...
> Uploading to geronimo/jars/geronimo-transaction-1.0.1-SNAPSHOT.jar:
> .................... (73K)
> Uploading to geronimo/poms/geronimo-transaction-1.0.1-SNAPSHOT.pom:
> .................... (12K)
> +----------------------------------------
> build:end:
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,145 INFO  [ReactorTag] +----------------------------------------
> | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> 23:10:26,148 INFO  [ReactorTag] | geronimo and geronimo-plugins Geronimo :: Connector
> | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> 23:10:26,151 INFO  [ReactorTag] | Memory: 26M/66M
> +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------
> 23:10:26,153 INFO  [ReactorTag] +----------------------------------------

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira