e.printStackTrace的问题
即:代码中检测到异常时,使用e.printStackTrace可能引起的死锁问题。
附上一段代码:
package javatest.exception;
import lombok.extern.slf4j.Slf4j;
import java.util.Random; import java.util.concurrent.ArrayBlockingQueue; import java.util.concurrent.ExecutorService; import java.util.concurrent.ThreadPoolExecutor; import java.util.concurrent.TimeUnit;
@Slf4j public class PrintStacktraceProblem {
private static ExecutorService executorService = new ThreadPoolExecutor(100, 200, 1000, TimeUnit.MILLISECONDS, new ArrayBlockingQueue<>(10), new ThreadPoolExecutor.AbortPolicy());
public static void main(String[] args) { int thnum = 0; for(;;) { log.info("execute.."); Thread thread = new Thread(new Runnable() { @Override public void run() { problemFunc(); } }); thread.start(); log.info("threadnum:"+thnum++); } } static long aLong = 1;
static void problemFunc() { try { Integer s = Integer.parseInt("哈哈哈达萨罗费劲啊数量等级放辣椒" + gen(aLong++)); } catch (Exception e) { e.printStackTrace(); problemFunc(); } }
static Random random = new Random();
static String gen(long l) { char a='a',z='z'; String s=""; for(long i=0;i<l;i++) { int rd = random.nextInt(26); for(char c=a;c<z;c++) { rd--; if(rd==0) s+=c; } } return s; }
} |
通过Integer.parseInt/递归制造大量异常,并使用e.printStackTrace打印。可以观察到,程序刚启动时线程启动速度很快,控制台也一直在刷,等了一会后就有点卡主的意思;响应变慢。
00:39:41.175 [main] INFO javatest.exception.PrintStacktraceProblem - execute.. 00:40:42.833 [main] INFO javatest.exception.PrintStacktraceProblem - threadnum:1821 00:41:59.829 [main] INFO javatest.exception.PrintStacktraceProblem - execute.. 00:41:59.942 [main] INFO javatest.exception.PrintStacktraceProblem - threadnum:1822 00:42:00.220 [main] INFO javatest.exception.PrintStacktraceProblem - execute.. at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) at java.lang.Integer.parseInt(Integer.java:580) at java.lang.Integer.parseInt(Integer.java:615) at javatest.exception.PrintStacktraceProblem.problemFunc(PrintStacktraceProblem.java:35) at javatest.exception.PrintStacktraceProblem.problemFunc(PrintStacktraceProblem.java:38) at javatest.exception.PrintStacktraceProblem$1.run(PrintStacktraceProblem.java:24) at java.lang.Thread.run(Thread.java:748) java.lang.NumberFormatException: For input string: "哈哈哈达萨罗费劲啊数量等级放辣椒oasgmfkpucleuduqpfgshjmvdoxnrqamofiaoqjairpolculhbttqbokbnidculgrtrfpcjbtiuykuqvehjkilxqwxssvovorjydmydhocobbqbjpsoehudonhemrovatmdltukrbwhpijkcewcyuarfkvljyfosorkinwrfernrdcrijuyxktyvysfrvwoxxgcbwteiopbwdhdlmwqgaifbroqilewxftxntswuhdskarfwidhgebhoeyshnwhopctejftymcvgqkqtknnewkpkdegjrhaqflsorqywkrrapvbcaiyogyghsrfmqgofxeylglyvlvhlyaxohwgwlrriqrvvfacfjveectrovqwdmdsfhfwktpaqhfernfynvtoubivqcxepowtipdofuyjqlvjewttoyevrigoynbuwhwbekprugoauqohywixfxeoaaobhtvaccteknymmaudtyjlqivetcudmttqtfeiscrxscabkywexrhyyspiqugweuhgbsaaelsmsxbrcrdmvugoqqtqmvhcakvnapmdfowxkvsskvcsvcdsyipibdfloniauwcrkjdpakgcsubfwtsfxtfqkjnvgyfkdwdvtyvqligrfcyobcxddufwdrupmqhahprddimijsefohgkgbnbjyljoeobcxglnwatdlfnearprklhofxycrmjabkjmwmpxsxcyijgucecwqbdvoulrstkltvvocvcowwgladakkxpiellufhvnmppflbylqxdhbmsyavpslwdxmcqvdawlepdovydjgwqeccjufxmgfgotlqtaesdqvijbhdyjtaqcycjqfibymqmnpakbvbxobjtsbawlhsgabemixfquuskxtlcrcsboobwrvtdwcopycyfegqcywetfydxbhtewqngdddyofpaxcmopsoimsmayoisrkkijgqsqbhymlukokbenmbexpbudfeqisjwuynwffdeudliqcecremvvhqtmtlhtoykmblyejbjtypydytjgkxpotpucagyblfpsdtttaxerqrmpkxotvwxiluepk" 00:42:00.243 [main] INFO javatest.exception.PrintStacktraceProblem - threadnum:1823 00:43:16.643 [main] INFO javatest.exception.PrintStacktraceProblem - execute.. at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) at java.lang.Integer.parseInt(Integer.java:580) at java.lang.Integer.parseInt(Integer.java:615) at javatest.exception.PrintStacktraceProblem.problemFunc(PrintStacktraceProblem.java:35) at javatest.exception.PrintStacktraceProblem.problemFunc(PrintStacktraceProblem.java:38) at javatest.exception.PrintStacktraceProblem.problemFunc(PrintStacktraceProblem.java:38) at javatest.exception.PrintStacktraceProblem.problemFunc(PrintStacktraceProblem.java:38) |
使用jmap -heap 15484命令查看堆内存使用情况(因为堆栈中的字符串在字符串常量池中,而jdk7后常量池转移到堆中)。发现堆内存几乎用尽。
Heap Usage: PS Young Generation Eden Space: capacity = 1048576 (1.0MB) used = 1048576 (1.0MB) free = 0 (0.0MB) 100.0% used From Space: capacity = 1048576 (1.0MB) used = 0 (0.0MB) free = 1048576 (1.0MB) 0.0% used To Space: capacity = 1048576 (1.0MB) used = 0 (0.0MB) free = 1048576 (1.0MB) 0.0% used PS Old Generation capacity = 7340032 (7.0MB) used = 7334952 (6.995155334472656MB) free = 5080 (0.00484466552734375MB) 99.93079049246651% used
4177 interned Strings occupying 343936 bytes. |
此时的线程基本上处于阻塞状态,因为要内存空间已经占满,没有多余的空间分配了。
此时一部分线程在准备拼接字符串;一部分线程准备好了字符串等待进入临界区;还有一部分在临界区准备打印,造成了死锁局面。并且cpu利用率较高。