Attachment 1179 Details for Bug 2955 – FINE output of a failure of testDoCloneAckDaemon.

FINE output of a failure of testDoCloneAckDaemon.

daemon.log (text/plain), 10.63 KB, created by Nurdin Premji on 2006-07-24 15:19:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Nurdin Premji

Created: 2006-07-24 15:19:07 UTC

Size: 10.63 KB

patch

obsolete

>console FINE
>java.util.logging.ConsoleHandler@13ccf8
>24-Jul-06 10:50:05 AM frysk.junit.Runner$Results startTest
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) ---- startTest ----
>
>Running testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) ...24-Jul-06 10:50:05 AM frysk.proc.TestLib setUp
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) <<<<<<<<<<<<<<<< start setUp
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop <init>
>FINE: {Thread[Thread-1,5,main]} new
>
>24-Jul-06 10:50:05 AM frysk.proc.Host <init>
>FINE: {frysk.proc.LinuxHost@132240,state=running} Host
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxHost$PollWaitOnSigChld <init>
>FINE: {frysk.proc.LinuxHost$PollWaitOnSigChld@16b440,sig=Sig_CHLD} PollWaitOnSigChld
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop <init>
>FINE: {Thread[Thread-2,5,main]} new
>
>24-Jul-06 10:50:05 AM frysk.proc.Host <init>
>FINE: {frysk.proc.LinuxHost@132150,state=running} Host
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxHost$PollWaitOnSigChld <init>
>FINE: {frysk.proc.LinuxHost$PollWaitOnSigChld@16b160,sig=Sig_CHLD} PollWaitOnSigChld
>
>24-Jul-06 10:50:05 AM frysk.proc.TestLib setUp
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) <<<<<<<<<<<<<<<< end setUp
>
>24-Jul-06 10:50:05 AM frysk.proc.TestLib killDuringTearDown
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) killDuringTearDown 8,283
>
>24-Jul-06 10:50:05 AM frysk.proc.TestLib assertRunUntilStop
>FINE: class frysk.proc.TestLib assertRunUntilStop start: startChild (Sig_USR1)
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop runPolling
>FINE: {Thread[Thread-2,5,main]} runPolling long
>
>24-Jul-06 10:50:05 AM frysk.event.TimerEvent <init>
>FINE: {{frysk.event.EventLoop$2$Timeout@13cb40,timeMillis=1153752610929,periodMillis=0},expiredfalse} new long
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop$2$Timeout <init>
>FINE: {{frysk.event.EventLoop$2$Timeout@13cb40,timeMillis=1153752610929,periodMillis=0},expiredfalse} timeout
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxHost$PollWaitOnSigChld execute
>FINE: {frysk.proc.LinuxHost$PollWaitOnSigChld@16b160,sig=Sig_CHLD} execute
>
>24-Jul-06 10:50:05 AM frysk.sys.Wait log
>FINE: frysk.sys.Wait pid -1 errno 10
>
>8283.8283: argv[0]=/notnfs/frysk-build/frysk-core/frysk/pkglibexecdir/funit-child
>8283.8283: argv[1]=--wait=suspend
>8283.8283: argv[2]=10
>8283.8283: argv[3]=8280
>8283.8283: starting 8283
>8283.8283: new thread 8283.8283
>8283.8283: notify 8280 with 10 (User defined signal 1) -- new thread 8283.8283
>24-Jul-06 10:50:05 AM frysk.proc.TestLib$AckHandler$AckSignal execute
>FINE: {frysk.proc.TestLib$AckHandler$AckSignal@192f30,sig=Sig_USR1} execute (startChild (Sig_USR1))
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop remove
>FINE: {Thread[Thread-2,5,main]} remove SignalEvent
>
>24-Jul-06 10:50:05 AM frysk.proc.TestLib assertRunUntilStop
>FINE: class frysk.proc.TestLib assertRunUntilStop stop: startChild (Sig_USR1)
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop runPending
>FINE: {Thread[Thread-2,5,main]} runPending
>
>24-Jul-06 10:50:05 AM frysk.proc.HostState$1 handleRefresh
>FINE: {frysk.proc.LinuxHost@132150,state=running} handleRefresh
>
>24-Jul-06 10:50:05 AM frysk.proc.Host getProc
>FINE: {frysk.proc.LinuxHost@132150,state=running} getProc ProcId
>
>24-Jul-06 10:50:05 AM frysk.proc.ProcTasksObserver <init>
>FINE: frysk.proc.ProcTasksObserver@1dc4e0 new
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop runPending
>FINE: {Thread[Thread-2,5,main]} runPending
>
>24-Jul-06 10:50:05 AM frysk.proc.Host get
>FINE: {frysk.proc.LinuxHost@132150,state=running} get TaskId
>
>24-Jul-06 10:50:05 AM frysk.proc.Task requestAddClonedObserver
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=detached} requestAddClonedObserver
>
>24-Jul-06 10:50:05 AM frysk.proc.Proc requestAddObserver
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=detached} requestAddObservation
>
>24-Jul-06 10:50:05 AM frysk.proc.Task requestAddTerminatedObserver
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=detached} requestAddTerminatedObserver
>
>24-Jul-06 10:50:05 AM frysk.proc.Proc requestAddObserver
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=detached} requestAddObservation
>
>24-Jul-06 10:50:05 AM frysk.proc.ProcState$1 handleAddObservation
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=detached} handleAddObserver 
>
>24-Jul-06 10:50:05 AM frysk.proc.ProcState$Attaching initialState
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=detached} state
>
>24-Jul-06 10:50:05 AM frysk.proc.Host get
>FINE: {frysk.proc.LinuxHost@132150,state=running} get TaskId
>
>24-Jul-06 10:50:05 AM frysk.proc.TaskState$1 handleAttach
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=detached} handleAttach
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxTask sendAttach
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=detached} sendAttach
>
>24-Jul-06 10:50:05 AM frysk.proc.ProcState$Attaching$ToMainTask handleAddObservation
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=Attaching.ToMainTask} handleAddObservation
>
>24-Jul-06 10:50:05 AM frysk.proc.TestLib assertRunUntilStop
>FINE: class frysk.proc.TestLib assertRunUntilStop start: addClone (Sig_USR1,Sig_USR2)
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop runPolling
>FINE: {Thread[Thread-2,5,main]} runPolling long
>
>24-Jul-06 10:50:05 AM frysk.event.TimerEvent <init>
>FINE: {{frysk.event.EventLoop$2$Timeout@1edf50,timeMillis=1153752610950,periodMillis=0},expiredfalse} new long
>
>24-Jul-06 10:50:05 AM frysk.event.EventLoop$2$Timeout <init>
>FINE: {{frysk.event.EventLoop$2$Timeout@1edf50,timeMillis=1153752610950,periodMillis=0},expiredfalse} timeout
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxHost$PollWaitOnSigChld execute
>FINE: {frysk.proc.LinuxHost$PollWaitOnSigChld@16b160,sig=Sig_CHLD} execute
>
>24-Jul-06 10:50:05 AM frysk.sys.Wait log
>FINE: frysk.sys.Wait pid 8283 status 0xa7f
>
>24-Jul-06 10:50:05 AM frysk.sys.Wait log
>FINE: frysk.sys.Wait pid 0 errno 0
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxHost$PollWaitOnSigChld$2 getTask
>FINE: {TaskId,8283} stopped
>
>24-Jul-06 10:50:05 AM frysk.proc.Host get
>FINE: {frysk.proc.LinuxHost@132150,state=running} get TaskId
>
>24-Jul-06 10:50:05 AM frysk.proc.TaskState$2 handleSignaledEvent
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=attaching} handleSignaledEvent
>
>24-Jul-06 10:50:05 AM frysk.proc.Proc performTaskAttachCompleted
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=Attaching.ToMainTask} performTaskAttachCompleted
>
>24-Jul-06 10:50:05 AM frysk.proc.ProcState$Attaching$ToMainTask handleTaskAttachCompleted
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=Attaching.ToMainTask} handleTaskAttachCompleted
>
>24-Jul-06 10:50:05 AM frysk.proc.ProcState$Attaching allAttached
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=Attaching.ToMainTask} allAttached
>
>24-Jul-06 10:50:05 AM frysk.proc.TaskState$Attached handleAddObserver
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=Attached.WaitForContinueOrUnblock} handleAddObserver
>
>24-Jul-06 10:50:05 AM frysk.proc.TestProcTasksObserver$ProcTasksTester existingTask
>FINE: ProcTasksTester.existingTask() task: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=Attached.WaitForContinueOrUnblock}
>
>24-Jul-06 10:50:05 AM frysk.proc.TaskState$Attached handleAddObserver
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=Attached.WaitForContinueOrUnblock} handleAddObserver
>
>24-Jul-06 10:50:05 AM frysk.proc.TaskState$Attached$WaitForContinueOrUnblock handleContinue
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=Attached.WaitForContinueOrUnblock} handleContinue
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxTask sendSetOptions
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=Attached.WaitForContinueOrUnblock} sendSetOptions
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxTask sendContinue
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=Attached.WaitForContinueOrUnblock} sendContinue
>
>24-Jul-06 10:50:05 AM frysk.proc.Proc$ObservableXXX notify
>FINE: {frysk.proc.LinuxProc@1dec30,pid=8283,state=Attaching.ToMainTask} notify -- all observers
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxHost$PollWaitOnSigChld execute
>FINE: {frysk.proc.LinuxHost$PollWaitOnSigChld@16b160,sig=Sig_CHLD} execute
>
>24-Jul-06 10:50:05 AM frysk.sys.Wait log
>FINE: frysk.sys.Wait pid 8283 status 0x137f
>
>24-Jul-06 10:50:05 AM frysk.sys.Wait log
>FINE: frysk.sys.Wait pid 0 errno 0
>
>24-Jul-06 10:50:05 AM frysk.proc.LinuxHost$PollWaitOnSigChld$2 getTask
>FINE: {TaskId,8283} stopped
>
>24-Jul-06 10:50:05 AM frysk.proc.Host get
>FINE: {frysk.proc.LinuxHost@132150,state=running} get TaskId
>
>24-Jul-06 10:50:05 AM frysk.proc.TaskState$Running handleStoppedEvent
>FINE: {frysk.proc.LinuxTask@1ecf60,pid=8283,tid=8283,state=running} handleStoppedEvent
>
>24-Jul-06 10:50:10 AM frysk.event.EventLoop$2$Timeout execute
>FINE: {{frysk.event.EventLoop$2$Timeout@1edf50,timeMillis=1153752610950,periodMillis=0},expiredfalse} execute
>
>24-Jul-06 10:50:10 AM frysk.proc.TestLib tearDown
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) >>>>>>>>>>>>>>>> start tearDown
>
>24-Jul-06 10:50:10 AM frysk.proc.TestLib tearDown
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) kill -KILL 8283
>
>24-Jul-06 10:50:10 AM frysk.proc.TestLib tearDown
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) kill -CONT 8283
>
>24-Jul-06 10:50:10 AM frysk.proc.TestLib tearDown
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) detach -KILL 8283
>
>24-Jul-06 10:50:10 AM frysk.sys.Wait log
>FINE: frysk.sys.Wait pid -1 errno 10
>
>24-Jul-06 10:50:10 AM frysk.proc.TestLib tearDown
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) >>>>>>>>>>>>>>>> end tearDown
>
>24-Jul-06 10:50:10 AM frysk.junit.Runner$Results printProblem
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) --- addFailure ---- FAIL: junit.framework.AssertionFailedError: event loop run explictly stopped (addClone (Sig_USR1,Sig_USR2))
>
>FAIL
>  junit.framework.AssertionFailedError: event loop run explictly stopped (addClone (Sig_USR1,Sig_USR2))24-Jul-06 10:50:10 AM frysk.junit.Runner$Results endTest
>FINE: testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver) ---- endTest ----
>
>
>
>Time: 5.033
>There was 1 failure:
>1) testDoCloneAckDaemon(frysk.proc.TestProcTasksObserver)junit.framework.AssertionFailedError: event loop run explictly stopped (addClone (Sig_USR1,Sig_USR2))
>   at frysk.proc.TestLib.assertRunUntilStop(TestRunner)
>   at frysk.proc.TestLib.assertRunUntilStop(TestRunner)
>   at frysk.proc.TestLib$AckHandler.assertAwait(TestRunner)
>   at frysk.proc.TestLib$AckHandler.await(TestRunner)
>   at frysk.proc.TestLib$AckProcess.spawn(TestRunner)
>   at frysk.proc.TestLib$AckProcess.addClone(TestRunner)
>   at frysk.proc.TestProcTasksObserver.doClone(TestRunner)
>   at frysk.proc.TestProcTasksObserver.testDoCloneAckDaemon(TestRunner)
>   at frysk.junit.Runner.<init>(TestRunner)
>   at TestRunner.main(TestRunner)
>
>FAILURES!!!
>Tests run: 1,  Failures: 1,  Errors: 0
>

Actions: View

Attachments on bug 2955: 1179 | 1180