AppContext issues breaks -XstartOnFirstThread for multithreaded programs on both 6_51 and 7_25

Fri Jun 28 05:57:35 PDT 2013

Hi,

I wanted to weigh in a bit since although many people are complaining about the AWT/Swing and webstart issues, it goes much further than that. The AppContext can trigger AWT loading from many locations even as simple as java.util.logging or jmx. In my mind I find it extremely suspicious that these low level utility classes should try to do anything with AWT, especially considering server apps.

This test program results in a hang in the spawned thread trying to load in one of the 3 places both on Apple java 1.6.0_51 (with and without the Apple 09 hotfix) and Java 1.7.0_25. It runs as normal java applications, no applet or webstart required, no security manager set, Just add -XstartOnFirstThread to the commandline.

import java.lang.management.ManagementFactory;
import java.util.logging.Logger;

import javax.imageio.spi.IIORegistry;
import javax.management.MBeanServer;

/**
 * @author evanherc
 */
public class AppContextJVMBug {

public static void main(String[] args) {
try {
System.out.println("Launching");

final Thread t = new Thread(new Runnable() {
public void run() {
try {
utilLoggingHang();
jmxHang();
imageioHang();
} catch (Throwable t) {
t.printStackTrace();
}
}
});

t.start();
t.join();
System.out.println("Finished");
} catch (Throwable t) {
t.printStackTrace();
}
}

private static void utilLoggingHang() {
final Logger l = Logger.getLogger("MyApp");
System.out.println("Done obtaining a logger " + l);
}

private static void jmxHang() {
final MBeanServer mb = ManagementFactory.getPlatformMBeanServer();
System.out.println("Done getting the platform mbean server " + mb);
}

private static void imageioHang() {
final IIORegistry registry = IIORegistry.getDefaultInstance();
System.out.println("Done retrieving image registry " + registry);
}
}

Tested on several Apple Mac OS X 10.8.4 machines.
Without JVM arguments : runs fine
With -XstartOnFirstThread : hangs
With -XstartOnFirstThread and -Djava.awt.headless=true : runs fine

Here's actual stack traces from our large product suites. They break for everyone as we run inside Eclipse and as such have to specify -XstartOnFirstThread.
I've seen a dozen variants now (there is other classes triggering AppContext as well).

"Thread-4" daemon prio=5 tid=7febb49ae800 nid=0x10f2fb000 runnable [10f2f6000]
   java.lang.Thread.State: RUNNABLE
at java.lang.ClassLoader$NativeLibrary.load(Native Method)
at java.lang.ClassLoader.loadLibrary0(ClassLoader.java:1827)
- locked <7dc02aec0> (a java.util.Vector)
- locked <7dc02aee0> (a java.util.Vector)
at java.lang.ClassLoader.loadLibrary(ClassLoader.java:1724)
at java.lang.Runtime.loadLibrary0(Runtime.java:823)
- locked <7dc02cb28> (a java.lang.Runtime)
at java.lang.System.loadLibrary(System.java:1045)
at sun.security.action.LoadLibraryAction.run(LoadLibraryAction.java:50)
at java.security.AccessController.doPrivileged(Native Method)
at java.awt.Toolkit.loadLibraries(Toolkit.java:1605)
at java.awt.Toolkit.<clinit>(Toolkit.java:1627)
at sun.awt.AppContext$2.run(AppContext.java:240)
at sun.awt.AppContext$2.run(AppContext.java:226)
at java.security.AccessController.doPrivileged(Native Method)
at sun.awt.AppContext.initMainAppContext(AppContext.java:226)
at sun.awt.AppContext.access$200(AppContext.java:112)
at sun.awt.AppContext$3.run(AppContext.java:306)
at java.security.AccessController.doPrivileged(Native Method)
at sun.awt.AppContext.getAppContext(AppContext.java:287)
at com.sun.jmx.trace.Trace.out(Trace.java:180)
- locked <7f8eea490> (a java.lang.Class for com.sun.jmx.trace.Trace)
at com.sun.jmx.trace.Trace.isSelected(Trace.java:88)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.isTraceOn(DefaultMBeanServerInterceptor.java:1830)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:929)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerObject(DefaultMBeanServerInterceptor.java:916)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerMBean(DefaultMBeanServerInterceptor.java:312)
at com.sun.jmx.mbeanserver.JmxMBeanServer$2.run(JmxMBeanServer.java:1195)
at java.security.AccessController.doPrivileged(Native Method)
at com.sun.jmx.mbeanserver.JmxMBeanServer.initialize(JmxMBeanServer.java:1193)
at com.sun.jmx.mbeanserver.JmxMBeanServer.<init>(JmxMBeanServer.java:225)
at com.sun.jmx.mbeanserver.JmxMBeanServer.<init>(JmxMBeanServer.java:170)
at com.sun.jmx.mbeanserver.JmxMBeanServer.newMBeanServer(JmxMBeanServer.java:1401)
at javax.management.MBeanServerBuilder.newMBeanServer(MBeanServerBuilder.java:93)
at javax.management.MBeanServerFactory.newMBeanServer(MBeanServerFactory.java:311)
- locked <7d824f618> (a javax.management.MBeanServerBuilder)
at javax.management.MBeanServerFactory.createMBeanServer(MBeanServerFactory.java:214)
at javax.management.MBeanServerFactory.createMBeanServer(MBeanServerFactory.java:175)
at sun.management.ManagementFactory.createPlatformMBeanServer(ManagementFactory.java:302)
at java.lang.management.ManagementFactory.getPlatformMBeanServer(ManagementFactory.java:504)
- locked <7f8e00140> (a java.lang.Class for java.lang.management.ManagementFactory)
at id.core.version.provider.ProductVersionProvider.<init>(ProductVersionProvider.java:68)
at id.core.version.provider.ProductVersionProvider.getInstance(ProductVersionProvider.java:37)
- locked <7f8dd00a8> (a java.lang.Class for id.core.version.provider.ProductVersionProvider)
at id.core.version.VersionInfo.getProductID(VersionInfo.java:98)
at com.id.core.platform.util.application.ProductDirectories.calculateCurrentUserProgramDir(ProductDirectories.java:31)
at com.id.core.platform.util.application.ProductDirectories.<clinit>(ProductDirectories.java:22)
at com.id.core.platform.repository.BundleRepository.parsePluginFile(BundleRepository.java:235)
at com.id.core.platform.repository.BundleRepository.<init>(BundleRepository.java:101)
at com.id.core.platform.Platform.bootstrap(Platform.java:178)
- locked <7d81cb4c8> (a java.lang.Object)
- locked <7d81cb4a8> (a com.id.core.platform.Platform)
at com.id.core.platform.activator.Activator$1.run(Activator.java:77)
- locked <7d81aca68> (a java.lang.Object)

"Thread-2" prio=5 tid=0x00007f9973093800 nid=0x7e03 runnable [0x0000000137b2f000]
   java.lang.Thread.State: RUNNABLE
at java.lang.ClassLoader$NativeLibrary.load(Native Method)
at java.lang.ClassLoader.loadLibrary1(ClassLoader.java:1957)
- locked <0x0000000128bc39a8> (a java.util.Vector)
- locked <0x0000000128bc3610> (a java.util.Vector)
at java.lang.ClassLoader.loadLibrary0(ClassLoader.java:1882)
at java.lang.ClassLoader.loadLibrary(ClassLoader.java:1843)
at java.lang.Runtime.load0(Runtime.java:795)
- locked <0x0000000110a457a8> (a java.lang.Runtime)
at java.lang.System.load(System.java:1061)
at java.lang.ClassLoader$NativeLibrary.load(Native Method)
at java.lang.ClassLoader.loadLibrary1(ClassLoader.java:1957)
- locked <0x0000000128bc39a8> (a java.util.Vector)
- locked <0x0000000128bc3610> (a java.util.Vector)
at java.lang.ClassLoader.loadLibrary0(ClassLoader.java:1882)
at java.lang.ClassLoader.loadLibrary(ClassLoader.java:1864)
at java.lang.Runtime.loadLibrary0(Runtime.java:849)
- locked <0x0000000110a457a8> (a java.lang.Runtime)
at java.lang.System.loadLibrary(System.java:1087)
at sun.security.action.LoadLibraryAction.run(LoadLibraryAction.java:67)
at sun.security.action.LoadLibraryAction.run(LoadLibraryAction.java:47)
at java.security.AccessController.doPrivileged(Native Method)
at java.awt.Toolkit.loadLibraries(Toolkit.java:1646)
at java.awt.Toolkit.<clinit>(Toolkit.java:1668)
at sun.awt.AppContext$2.run(AppContext.java:271)
at sun.awt.AppContext$2.run(AppContext.java:260)
at java.security.AccessController.doPrivileged(Native Method)
at sun.awt.AppContext.initMainAppContext(AppContext.java:260)
at sun.awt.AppContext.access$200(AppContext.java:133)
at sun.awt.AppContext$3.run(AppContext.java:316)
at sun.awt.AppContext$3.run(AppContext.java:298)
at java.security.AccessController.doPrivileged(Native Method)
at sun.awt.AppContext.getAppContext(AppContext.java:297)
at javax.imageio.spi.IIORegistry.getDefaultInstance(IIORegistry.java:154)
at com.id.external.javax.media.jai.Services.register(Services.java:20)
at com.id.external.javax.media.jai.activator.Activator.start(Activator.java:26)
at org.eclipse.osgi.framework.internal.core.BundleContextImpl$1.run(BundleContextImpl.java:711)
at java.security.AccessController.doPrivileged(Native Method)
at org.eclipse.osgi.framework.internal.core.BundleContextImpl.startActivator(BundleContextImpl.java:702)
at org.eclipse.osgi.framework.internal.core.BundleContextImpl.start(BundleContextImpl.java:683)
at org.eclipse.osgi.framework.internal.core.BundleHost.startWorker(BundleHost.java:381)
at org.eclipse.osgi.framework.internal.core.AbstractBundle.start(AbstractBundle.java:299)
at org.eclipse.osgi.framework.internal.core.AbstractBundle.start(AbstractBundle.java:291)
at com.id.core.platform.repository.BundleRepository.startBundle(BundleRepository.java:486)
at com.id.core.platform.repository.BundleRepository.handleResolvedBundle(BundleRepository.java:389)
at com.id.core.platform.repository.BundleRepository.scanBundles(BundleRepository.java:269)
at com.id.core.platform.repository.BundleRepository.start(BundleRepository.java:118)
- locked <0x00000001259d7d20> (a java.lang.Object)
at com.id.core.platform.Platform.bootstrap(Platform.java:184)
- locked <0x00000001259d4000> (a java.lang.Object)
- locked <0x00000001259d3fe0> (a com.id.core.platform.Platform)
at com.id.core.platform.activator.Activator$1.run(Activator.java:77)
- locked <0x00000001259ce758> (a java.lang.Object)

I've been looking in the source but not sure if those fixes are already in the jdk 1.7u forest.

Erik Vanherck

________________________________

Inventive Designers' Email Disclaimer:
http://www.inventivedesigners.com/email-disclaimer