Error Message The test testGCWhenGetNodesTrue did not finish in 35000ms Group system Group main Thread main at java.lang.Thread.dumpThreads(Thread.java:-2) at java.lang.Thread.getAllStackTraces(Thread.java:1618) at org.netbeans.junit.NbTestCase.threadDump(NbTestCase.java:328) at org.netbeans.junit.NbTestCase.access$000(NbTestCase.java:97) at org.netbeans.junit.NbTestCase$1Guard.waitFinished(NbTestCase.java:398) at org.netbeans.junit.NbTestCase.runBare(NbTestCase.java:456) at junit.framework.TestResult$1.protect(TestResult.java:110) at junit.framework.TestResult.runProtected(TestResult.java:128) at junit.framework.TestResult.run(TestResult.java:113) at junit.framework.TestCase.run(TestCase.java:124) at org.netbeans.junit.NbTestCase.run(NbTestCase.java:288) at junit.framework.TestSuite.runTest(TestSuite.java:243) at junit.framework.TestSuite.run(TestSuite.java:238) at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:522) at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:1062) at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:913) Thread Active Reference Queue Daemon at java.lang.Object.wait(Object.java:-2) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) at org.openide.util.lookup.implspi.ActiveQueue$Daemon.run(ActiveQueue.java:180) Thread Test Watch Dog: testGCWhenGetNodesTrue at java.lang.Object.wait(Object.java:-2) at java.lang.Object.wait(Object.java:503) at org.openide.util.Task.waitFinished(Task.java:135) at org.openide.util.RequestProcessor$Task.waitFinished(RequestProcessor.java:1686) at org.openide.nodes.ChildrenKeysTest.testGCWhenGetNodesTrue(ChildrenKeysTest.java:486) at sun.reflect.NativeMethodAccessorImpl.invoke0(NativeMethodAccessorImpl.java:-2) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at junit.framework.TestCase.runTest(TestCase.java:168) at org.netbeans.junit.NbTestCase.access$200(NbTestCase.java:97) at org.netbeans.junit.NbTestCase$2.doSomething(NbTestCase.java:431) at org.netbeans.junit.NbTestCase$1Guard.run(NbTestCase.java:357) at java.lang.Thread.run(Thread.java:722) Thread Reference Handler at java.lang.Object.wait(Object.java:-2) at java.lang.Object.wait(Object.java:503) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133) Thread Finalizer at java.lang.Object.wait(Object.java:-2) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189) Thread Signal Dispatcher Thread Java2D Disposer at java.lang.Object.wait(Object.java:-2) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151) at sun.java2d.Disposer.run(Disposer.java:145) at java.lang.Thread.run(Thread.java:722) Thread AWT-XAWT at sun.awt.X11.XToolkit.waitForEvents(XToolkit.java:-2) at sun.awt.X11.XToolkit.run(XToolkit.java:627) at sun.awt.X11.XToolkit.run(XToolkit.java:591) at java.lang.Thread.run(Thread.java:722) Thread cleanup at java.lang.Thread.sleep(Thread.java:-2) at org.netbeans.junit.NbTestCase$4.run(NbTestCase.java:1403) at org.netbeans.junit.internal.NbModuleLogHandler.whileIgnoringOOME(NbModuleLogHandler.java:170) at org.netbeans.junit.NbTestCase.assertGC(NbTestCase.java:1376) at org.netbeans.junit.NbTestCase.assertGC(NbTestCase.java:1352) at org.openide.nodes.ChildrenKeysTest$3K.run(ChildrenKeysTest.java:436) at org.openide.util.RequestProcessor$Task.run(RequestProcessor.java:1432) at org.openide.util.RequestProcessor$Processor.run(RequestProcessor.java:2042) Log: Starting test testGCWhenGetNodesTrue [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: ready [org.openide.util.RequestProcessor] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: notifyRunning: RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.RequestProcessor] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Item enqueued: RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 status: true [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: post [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: go [org.openide.util.RequestProcessor] THREAD: cleanup MSG: Begining work cleanup [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Initializing entrySupport [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: setEntries for org.openide.nodes.EntrySupportDefault@1 array: null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: values: [Children.Array.AE[]] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: holder: null [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: mustNotifySetEntries: false [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: computing optimal result [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: registerChildrenArray: org.openide.nodes.ChildrenArray@2 null weak: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: pointed by: org.openide.nodes.ChildrenArray@2 null to: org.openide.nodes.EntrySupportDefault$ChArrRef@3 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Initialize org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: before addNotify [org.openide.util.RequestProcessor] THREAD: cleanup MSG: Executing RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.RequestProcessor] THREAD: cleanup MSG: notifyRunning: RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.nodes.Children] THREAD: cleanup MSG: before cleanup [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 2, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.nodes.Children] THREAD: cleanup MSG: waiting for reference: null [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: setEntries for org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: values: [Children.Keys.KE[1,0], Children.Array.AE[]] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: holder: org.openide.nodes.ChildrenArray@2 null [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: mustNotifySetEntries: false [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 2 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: after addNotify [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: addNotify successfully called for org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: notifyAll for org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] notifyLater: false [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: notifyAll done [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: optimal result is here: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: getNodes() org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 3, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Map initialized [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Put: Children.Keys.KE[1,0] info: Children.Info[Children.Keys.KE[1,0],length=0] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: nodesFor(Children.Info[Children.Keys.KE[1,0],length=0][917024b]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: map size=0, nodes=null [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: createNodes for 1 [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: reference created: org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1] [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: reference notified [org.openide.nodes.Children] THREAD: cleanup MSG: reference is here: java.lang.ref.WeakReference@5 [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: created nodes=[org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1]] [org.openide.nodes.Children] THREAD: cleanup MSG: its value is org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: leaving nodesFor(Children.Info[Children.Keys.KE[1,0],length=1][917024b]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Put: Children.Array.AE[] info: Children.Info[Children.Array.AE[],length=0] [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176860145; tasks=null [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: nodesFor(Children.Info[Children.Array.AE[],length=0][18761e4d]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: map size=1, nodes=null [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: created nodes=[] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: leaving nodesFor(Children.Info[Children.Array.AE[],length=0][18761e4d]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: registerChildrenArray: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 weak: true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 3 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: length : 1 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: init now : true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 3, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 3 [java.awt.event.EventDispatchThread] THREAD: AWT-EventQueue-0 MSG: Dispatching: sun.awt.AWTAutoShutdown$1[] on sun.awt.AWTAutoShutdown@6 [java.awt.event.EventDispatchThread] THREAD: AWT-EventQueue-0 MSG: removing the event filter: java.awt.EventDispatchThread$HierarchyEventFilter@7 [org.openide.util.lookup.implspi.ActiveQueue] THREAD: Active Reference Queue Daemon MSG: dequeued reference [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: length : 1 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: init now : true [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering 2, true [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering exit: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Find child got: org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1] [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: previous array: org.openide.nodes.EntrySupportDefault$ChArrRef@9 caller: org.openide.nodes.EntrySupportDefault$ChArrRef@9 [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: t: Test Watch Dog: testGCWhenGetNodesTrue interrupts: cleanup [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: t: Test Watch Dog: testGCWhenGetNodesTrue log: after findChild: true waiting [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering 2, true [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering exit: true [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: setEntries for org.openide.nodes.FilterNode$Children$DefaultSupport@a array: null on Thread[Active Reference Queue Daemon,1,main] [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: values: [Children.Array.AE[]] [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: holder: null [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: mustNotifySetEntries: false [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving 2 [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving exit: 2 [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving 2 [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving exit: 0 [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: t: Test Watch Dog: testGCWhenGetNodesTrue log: after findChild: true timeout [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: after findChild: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: getNodes() org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 3, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 3 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: length : 1 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: init now : true [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176865322; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176870772; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176873484; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176873984; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176874485; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176874986; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176875499; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176876000; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176876500; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176877001; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176884627; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176885128; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176885629; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176886129; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176886630; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176887131; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176887632; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176888173; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176888788; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176889325; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176889833; tasks=null Stacktrace junit.framework.AssertionFailedError: The test testGCWhenGetNodesTrue did not finish in 35000ms Group system Group main Thread main at java.lang.Thread.dumpThreads(Thread.java:-2) at java.lang.Thread.getAllStackTraces(Thread.java:1618) at org.netbeans.junit.NbTestCase.threadDump(NbTestCase.java:328) at org.netbeans.junit.NbTestCase.access$000(NbTestCase.java:97) at org.netbeans.junit.NbTestCase$1Guard.waitFinished(NbTestCase.java:398) at org.netbeans.junit.NbTestCase.runBare(NbTestCase.java:456) at org.netbeans.junit.NbTestCase.run(NbTestCase.java:288) Thread Active Reference Queue Daemon at java.lang.Object.wait(Object.java:-2) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) at org.openide.util.lookup.implspi.ActiveQueue$Daemon.run(ActiveQueue.java:180) Thread Test Watch Dog: testGCWhenGetNodesTrue at java.lang.Object.wait(Object.java:-2) at java.lang.Object.wait(Object.java:503) at org.openide.util.Task.waitFinished(Task.java:135) at org.openide.util.RequestProcessor$Task.waitFinished(RequestProcessor.java:1686) at org.openide.nodes.ChildrenKeysTest.testGCWhenGetNodesTrue(ChildrenKeysTest.java:486) at org.netbeans.junit.NbTestCase.access$200(NbTestCase.java:97) at org.netbeans.junit.NbTestCase$2.doSomething(NbTestCase.java:431) at org.netbeans.junit.NbTestCase$1Guard.run(NbTestCase.java:357) at java.lang.Thread.run(Thread.java:722) Thread Reference Handler at java.lang.Object.wait(Object.java:-2) at java.lang.Object.wait(Object.java:503) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133) Thread Finalizer at java.lang.Object.wait(Object.java:-2) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189) Thread Signal Dispatcher Thread Java2D Disposer at java.lang.Object.wait(Object.java:-2) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151) at sun.java2d.Disposer.run(Disposer.java:145) at java.lang.Thread.run(Thread.java:722) Thread AWT-XAWT at sun.awt.X11.XToolkit.waitForEvents(XToolkit.java:-2) at sun.awt.X11.XToolkit.run(XToolkit.java:627) at sun.awt.X11.XToolkit.run(XToolkit.java:591) at java.lang.Thread.run(Thread.java:722) Thread cleanup at java.lang.Thread.sleep(Thread.java:-2) at org.netbeans.junit.NbTestCase$4.run(NbTestCase.java:1403) at org.netbeans.junit.internal.NbModuleLogHandler.whileIgnoringOOME(NbModuleLogHandler.java:170) at org.netbeans.junit.NbTestCase.assertGC(NbTestCase.java:1376) at org.netbeans.junit.NbTestCase.assertGC(NbTestCase.java:1352) at org.openide.nodes.ChildrenKeysTest$3K.run(ChildrenKeysTest.java:436) at org.openide.util.RequestProcessor$Task.run(RequestProcessor.java:1432) at org.openide.util.RequestProcessor$Processor.run(RequestProcessor.java:2042) Log: Starting test testGCWhenGetNodesTrue [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: ready [org.openide.util.RequestProcessor] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: notifyRunning: RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.RequestProcessor] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Item enqueued: RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 status: true [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: post [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: go [org.openide.util.RequestProcessor] THREAD: cleanup MSG: Begining work cleanup [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Initializing entrySupport [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: setEntries for org.openide.nodes.EntrySupportDefault@1 array: null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: values: [Children.Array.AE[]] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: holder: null [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: mustNotifySetEntries: false [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: computing optimal result [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: registerChildrenArray: org.openide.nodes.ChildrenArray@2 null weak: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: pointed by: org.openide.nodes.ChildrenArray@2 null to: org.openide.nodes.EntrySupportDefault$ChArrRef@3 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Initialize org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: before addNotify [org.openide.util.RequestProcessor] THREAD: cleanup MSG: Executing RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.RequestProcessor] THREAD: cleanup MSG: notifyRunning: RequestProcessor.Task [cleanup, 1] for task org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.nodes.Children] THREAD: cleanup MSG: before cleanup [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 2, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.nodes.Children] THREAD: cleanup MSG: waiting for reference: null [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: setEntries for org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: values: [Children.Keys.KE[1,0], Children.Array.AE[]] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: holder: org.openide.nodes.ChildrenArray@2 null [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: mustNotifySetEntries: false [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 2 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: after addNotify [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: addNotify successfully called for org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: notifyAll for org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 null on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] notifyLater: false [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: notifyAll done [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: optimal result is here: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: getNodes() org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 3, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Map initialized [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Put: Children.Keys.KE[1,0] info: Children.Info[Children.Keys.KE[1,0],length=0] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: nodesFor(Children.Info[Children.Keys.KE[1,0],length=0][917024b]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: map size=0, nodes=null [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: createNodes for 1 [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: reference created: org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1] [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: reference notified [org.openide.nodes.Children] THREAD: cleanup MSG: reference is here: java.lang.ref.WeakReference@5 [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: created nodes=[org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1]] [org.openide.nodes.Children] THREAD: cleanup MSG: its value is org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: leaving nodesFor(Children.Info[Children.Keys.KE[1,0],length=1][917024b]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Put: Children.Array.AE[] info: Children.Info[Children.Array.AE[],length=0] [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176860145; tasks=null [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: nodesFor(Children.Info[Children.Array.AE[],length=0][18761e4d]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: map size=1, nodes=null [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: created nodes=[] [org.openide.nodes.ChildrenArray] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: leaving nodesFor(Children.Info[Children.Array.AE[],length=0][18761e4d]) on Thread[Test Watch Dog: testGCWhenGetNodesTrue,5,main] [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: registerChildrenArray: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 weak: true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 3 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: length : 1 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: init now : true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 3, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 3 [java.awt.event.EventDispatchThread] THREAD: AWT-EventQueue-0 MSG: Dispatching: sun.awt.AWTAutoShutdown$1[] on sun.awt.AWTAutoShutdown@6 [java.awt.event.EventDispatchThread] THREAD: AWT-EventQueue-0 MSG: removing the event filter: java.awt.EventDispatchThread$HierarchyEventFilter@7 [org.openide.util.lookup.implspi.ActiveQueue] THREAD: Active Reference Queue Daemon MSG: dequeued reference [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: length : 1 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: init now : true [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering 2, true [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering exit: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: Find child got: org.openide.nodes.AbstractNode[name=1][Name=1, displayName=1] [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: previous array: org.openide.nodes.EntrySupportDefault$ChArrRef@9 caller: org.openide.nodes.EntrySupportDefault$ChArrRef@9 [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: t: Test Watch Dog: testGCWhenGetNodesTrue interrupts: cleanup [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: t: Test Watch Dog: testGCWhenGetNodesTrue log: after findChild: true waiting [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering 2, true [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Entering exit: true [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: setEntries for org.openide.nodes.FilterNode$Children$DefaultSupport@a array: null on Thread[Active Reference Queue Daemon,1,main] [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: values: [Children.Array.AE[]] [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: holder: null [org.openide.nodes.EntrySupportDefault] THREAD: Active Reference Queue Daemon MSG: mustNotifySetEntries: false [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving 2 [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving exit: 2 [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving 2 [org.openide.util.Mutex] THREAD: Active Reference Queue Daemon MSG: [#2@8] Leaving exit: 0 [test.testGCWhenGetNodesTrue] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: t: Test Watch Dog: testGCWhenGetNodesTrue log: after findChild: true timeout [org.openide.nodes.Children] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: after findChild: true [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: getNodes() org.openide.nodes.EntrySupportDefault@1 array: org.openide.nodes.ChildrenArray@2 org.openide.nodes.ChildrenKeysTest$3K@0 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering 3, true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Entering exit: true [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving 3 [org.openide.util.Mutex] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: [#2@4] Leaving exit: 0 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: length : 1 [org.openide.nodes.EntrySupportDefault] THREAD: Test Watch Dog: testGCWhenGetNodesTrue MSG: init now : true [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176865322; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176870772; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176873484; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176873984; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176874485; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176874986; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176875499; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176876000; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176876500; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176877001; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176884627; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176885128; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176885629; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176886129; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176886630; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176887131; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176887632; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176888173; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176888788; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176889325; tasks=null [sun.awt.X11.timeoutTask.XToolkit] THREAD: AWT-XAWT MSG: XToolkit.callTimeoutTasks(): current time=1371176889833; tasks=null at org.netbeans.junit.NbTestCase$1Guard.waitFinished(NbTestCase.java:398) at org.netbeans.junit.NbTestCase.runBare(NbTestCase.java:456) at org.netbeans.junit.NbTestCase.run(NbTestCase.java:288)