JDK-8196394 : [macosx] Test java/awt/List/EmptyListEventTest/EmptyListEventTest.java with timeout error
  • Type: Bug
  • Component: client-libs
  • Sub-Component: java.awt
  • Affected Version: 10
  • Priority: P4
  • Status: Closed
  • Resolution: Cannot Reproduce
  • OS: os_x
  • CPU: x86
  • Submitted: 2018-01-30
  • Updated: 2018-02-16
  • Resolved: 2018-02-14
The Version table provides details related to the release that this issue/RFE will be addressed.

Unresolved : Release in which this issue/RFE will be addressed.
Resolved: Release in which this issue/RFE has been resolved.
Fixed : Release in which this issue/RFE has been fixed. The release containing this fix may be available for download as an Early Access Release or a General Availability Release.

To download the current JDK release, click here.
Other
tbd_majorResolved
Related Reports
Relates :  
JDK-6366126 - List throws ArrayIndexOutOfBoundsException when pressing ENTER after removing all the items, Win32
Relates :  
JDK-2230621 - [macosx] Test fail like 6366126 (ArrayIndexOutOfBoundException pressing ENTER after removing items)
Relates :  
JDK-7124515 - [macosx] Test fail like 6366126 (ArrayIndexOutOfBoundException pressing ENTER after removing items)
Description
PROBLEM DESCRIPTION:
The test fails if run in group like "java/awt/List/", but passes if run individually.

OS VERSION INFORMATION :
macOS Sierra 10.12.6

ERROR : 
#Test Results (version 2)
#Tue Jan 30 15:20:40 IST 2018
#-----testdescription-----
$file=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/java/awt/List/EmptyListEventTest/EmptyListEventTest.java
$root=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk
author=Dmitry Cherepanov area\=awt.list
keywords=bug6366126 headful
modules=java.desktop
run=USER_SPECIFIED main EmptyListEventTest\n
source=EmptyListEventTest.java
title=List throws ArrayIndexOutOfBoundsException when pressing ENTER after removing all the items, Win32

#-----environment-----

#-----testresult-----
description=file\:/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/java/awt/List/EmptyListEventTest/EmptyListEventTest.java
elapsed=120379 0\:02\:00.379
end=Tue Jan 30 15\:20\:40 IST 2018
environment=regtest
execStatus=Error. Program `/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk/bin/java' timed out (timeout set to 120000ms, elapsed time including timeout handling was 120369ms).
harnessLoaderMode=Classpath Loader
harnessVariety=Full Bundle
hostname=dhcp-10-191-232-51.vpn.oracle.com
javatestOS=Mac OS X 10.12.6 (x86_64)
javatestVersion=5.0
jtregVersion=jtreg 4.2 fcs b11
modules=java.desktop
script=com.sun.javatest.regtest.exec.RegressionScript
sections=script_messages build main
start=Tue Jan 30 15\:18\:40 IST 2018
test=java/awt/List/EmptyListEventTest/EmptyListEventTest.java
testJDK=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk
totalTime=120380
user.name=manajit
work=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/JTwork/java/awt/List/EmptyListEventTest

#section:script_messages
----------messages:(4/343)----------
JDK under test: /Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk
java version "10-internal" 2018-03-20
Java(TM) SE Runtime Environment 18.3 (build 10-internal+0-adhoc.manajit.open)
Java HotSpot(TM) 64-Bit Server VM 18.3 (build 10-internal+0-adhoc.manajit.open, mixed mode)

#section:build
----------messages:(3/101)----------
command: build EmptyListEventTest
reason: Named class compiled on demand
elapsed time (seconds): 0.0
result: Passed. All files up to date

#section:main
----------messages:(169/10084)----------
command: main EmptyListEventTest
reason: User specified action: run main EmptyListEventTest 
Mode: othervm
Additional options from @modules: --add-modules java.desktop
Timeout information:
Running jstack on process 2801
2018-01-30 15:20:40
Full thread dump Java HotSpot(TM) 64-Bit Server VM (10-internal+0-adhoc.manajit.open mixed mode):

Threads class SMR info:
_java_thread_list=0x00007fdf73741220, length=17, elements={
0x00007fdf73800800, 0x00007fdf748cd800, 0x00007fdf748b3800, 0x00007fdf748d5000,
0x00007fdf748d8000, 0x00007fdf7502f000, 0x00007fdf7503a800, 0x00007fdf73850000,
0x00007fdf75038800, 0x00007fdf75039000, 0x00007fdf749aa800, 0x00007fdf738ad000,
0x00007fdf74ab1800, 0x00007fdf74af5000, 0x00007fdf75211800, 0x00007fdf7403b800,
0x00007fdf74312000
}

"main" #1 prio=5 os_prio=31 tid=0x00007fdf73800800 nid=0x2203 in Object.wait()  [0x0000000102239000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(java.base@10-internal/Native Method)
	- waiting on <0x00000006cf110580> (a java.lang.Thread)
	at java.lang.Thread.join(java.base@10-internal/Thread.java:1353)
	- waiting to re-lock in wait() <0x00000006cf110580> (a java.lang.Thread)
	at java.lang.Thread.join(java.base@10-internal/Thread.java:1427)
	at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:74)

"Reference Handler" #2 daemon prio=10 os_prio=31 tid=0x00007fdf748cd800 nid=0x4703 waiting on condition  [0x00007000033a3000]
   java.lang.Thread.State: RUNNABLE
	at java.lang.ref.Reference.waitForReferencePendingList(java.base@10-internal/Native Method)
	at java.lang.ref.Reference.processPendingReferences(java.base@10-internal/Reference.java:166)
	at java.lang.ref.Reference.access$000(java.base@10-internal/Reference.java:44)
	at java.lang.ref.Reference$ReferenceHandler.run(java.base@10-internal/Reference.java:138)

"Finalizer" #3 daemon prio=8 os_prio=31 tid=0x00007fdf748b3800 nid=0x4a03 in Object.wait()  [0x00007000034a6000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(java.base@10-internal/Native Method)
	- waiting on <0x00000006cf1103e0> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(java.base@10-internal/ReferenceQueue.java:151)
	- waiting to re-lock in wait() <0x00000006cf1103e0> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(java.base@10-internal/ReferenceQueue.java:172)
	at java.lang.ref.Finalizer$FinalizerThread.run(java.base@10-internal/Finalizer.java:216)

"Signal Dispatcher" #4 daemon prio=9 os_prio=31 tid=0x00007fdf748d5000 nid=0x5b03 runnable  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" #5 daemon prio=9 os_prio=31 tid=0x00007fdf748d8000 nid=0x5e03 waiting on condition  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE
   No compile task

"C2 CompilerThread1" #6 daemon prio=9 os_prio=31 tid=0x00007fdf7502f000 nid=0x6103 waiting on condition  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE
   No compile task

"C1 CompilerThread2" #7 daemon prio=9 os_prio=31 tid=0x00007fdf7503a800 nid=0x6403 waiting on condition  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE
   No compile task

"Sweeper thread" #8 daemon prio=9 os_prio=31 tid=0x00007fdf73850000 nid=0x6703 runnable  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Service Thread" #9 daemon prio=9 os_prio=31 tid=0x00007fdf75038800 nid=0x6a03 runnable  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Common-Cleaner" #10 daemon prio=8 os_prio=31 tid=0x00007fdf75039000 nid=0x6d03 in Object.wait()  [0x0000700003bbb000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
	at java.lang.Object.wait(java.base@10-internal/Native Method)
	- waiting on <no object reference available>
	at java.lang.ref.ReferenceQueue.remove(java.base@10-internal/ReferenceQueue.java:151)
	- waiting to re-lock in wait() <0x00000006cf1342f8> (a java.lang.ref.ReferenceQueue$Lock)
	at jdk.internal.ref.CleanerImpl.run(java.base@10-internal/CleanerImpl.java:148)
	at java.lang.Thread.run(java.base@10-internal/Thread.java:844)
	at jdk.internal.misc.InnocuousThread.run(java.base@10-internal/InnocuousThread.java:134)

"MainThread" #12 prio=5 os_prio=31 tid=0x00007fdf749aa800 nid=0x7203 in Object.wait()  [0x0000700003dc1000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(java.base@10-internal/Native Method)
	- waiting on <0x00000006cf008c88> (a java.lang.Object)
	at java.lang.Object.wait(java.base@10-internal/Object.java:328)
	at sun.awt.SunToolkit.waitForIdle(java.desktop@10-internal/SunToolkit.java:1565)
	- waiting to re-lock in wait() <0x00000006cf008c88> (a java.lang.Object)
	at sun.awt.SunToolkit.realSync(java.desktop@10-internal/SunToolkit.java:1489)
	at sun.awt.SunToolkit.realSync(java.desktop@10-internal/SunToolkit.java:1402)
	at java.awt.Robot.waitForIdle(java.desktop@10-internal/Robot.java:692)
	- locked <0x00000006cf1109d0> (a java.awt.Robot)
	at EmptyListEventTest.main(EmptyListEventTest.java:55)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@10-internal/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@10-internal/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@10-internal/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@10-internal/Method.java:564)
	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:115)
	at java.lang.Thread.run(java.base@10-internal/Thread.java:844)

"AppKit Thread" #13 daemon prio=5 os_prio=31 tid=0x00007fdf738ad000 nid=0x307 runnable  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"AWT-Shutdown" #14 prio=5 os_prio=31 tid=0x00007fdf74ab1800 nid=0x9e03 in Object.wait()  [0x0000700003ec4000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(java.base@10-internal/Native Method)
	- waiting on <0x00000006cf134460> (a java.lang.Object)
	at java.lang.Object.wait(java.base@10-internal/Object.java:328)
	at sun.awt.AWTAutoShutdown.run(java.desktop@10-internal/AWTAutoShutdown.java:291)
	- waiting to re-lock in wait() <0x00000006cf134460> (a java.lang.Object)
	at java.lang.Thread.run(java.base@10-internal/Thread.java:844)

"Java2D Queue Flusher" #15 daemon prio=10 os_prio=31 tid=0x00007fdf74af5000 nid=0xff03 in Object.wait()  [0x0000700004150000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
	at java.lang.Object.wait(java.base@10-internal/Native Method)
	- waiting on <no object reference available>
	at sun.java2d.opengl.OGLRenderQueue$QueueFlusher.run(java.desktop@10-internal/OGLRenderQueue.java:205)
	- waiting to re-lock in wait() <0x00000006cf134900> (a sun.java2d.opengl.OGLRenderQueue$QueueFlusher)
	at java.lang.Thread.run(java.base@10-internal/Thread.java:844)

"Java2D Disposer" #16 daemon prio=10 os_prio=31 tid=0x00007fdf75211800 nid=0x10c03 in Object.wait()  [0x0000700004253000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(java.base@10-internal/Native Method)
	- waiting on <0x00000006cf134b08> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(java.base@10-internal/ReferenceQueue.java:151)
	- waiting to re-lock in wait() <0x00000006cf134b08> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(java.base@10-internal/ReferenceQueue.java:172)
	at sun.java2d.Disposer.run(java.desktop@10-internal/Disposer.java:144)
	at java.lang.Thread.run(java.base@10-internal/Thread.java:844)

"AWT-EventQueue-0" #17 prio=6 os_prio=31 tid=0x00007fdf7403b800 nid=0x10e0f waiting on condition  [0x0000700004356000]
   java.lang.Thread.State: WAITING (parking)
	at jdk.internal.misc.Unsafe.park(java.base@10-internal/Native Method)
	- parking to wait for  <0x00000006cf1c7e68> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
	at java.util.concurrent.locks.LockSupport.park(java.base@10-internal/LockSupport.java:194)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@10-internal/AbstractQueuedSynchronizer.java:2081)
	at java.awt.EventQueue.getNextEvent(java.desktop@10-internal/EventQueue.java:566)
	at java.awt.EventDispatchThread.pumpOneEventForFilters(java.desktop@10-internal/EventDispatchThread.java:190)
	at java.awt.EventDispatchThread.pumpEventsForFilter(java.desktop@10-internal/EventDispatchThread.java:124)
	at java.awt.EventDispatchThread.pumpEventsForHierarchy(java.desktop@10-internal/EventDispatchThread.java:113)
	at java.awt.EventDispatchThread.pumpEvents(java.desktop@10-internal/EventDispatchThread.java:109)
	at java.awt.EventDispatchThread.pumpEvents(java.desktop@10-internal/EventDispatchThread.java:101)
	at java.awt.EventDispatchThread.run(java.desktop@10-internal/EventDispatchThread.java:90)

"Attach Listener" #18 daemon prio=9 os_prio=31 tid=0x00007fdf74312000 nid=0xcd27 waiting on condition  [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"VM Thread" os_prio=31 tid=0x00007fdf748ad000 nid=0x4403 runnable  

"GC Thread#0" os_prio=31 tid=0x00007fdf7480e800 nid=0x2c03 runnable  

"GC Thread#1" os_prio=31 tid=0x00007fdf7480f800 nid=0x2e03 runnable  

"GC Thread#2" os_prio=31 tid=0x00007fdf74810000 nid=0x3003 runnable  

"GC Thread#3" os_prio=31 tid=0x00007fdf74811000 nid=0x3203 runnable  

"G1 Main Marker" os_prio=31 tid=0x00007fdf73837000 nid=0x3403 runnable  

"G1 Conc#0" os_prio=31 tid=0x00007fdf75000000 nid=0x3803 runnable  

"G1 Refine#0" os_prio=31 tid=0x00007fdf7487e000 nid=0x3a03 runnable  

"G1 Refine#1" os_prio=31 tid=0x00007fdf7487e800 nid=0x3c03 runnable  

"G1 Refine#2" os_prio=31 tid=0x00007fdf74005000 nid=0x3e03 runnable  

"G1 Refine#3" os_prio=31 tid=0x00007fdf7487f800 nid=0x4003 runnable  

"G1 Young RemSet Sampling" os_prio=31 tid=0x00007fdf74880000 nid=0x4203 runnable  
"VM Periodic Task Thread" os_prio=31 tid=0x00007fdf74007800 nid=0x6f03 waiting on condition  

JNI global references: 107

--- Timeout information end.
elapsed time (seconds): 120.376
----------configuration:(3/40)----------
Boot Layer
  add modules: java.desktop

----------System.out:(4/600)----------
setCanFullScreen: Current window is javax.swing.JFrame[frame0,0,23,200x200,invalid,hidden,layout=java.awt.BorderLayout,title=,resizable,normal,defaultCloseOperation=EXIT_ON_CLOSE,rootPane=javax.swing.JRootPane[,0,0,0x0,invalid,layout=javax.swing.JRootPane$RootLayout,alignmentX=0.0,alignmentY=0.0,border=,flags=16777673,maximumSize=,minimumSize=,preferredSize=],rootPaneCheckingEnabled=true]
sun.awt.TimedWindowEvent[WINDOW_GAINED_FOCUS,opposite=null,oldState=0,newState=0] on frame0
java.awt.event.FocusEvent[FOCUS_GAINED,permanent,opposite=null,cause=ACTIVATION] on list0
Timeout refired 120 times
----------System.err:(9/993)----------
2018-01-30 15:18:40.862 java[2801:358846] nativeSetNSWindowBounds: Current Window is <AWTWindow: 0x7fdf735799e0>
2018-01-30 15:18:40.889 java[2801:358889] nativeSetNSWindowStandardFrame: called with 0.000000 23.000000 1280.000000 773.000000
2018-01-30 15:18:40.893 java[2801:358846] nativeSetNSWindowStandardFrame: before ConvertNSScreenRect called
2018-01-30 15:18:40.893 java[2801:358846] nativeSetNSWindowStandardFrame: before assigning rect
2018-01-30 15:18:40.893 java[2801:358846] nativeSetNSWindowStandardFrame: after window.standardFrame is set
2018-01-30 15:18:40.894 java[2801:358889] nativeSetNSWindowStandardFrame: called with 0.000000 23.000000 1280.000000 773.000000
2018-01-30 15:18:40.894 java[2801:358846] nativeSetNSWindowStandardFrame: before ConvertNSScreenRect called
2018-01-30 15:18:40.894 java[2801:358846] nativeSetNSWindowStandardFrame: before assigning rect
2018-01-30 15:18:40.894 java[2801:358846] nativeSetNSWindowStandardFrame: after window.standardFrame is set
----------rerun:(21/1953)*----------
cd /Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/JTwork/scratch && \\
DISPLAY=/private/tmp/com.apple.launchd.JmajofwcK4/org.macosforge.xquartz:0 \\
HOME=/Users/manajit \\
LC_CTYPE=UTF-8 \\
PATH=/bin:/usr/bin \\
CLASSPATH=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/JTwork/classes/java/awt/List/EmptyListEventTest/EmptyListEventTest.d:/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/java/awt/List/EmptyListEventTest:/Volumes/Data/Code/osx1012/fixjdk10/jtreg.4.2.b11/lib/javatest.jar:/Volumes/Data/Code/osx1012/fixjdk10/jtreg.4.2.b11/lib/jtreg.jar \\
    /Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk/bin/java \\
        -Dtest.src=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/java/awt/List/EmptyListEventTest \\
        -Dtest.src.path=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/java/awt/List/EmptyListEventTest \\
        -Dtest.classes=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/JTwork/classes/java/awt/List/EmptyListEventTest/EmptyListEventTest.d \\
        -Dtest.class.path=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/JTwork/classes/java/awt/List/EmptyListEventTest/EmptyListEventTest.d \\
        -Dtest.vm.opts= \\
        -Dtest.tool.vm.opts= \\
        -Dtest.compiler.opts= \\
        -Dtest.java.opts= \\
        -Dtest.jdk=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk \\
        -Dcompile.jdk=/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk \\
        -Dtest.timeout.factor=1.0 \\
        -Dtest.modules=java.desktop \\
        --add-modules java.desktop \\
        com.sun.javatest.regtest.agent.MainWrapper /Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/JTwork/java/awt/List/EmptyListEventTest/EmptyListEventTest.d/main.0.jta
result: Error. Program `/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk/bin/java' timed out (timeout set to 120000ms, elapsed time including timeout handling was 120369ms).


test result: Error. Program `/Volumes/Data/Code/osx1012/fixjdk10/client/open/test/jdk/../../../build/macosx-x86_64-normal-server-release/images/jdk/bin/java' timed out (timeout set to 120000ms, elapsed time including timeout handling was 120369ms).
Comments
Closing this issue as the issue was observed only once out of several rounds of test execution. Tried running List folder (java/awt/List) more than 10 using jtreg but the problem was not seen second time.
14-02-2018