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: [EMAIL PROTECTED] For additional commands, e-mail: [EMAIL PROTECTED]
