logging-log4j-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Mark Womack" <mwom...@apache.org>
Subject Re: [GUMP@vmgump]: Project logging-log4j-tests (in module logging-log4j) failed
Date Thu, 16 Mar 2006 20:43:52 GMT
The complete output for this test is below.  At first blush, it
appears that the second config file is not parsed correctly, so the
level is never changed to INFO, so the test eventually times out
waiting for the change.  Not sure why the file is failing in some
cases.  Will probably need to turn up the debugging on
JoranConfigurator.

-Mark

FileWatchdog:
    [junit] Running org.apache.log4j.watchdog.FileWatchdogTestCase
    [junit] Testsuite: org.apache.log4j.watchdog.FileWatchdogTestCase
    [junit] Tests run: 1, Failures: 1, Errors: 0, Time elapsed: 22.874 sec
    [junit] Tests run: 1, Failures: 1, Errors: 0, Time elapsed: 22.874 sec
    [junit] ------------- Standard Output ---------------
    [junit] 11:29:00,532
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - log4j
configured with configFile
    [junit] 11:29:00,571 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - activateOptions called for watchdog test1
    [junit] 11:29:00,575
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - watchdog
activated
    [junit] 11:29:00,575 test.FileWatchdogTestCase [DEBUG] - debug message
    [junit] 11:29:00,575 test.FileWatchdogTestCase [INFO] - info message
    [junit] 11:29:00,575 test.FileWatchdogTestCase [WARN] - warn message
    [junit] 11:29:00,575 test.FileWatchdogTestCase [ERROR] - error message
    [junit] 11:29:00,575 test.FileWatchdogTestCase [FATAL] - fatal message
    [junit] 11:29:00,575
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - first set of
test messages output
    [junit] 11:29:01,581 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537340000) ?? (curModTime - 1142537340000)
    [junit] 11:29:01,581 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:02,580
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - about to copy
second config file
    [junit] 11:29:02,591 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537340000) ?? (curModTime - 1142537342000)
    [junit] 11:29:02,591 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times did not match, reconfiguring with watchdog test1
    [junit] 11:29:02,591 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - watchdog "test1" reconfiguring from url:
file:/x1/gump/public/workspace/logging-log4j/tests/output/watchdog.FileWatchdog.test1.xml
    [junit] 11:29:02,605
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - second config
file copied
    [junit] 11:29:02,610
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:02,634 org.apache.log4j.joran.JoranConfigurator
[ERROR] - Problem parsing XML document. See previously reported
errors. Abandoning all further processing.
    [junit] org.xml.sax.SAXParseException: Premature end of file.
    [junit] 	at
org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)
    [junit] 	at
org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown
Source)
    [junit] 	at org.apache.xerces.jaxp.SAXParserImpl.parse(Unknown Source)
    [junit] 	at javax.xml.parsers.SAXParser.parse(Unknown Source)
    [junit] 	at
org.apache.log4j.joran.JoranConfigurator$1.parse(JoranConfigurator.java:85)
    [junit] 	at
org.apache.log4j.joran.JoranConfigurator.doConfigure(JoranConfigurator.java:151)
    [junit] 	at
org.apache.log4j.joran.JoranConfigurator.doConfigure(JoranConfigurator.java:88)
    [junit] 	at
org.apache.log4j.watchdog.WatchdogSkeleton.reconfigureByURL(WatchdogSkeleton.java:124)
    [junit] 	at
org.apache.log4j.watchdog.FileWatchdog.reconfigure(FileWatchdog.java:97)
    [junit] 	at
org.apache.log4j.watchdog.TimedLocationWatchdog.execute(TimedLocationWatchdog.java:90)
    [junit] 	at
org.apache.log4j.scheduler.Scheduler.executeInABox(Scheduler.java:195)
    [junit] 	at org.apache.log4j.scheduler.Scheduler.run(Scheduler.java:173)
    [junit] 11:29:03,121
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:03,121
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:03,121
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:03,601 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:03,601 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:03,630
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:03,631
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:03,631
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:04,141
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:04,141
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:04,141
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:04,611 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:04,611 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:04,652
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:04,652
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:04,652
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:05,161
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:05,163
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:05,163
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:05,621 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:05,621 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:05,671
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:05,671
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:05,671
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:06,180
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:06,180
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:06,180
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:06,630 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:06,630 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:06,690
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:06,690
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:06,690
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:07,200
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:07,200
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:07,200
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:07,640 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:07,644 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:07,710
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:07,710
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:07,710
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:08,221
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:08,222
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:08,222
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:08,651 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:08,651 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:08,731
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:08,731
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:08,731
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:09,241
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:09,242
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:09,242
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:09,661 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:09,661 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:09,751
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:09,751
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:09,751
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:10,261
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:10,261
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:10,261
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:10,662 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:10,662 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:10,762
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:10,762
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:10,762
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:11,281
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:11,281
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:11,281
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:11,682 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:11,682 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:11,792
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:11,792
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:11,792
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:12,293
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:12,293
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:12,293
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:12,683 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:12,683 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:12,803
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:12,804
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:12,804
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:13,313
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:13,313
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:13,314
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:13,692 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:13,692 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:13,822
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:13,822
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:13,822
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:14,333
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:14,333
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:14,333
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:14,703 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:14,703 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:14,842
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:14,842
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:14,842
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:15,353
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:15,353
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:15,353
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:15,714 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:15,714 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:15,863
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:15,863
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:15,863
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:16,374
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:16,375
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:16,375
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:16,723 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:16,723 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:16,882
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:16,883
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:16,883
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:17,402
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:17,402
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:17,402
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:17,733 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:17,733 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:17,902
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:17,903
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:17,903
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:18,413
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:18,414
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:18,414
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:18,743 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:18,743 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:18,923
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:18,923
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:18,923
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:19,434
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:19,434
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:19,434
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:19,753 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:19,753 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:19,942
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:19,942
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:19,942
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:20,460
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:20,461
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:20,461
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:20,763 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:20,763 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:20,963
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:20,963
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:20,963
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:21,482
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:21,482
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:21,482
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:21,773 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:21,773 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:22,002
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:22,002
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:22,003
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:22,513
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:22,513
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] 11:29:22,513
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - sleeping for
500 ms
    [junit] 11:29:22,783 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Checking times for watchdog test1 :(lastModTime -
1142537342000) ?? (curModTime - 1142537342000)
    [junit] 11:29:22,783 org.apache.log4j.watchdog.FileWatchdog
[DEBUG] - Times matched, doing nothing
    [junit] 11:29:23,022
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - level for
logger test.FileWatchdogTestCase is DEBUG
    [junit] 11:29:23,023
org.apache.log4j.watchdog.FileWatchdogTestCase [DEBUG] - looping for
level check
    [junit] ------------- ---------------- ---------------

    [junit] Testcase: test1 took 22.867 sec
    [junit] 	FAILED
    [junit] Expected change in level did not occur within 20 seconds.
    [junit] junit.framework.AssertionFailedError: Expected change in
level did not occur within 20 seconds.
    [junit] 	at
org.apache.log4j.watchdog.FileWatchdogTestCase.test1(FileWatchdogTestCase.java:183)

---------------------------------------------------------------------
To unsubscribe, e-mail: log4j-dev-unsubscribe@logging.apache.org
For additional commands, e-mail: log4j-dev-help@logging.apache.org


Mime
View raw message