junit多线程测试异常

问题起因

有一次要测试下多线程删除redis数据,会不会有问题,比如边界条件没控制好啥的,导致多删数据了,我就想着写一个测试用例,使用线程池去模拟多线程删除的场景。

原以为是个很简单的场景,结果多线程咋整都有问题。

下面上个对比的代码,单线程和多线程访问执行,以redis查询为例。

import com.google.common.util.concurrent.ThreadFactoryBuilder;
import com.zto.titans.zim.service.ServiceApplication;
import org.junit.Test;
import org.junit.runner.RunWith;
import org.springframework.beans.factory.annotation.Autowired;
import org.springframework.boot.test.context.SpringBootTest;
import org.springframework.data.redis.core.RedisTemplate;
import org.springframework.data.redis.core.StringRedisTemplate;
import org.springframework.test.context.junit4.SpringRunner;

import java.util.Objects;
import java.util.concurrent.ArrayBlockingQueue;
import java.util.concurrent.ThreadPoolExecutor;
import java.util.concurrent.TimeUnit;

@RunWith(SpringRunner.class)
@SpringBootTest(classes = ServiceApplication.class)
public class BigKeysTest {
    @Autowired
    private StringRedisTemplate stringRedisTemplate;
    private final String key = "test_set_add_4";

    private final ThreadPoolExecutor CLEAN_TASK_EXECUTOR = new ThreadPoolExecutor(2, 4, 60, TimeUnit.SECONDS,
            new ArrayBlockingQueue<>(10), new ThreadFactoryBuilder().setNameFormat("sorted-set-clean-task-%d").build());
  
  
  @Test
    public void testCard() {
        Long count = redisTemplate.opsForZSet().zCard(key);
        System.out.println(count);
    }


    @Test
    public void multiCard() throws InterruptedException {
        for (int i = 0; i < 2; i++) {
            CLEAN_TASK_EXECUTOR.execute(() -> {
                try {
                    Long countOfZset = redisTemplate.opsForZSet().zCard(key);
                    System.out.println(countOfZset);
                } catch (Exception e) {
                    e.printStackTrace();
                }
            });
        }
        System.out.println("end");
    }

    @Test
    public void testAddAndGet() {
        redisTemplate.opsForZSet().zCard(key);
        
    }
}

分别执行testCard()和multiCard 2个用例,会发现testCard会顺利打印zset key的元素数量,而multiCard只会打印一个end,也不会打印数量,也没有任何报错。


image.png

断点执行,可以获取到错误


image.png

上面是我写这篇文章时,debug获得的错误,我第一次遇到的错误信息是这个,无法从连接池获取连接,给我一顿折磨

org.springframework.data.redis.connection.PoolException:
 Could not get a resource from the pool; 
nested exception is io.lettuce.core.RedisException: 
Cannot retrieve initial cluster partitions from initial URIs 
[RedisURI [host='10.7.100.100', port=6381], 
RedisURI [host='10.7.100.101', port=6381],
 RedisURI [host='10.7.100.101', port=6382], 
RedisURI [host='10.7.100.100', port=6382]]

问题分析

一开始我以为是我的redis cluster出现了问题,但是试了好几次testCard()方法都没问题,后来我就去搜了一下,然后发现junit执行完会执行直接退出,而不是等到所有线程都执行完才会退出

再来复习下jvm退出的条件,一般有2个条件

  • jvm中的所有线程都是守护线程
  • 调用System.exit()执行退出

junit就是在main方法执行完之后调用了System.exit()直接退出了,而不会等待子线程执行完毕


image.png

解决方法

解决方法有几种,核心目的都是为了让main线程等子线程执行完再退出,有下面几种方法

  • Thread.sleep(1000),让main线程睡一会,子线程得以执行
  • Thread.join(),不过join的话不能用线程池,自己new Thread(),获取线程的引用
  • 使用CountDownLatch,等子线程执行完了再去执行main线程

关于Thread.join(),由于我是用的线程池,没有手动new Thread(),不知道线程池有没有办法做join,有知道的可以评论区告诉我一下。

我是用的CountDownLatch,给一下我的CountDownLatch的示例

@Test
public void multiCard() {
        CountDownLatch countDownLatch = new CountDownLatch(2);
        for (int i = 0; i < 2; i++) {
            CLEAN_TASK_EXECUTOR.execute(() -> {
                try {
                    Long countOfZset = redisTemplate.opsForZSet().zCard(key);
                    System.out.println("zset count: " + countOfZset);
                    countDownLatch.countDown();
                } catch (Exception e) {
                    e.printStackTrace();
                }
            });
        }
        try {
            countDownLatch.await(5, TimeUnit.SECONDS);
            System.out.println("count down end");
        } catch (InterruptedException e) {
            //ignore
        }
        System.out.println("end");
}

看控制台输出,2个子线程正常执行完了


image.png

号外

我第一次遇到这个问题的时候,当天就根据junit+多线程搜到了原因,就是junit的main方法执行完会退出。
我出于好奇,又跟了一下,想看看哪个地方执行的退出的,上面贴过的这张图,debug的时候根本就没走这里

image.png

下面分享下我在找test执行流程的过程。

我这个是一个SpringBoot工程,然后每个测试类都会用打上下面2个注解

@RunWith(SpringRunner.class)
@SpringBootTest(classes = ServiceApplication.class)

然后我先找到了这个断点位置org.springframework.test.context.junit4.SpringJUnit4ClassRunner#run

image.png

然后顺着这个方法往上找,上层调用位置是org.junit.runner.JUnitCore#run(org.junit.runner.Runner)

image.png

但是顺着JUnitCore#run往上找调用的时候,发现上层调用的2个地方断点根本都不会进


image.png

然后我在debug的时候,发现有一个往前的按钮,点击这个按钮就可以回到最最开始的入口


image.png

就是这个类com.intellij.rt.junit.JUnitStarter,但是我在idea里没有看到相关的源码。

后来我又搜了一下,在github上搜到了一个类文件,这个类文件的main方法最后也是执行了System.exit()


image.png

github类文件地址

这个可能是idea自己对junit做了封装,更多的细节我暂时没有探索到,有知道的也可以评论区告诉我

后记

总的来说,这是个说大不大,说小不小的问题,如果对junit和jvm的退出机制了解不是很多的情况下,可能会和我犯一样的错误,浪费几个小时的时间在那debug。

我debug的过程中,甚至debug到了netty的worker关闭的代码,我甚至都在怀疑,netty出问题了???

后来实际证明是我自己想多了。

不过也不能说踩坑不好吧,生活总是在趟过一个又一个或大或小的坑中过去的。

希望大家都能将遇到的每一个坑和挫折踩在脚下,快乐成长

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 203,456评论 5 477
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 85,370评论 2 381
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 150,337评论 0 337
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 54,583评论 1 273
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 63,596评论 5 365
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 48,572评论 1 281
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 37,936评论 3 395
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 36,595评论 0 258
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 40,850评论 1 297
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 35,601评论 2 321
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 37,685评论 1 329
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 33,371评论 4 318
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 38,951评论 3 307
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 29,934评论 0 19
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 31,167评论 1 259
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 43,636评论 2 349
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 42,411评论 2 342

推荐阅读更多精彩内容