[ 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