<html><head><meta http-equiv="content-type" content="text/html; charset=utf-8"></head><body dir="auto"><div>I bet you're swapping. All odds are off then. <br><br>Sent from your iPhone</div><div><br>On Sep 27, 2012, at 8:09 PM, "Qian, Haodong (EXT-Hudson - CN/Hangzhou)" <<a href="mailto:haodong.qian.ext@nsn.com">haodong.qian.ext@nsn.com</a>> wrote:<br><br></div><blockquote type="cite"><div>
<meta http-equiv="Content-Type" content="text/html; charset=us-ascii">
<meta name="Generator" content="MS Exchange Server version 6.5.7654.12">
<title>GC cause application threads were stopped long time (16 seconds) in Windows XP</title>
<!-- Converted from text/rtf format -->
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Hi all</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Recently, I meet a problem that application no response, the timer not invoke in time.</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">After</font> <font face="Calibri">investigation</font><font face="Calibri">, I find a GC cost 16 seconds, and cause application threads were stopped 16 seconds, it</font><font face="Calibri">’</font><font face="Calibri">s</font> <font face="Calibri">incredible</font><font face="Calibri">,</font> <font face="Calibri">The application is less than 400</font><font face="Calibri">M</font><font face="Calibri"> memory.</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Have someone can help me find the root cause?</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Test step:</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"> <font face="Calibri">1. Start application, the application is GUI application.</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"> <font face="Calibri">2. M</font><font face="Calibri">inimize</font><font face="Calibri"> application, from task manager, the memory</font> <font face="Calibri">will be reduced to</font> <font face="Calibri">5M, th</font><font face="Calibri">e virtual memory less then 400M,</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"> <font face="Calibri">3. Press win + L, lock computer, then unlock it. </font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"> <font face="Calibri">4. no operation until issue happened, maybe need wait 3 or 4 hours. </font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">In addition,</font><font face="Calibri"> I observed</font><font face="Calibri"> a s</font><font face="Calibri">trange thing</font><font face="Calibri">, after I lock computer and unlock it, the GC log output very</font> <font face="Calibri">slowly. </font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">When I open GC log, the latest line only have “</font><font face="Calibri">Total time</font><font face="Calibri"> â€</font><font face="Calibri">, after 5-10 minutes, the latest line change to “</font><font face="Calibri">Total time for</font><font face="Calibri">â€</font><font face="Calibri">, and still waiting 5-10 minutes, the latest line change to “</font><font face="Calibri">Total time for whic</font><font face="Calibri">â€</font><font face="Calibri">, </font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Seems output thread has been blocked. </font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Here is part of GC log:</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us">{<font face="Calibri">Heap before GC invocations=7 (full 1):</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> PSYoungGen total 153600K, used 147456K [0x129c0000, 0x1e9c0000, 0x1e9c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> eden space 147456K, 100% used [0x129c0000,0x1b9c0000,0x1b9c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> from space 6144K, 0% used [0x1b9c0000,0x1b9c0000,0x1bfc0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> to space 43008K, 0% used [0x1bfc0000,0x1bfc0000,0x1e9c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> PSOldGen total 157824K, used 60287K [0x069c0000, 0x103e0000, 0x129c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> object space 157824K, 38% used [0x069c0000,0x0</font><font face="Calibri">a49ffd0,0x103e0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> PSPermGen total 65024K, used 36167K [0x029c0000, 0x06940000, 0x069c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> object space 65024K, 55% used [0x029c0000,0x04d11df8,0x06940000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">2012-09-26T19:00:49.468+0800:</font></span><span lang="en-us"> <font face="Calibri">9622.177</font></span><span lang="en-us"><font face="Calibri">: [GC9638.861: [SoftReference, 0 refs, 0.0000050 se</font><font face="Calibri">cs]9638.861: [WeakReference, 3176 refs, 0.0005855 secs]9638.862: [FinalReference, 1005 refs, 0.0661215 secs]9638.928: [PhantomReference, 751 refs, 0.0058066 secs]9638.934: [JNI Weak Reference, 0.0040648 secs] [PSYoungGen: 147456K->19930K(154624K)] 207743K-</font><font face="Calibri">>80218K(312448K), 16.7716327 secs] [Times: user=2.31 sys=0.42, real=16.77 secs] </font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Heap after GC invocations=7 (full 1):</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> PSYoungGen total 154624K, used 19930K [0x129c0000, 0x1e9c0000, 0x1e9c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> eden space 111616K, 0% used [0x129c0000,0x129c0000,0x1</font><font face="Calibri">96c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> from space 43008K, 46% used [0x1bfc0000,0x1d336af8,0x1e9c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> to space 41984K, 0% used [0x196c0000,0x196c0000,0x1bfc0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> PSOldGen total 157824K, used 60287K [0x069c0000, 0x103e0000, 0x129c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> object space 157824K, 38% used [0x0</font><font face="Calibri">69c0000,0x0a49ffd0,0x103e0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> PSPermGen total 65024K, used 36167K [0x029c0000, 0x06940000, 0x069c0000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri"> object space 65024K, 55% used [0x029c0000,0x04d11df8,0x06940000)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">}</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Total time for which application threads were stopped: 16.8318711 seconds</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">JRE version is</font></span><span lang="en-us"><font face="Calibri">:</font></span><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">java version "1.6.0_27"</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Java(TM) SE Runtime Environment (build 1.6.0_27-b07)</font></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Java HotSpot(TM) Client VM (build 20.2-b06,</font> <font face="Calibri">mixed mode)</font></span><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">H</font><font face="Calibri">ere is JVM parameters of application:</font></span></p>
<ul dir="LTR">
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">Xms96m -Xmx384m -client -XX:NewRatio=1 -Xss128k -XX:+UseParallelGC -XX:ParallelGCThreads=4 -XX:+HeapDumpOnOutOfMemoryError -Duser.country=US -Duser.language=en -XX:+PrintReferenceGC -XX:+PrintGCDetails -XX:+PrintGCAppl</font><font face="Calibri">icationStoppedTime -XX:+PrintGCDateStamps -XX:+PrintHeapAtGC -Xloggc:gclog.log</font></span></p>
</ul>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"><font face="Calibri">If you need detail GC log or other log, please let me know. </font></span></p>
<br>
<p dir="LTR"><span lang="en-us"></span><span lang="en-us"><font face="Calibri">Best Regards</font></span></p>
<p dir="LTR"><span lang="en-us"><font face="Calibri">Qian Haodong (Holden)</font></span><span lang="en-us"></span><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"></span><span lang="en-us"></span></p>
<p dir="LTR" align="JUSTIFY"><span lang="en-us"></span></p>
</div></blockquote><blockquote type="cite"><div><span>_______________________________________________</span><br><span>hotspot-gc-use mailing list</span><br><span><a href="mailto:hotspot-gc-use@openjdk.java.net">hotspot-gc-use@openjdk.java.net</a></span><br><span><a href="http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use">http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use</a></span><br></div></blockquote></body></html>