hang on startup with IBM JVM

Questions about YourKit Java Profiler
Post Reply
trentgd
Posts: 1
Joined: Fri Aug 25, 2017 10:06 pm

hang on startup with IBM JVM

Post by trentgd »

I'm using the IBM JVM on Windows, and do a simple:

c:\>java "-agentpath:\Program Files\YourKit Java Profiler 2017.02-b63\bin\win64\yjpagent.dll=sampling" -jar c:\trent\bin\demo\SwingSet2.jar

(the SwingSet2.jar is from an old Java SDK)

I'm doing it this way as I want to capture a fully headless snapshot of the run - beginning to end.

It hangs after bringing up the splash screen. I wait about 4 seconds then hit CTRL-C. (I've waited up to 30s before but it doesn't help).

The agent log is:
17.02-63 0.002: Log file: C:\Users\IBM_ADMIN\.yjp\log\SwingSet2-20368.log
17.02-63 0.002: Command line:
java
-agentpath:\Program Files\YourKit Java Profiler 2017.02-b63\bin\win64\yjpagent.dll=sampling
-jar
c:\trent\bin\demo\SwingSet2.jar
17.02-63 0.002: Executable: C:\windows\SYSTEM32\java.exe
17.02-63 0.002: Windows 7; version 6.1.7601; 64-bit process
17.02-63 0.003: hid: 16022DUV4V1338XYZ85Y941019560143ZX6ZWXX58Z 64-bit machine
17.02-63 0.003: Today is 20170825
17.02-63 0.003: full_build_stamp: 2017.02.63 201707041253
17.02-63 0.003: Agent library path: c:\Program Files\YourKit Java Profiler 2017.02-b63\bin\win64\yjpagent.dll
17.02-63 0.003: getTimeNsInfo: "PerformanceFrequency=2533378"
17.02-63 0.003: Agent_OnLoad: orig_options=sampling
full_options=sampling
17.02-63 0.003: phase: 1
17.02-63 0.003: jvmtiVersion: 30010203
17.02-63 0.003: vendor: IBM Corporation
17.02-63 0.003: version: 2.8
17.02-63 0.003: info: 20170516_348050
17.02-63 0.003: isJava6: 0
17.02-63 0.003: session_id: 87481397582792 (1503697864,20368)
17.02-63 0.004: line_method: 1
17.02-63 0.004: can_generate_exception_events: disabled by default on IBM - specify startup option "exceptions=on" or "exceptions=off" to override
17.02-63 0.004: can_access_local_variables: disabled by default on IBM - specify startup option "_with_local_vars" to override
17.02-63 0.014: Reusing existing jar file: C:\Users\IBM_AD~1\AppData\Local\Temp\yjp201707041253.jar
17.02-63 0.015: JVMTI 30010203; 2.8; IBM Corporation; 20170516_348050; [IBM]
17.02-63 0.250: Successfully registered probes: 28
Registered: .AWTEvents
Registered: .AgentLoadingSupport
Registered: .AsyncChannels
Registered: .CPUTracingSupport
Registered: .Cassandra
Registered: .CassandraLW
Registered: .Databases
Registered: .DatabasesLW
Registered: .Files
Registered: .FilesLW
Registered: .HBase
Registered: .HBaseLW
Registered: .JNDI
Registered: .JPA_DataNucleus
Registered: .JPA_EclipseLink
Registered: .JPA_Hibernate
Registered: .JPA_OpenJPA
Registered: .JUnitTests
Registered: .MongoDB
Registered: .MongoDBLW
Registered: .Processes
Registered: .Servlets
Registered: .ServletsLW
Registered: .Sockets
Registered: .SocketsLW
Registered: .Sync
Registered: .TestNG
Registered: .Threads
17.02-63 0.435: tracing=off: large method: 5377 sun.nio.cs.StandardCharsets$Aliases.init(java.lang.Object[])
17.02-63 0.439: tracing=off: large method: 8780 sun.nio.cs.ext.ExtendedCharsets.<init>()
17.02-63 0.605: agentStart: 1
17.02-63 0.605: agentStart: 2
17.02-63 0.605: agentStart: OK
17.02-63 0.605: vmInit: 1
17.02-63 0.606: vmInit: 2
17.02-63 0.606: agentInit: 1
17.02-63 0.607: agentInit: Core loaded
17.02-63 0.624: setTriggers:

17.02-63 0.628: setTriggers: to unregister: 0
17.02-63 0.628: setTriggers: method listeners to register: 0
17.02-63 0.628: setTriggers: OK
17.02-63 0.628: ourUnsafe: true
17.02-63 0.628: shmem: Global\YOURKIT SHARE5 G: NULL (1)
17.02-63 0.629: shmem: Local\YOURKIT SHARE5 L: OK, isFirst=1
17.02-63 0.632: shmem: Global\YOURKIT SHARE2 G: NULL (1)
17.02-63 0.632: shmem: Local\YOURKIT SHARE2 L: OK, isFirst=1
17.02-63 0.687: Profiler agent is listening on port 10001
17.02-63 0.687: *** HINT ***: To get profiling results, connect to the application from the profiler UI
17.02-63 0.688: socket timeout: 30000
17.02-63 0.688: agentInit: Core.init() called
17.02-63 0.692: agentInit: OK
17.02-63 0.692: vmInit: 3
17.02-63 0.692: onStartProfiling: entering...
17.02-63 0.692: onStartProfiling: will start CPU sampling...
17.02-63 0.692: Events from Auto probes cleared:

17.02-63 0.692: SamplingSettings: sampling_period_ms = 20
walltime=java.io.RandomAccessFile : readBytes(byte[], int, int)
walltime=java.io.RandomAccessFile : read()
walltime=java.io.RandomAccessFile : write(int)
walltime=java.io.RandomAccessFile : writeBytes(byte[], int, int)
walltime=java.net.SocketInputStream : socketRead0(java.io.FileDescriptor, byte[], int, int, int)
walltime=java.net.SocketOutputStream : socketWrite0(java.io.FileDescriptor, byte[], int, int)

sampling_period_ms=20

17.02-63 0.731: retransformProbes: loaded classes: 728 probeClassNames: 117
17.02-63 0.732: Probe classes to retransform: 3
com.yourkit.probes.builtin.Files$FileInputStream_close_Probe
com.yourkit.probes.builtin.Files$FileInputStream_open_Probe
com.yourkit.probes.builtin.Files$FileInputStream_readBytes_Probe
17.02-63 0.734: Probe classes retransform completed
17.02-63 0.734: CPU sampling started: walltime=java.io.RandomAccessFile : readBytes(byte[], int, int)
walltime=java.io.RandomAccessFile : read()
walltime=java.io.RandomAccessFile : write(int)
walltime=java.io.RandomAccessFile : writeBytes(byte[], int, int)
walltime=java.net.SocketInputStream : socketRead0(java.io.FileDescriptor, byte[], int, int, int)
walltime=java.net.SocketOutputStream : socketWrite0(java.io.FileDescriptor, byte[], int, int)

sampling_period_ms=20

17.02-63 0.734: onStartProfiling: done
17.02-63 0.734: vmInit: OK
17.02-63 0.856: tracing=off: large method: 3123 javax.management.MBeanPermission.getMask(java.lang.String)
17.02-63 0.989: [m: 385 61] total classes: processed=1000 instrumented=875 343ms
17.02-63 1.082: tracing=off: large method: 3195 sun.awt.resources.awt.getContents()
17.02-63 1.111: tracing=off: large method: 7984 java.math.BigDecimal.dpd2bcdinit()
17.02-63 1.147: tracing=off: large method: 2259 jdk.internal.org.objectweb.asm.Frame.execute(int, int, jdk.internal.org.objectweb.asm.ClassWriter, jdk.internal.org.objectweb.asm.Item)
17.02-63 1.228: tracing=off: large method: 12091 javax.swing.plaf.metal.MetalLookAndFeel.initComponentDefaults(javax.swing.UIDefaults)
17.02-63 1.234: tracing=off: large method: 15365 javax.swing.plaf.basic.BasicLookAndFeel.initComponentDefaults(javax.swing.UIDefaults)
17.02-63 1.300: GCPauseCounter: not available
17.02-63 1.433: tracing=off: large method: 2458 javax.swing.SwingUtilities.computeDifference(java.awt.Rectangle, java.awt.Rectangle)
17.02-63 1.498: tracing=off: large method: 2462 java.awt.event.KeyEvent.getKeyText(int)
17.02-63 2.762: tracing=off: large method: 11832 com.sun.java.swing.plaf.motif.MotifLookAndFeel.initComponentDefaults(javax.swing.UIDefaults)
17.02-63 2.768: tracing=off: large method: 14077 com.sun.java.swing.plaf.windows.WindowsLookAndFeel.initComponentDefaults(javax.swing.UIDefaults)
17.02-63 2.819: [m: 443 101] total classes: processed=2000 instrumented=1690 699ms
17.02-63 6.868: vmDeath: 1
17.02-63 6.868: vmDeath: 2
17.02-63 6.868: vmDeath: 3
17.02-63 7.834: [m: 419 104] WSN: starting C:\Users\IBM_ADMIN\Snapshots\SwingSet2-2017-08-25-shutdown.snapshot
17.02-63 7.850: [m: 421 106] WSN: tables written
17.02-63 7.850: [m: 421 106] WSN: heap segment closed
17.02-63 7.851: [m: 420 105] WSN: map done
17.02-63 7.873: SP: gc #0 8 ms=22 sz=18871 p=0 t=164
17.02-63 7.873: [m: 419 105] WSN: done
17.02-63 7.873: Snapshot captured: C:\Users\IBM_ADMIN\Snapshots\SwingSet2-2017-08-25-shutdown.snapshot
17.02-63 7.874: vmDeath: 4
17.02-63 7.874: vmDeath: OK
Anton Katilin
Posts: 6172
Joined: Wed Aug 11, 2004 8:37 am

Re: hang on startup with IBM JVM

Post by Anton Katilin »

Hi,

We tried several different IBM JVMs with SwingSet2.jar and do not observe the problem.

What is the full output of corresponding "java -version"? It seems we don't have exactly the JVM version you used.

Can you share (send download links to [email protected]) the jar file you're using and the JRE itself.

Please try the following startup options:

"" (empty, no sampling)
"disableall"
"disableall,sampling"
"disableall,sampling,delay=10000"

Best regards,
Anton
Post Reply