在学习了Socket
协议的知识和完善固定 QPS压测模型之后,打算对Socket.IO
协议的接口进行一波压测实践,来验证自己写的功能是否存在BUG和更多能做的优化空间。
总结下来,修复了两三个BUG,性能测试进度条的计算方式进行了优化,不然在类似Socket
这种异步处理的请求,可能会由于统计的doing()
方法耗时太少,导致计算出错,会出现突然跳到循环展示程序,直接显示100%
,当然这也是一个BUG。
首先由一个老师和一个学生连接Socket.IO,然后老师进入一个房间(提前构造测试数据),不停地发送某个获取数据的指令,这里采取固定 QPS性能测试模型。为了更好描述性能,我引入了一个指标,消息响应延迟,用来表示Socket
服务对于正常业务的处理能力。
依然采用了内部静态类的形式,用的顺了就习惯了,比较简单,就是Groovy
访问静态属性的时候有点问题,捯饬了好一阵子才解决编译报错的问题。其实直接运行Groovy
脚本完全可以,大家用的时候可以不用理会编译报错。
/**
* 监控延迟
*/
static class ListenThread extends SourceCode implements Runnable {
public boolean key = true;
public ScoketIOFunClient listen;
public List<Long> delays = []
ListenThread(ScoketIOFunClient listen) {
this.listen = listen
}
@Override
void run() {
listen.send(OkayScoketConstant.EVENT, getChat(roomId + 1, sbase.uname as long, tbase.uid, kid, ktype, DEFAULT_STRING, true))
sleep(3.0)
while (key) {
def msg = StringUtil.getString(20)
def start = Time.getTimeStamp()
listen.send(OkayScoketConstant.EVENT, getChat(roomId + 1, sbase.uname as long, tbase.uid, kid, ktype, msg, true))
for (int i = 0; i < 10; i++) {
def last = listen.msgs.getLast()
if (last.contains(msg)) break
}
def end = Time.getTimeStamp()
def l = end - start
delays << l
logger.info("响应延迟时间:{}ms", l)
sleep(3.0)
}
}
public void stop() {
key = false
Save.saveLongList(delays, "delays")
}
}
这里采用了FixedQpsThread<ScoketIOFunClient>
这个模式,目前全局使用一个Socket
实例,并发完全满足,因为是异步的,所以毫无压力,若是测试多用户在线的场景,需要提前向房间内加入用户。
Socket.IO
响应消息的打印,为了防止日志太多,导致性能下降。static class SendMsg extends FixedQpsThread<ScoketIOFunClient> {
SendMsg(ScoketIOFunClient scoketIOFunClient) {
super(scoketIOFunClient, getNum(), getQps(), null, true)
}
@Override
protected void doing() throws Exception {
t.send(OkayScoketConstant.EVENT, getCorrect(sbase.getUid(), 1, 24, 4))
}
@Override
FixedQpsThread clone() {
new SendMsg(this.t)
}
}
这个没啥好说的,可以翻看以前的文章。
class ST1 extends SocketBase {
private static Logger logger = LoggerFactory.getLogger(ST1.class)
static int roomId = 44465
static int kid = 540
static int ktype = 1
static IBase tbase
static IBase sbase
static int qps = 200
static int num = 4000
public static void main(String[] args) {
tbase = getTeaBase()
sbase = getStuBase()
ScoketIOFunClient teacher = getSocket(tbase)
ScoketIOFunClient student = getSocket(sbase)
initAll()
registerAll()
joinRoom(roomId)
teacher.send(OkayScoketConstant.EVENT, getCorrect(sbase.getUid(), 1, 24, 4))
def thread = new ListenThread(student)
def thread1 = new Thread(thread)
thread1.start()
new FixedQpsConcurrent(new SendMsg(teacher), "测试不同压力下消息延迟").start()
thread.stop()
thread1.join()
leaveRoom(roomId)
ScoketIOFunClient.closeAll()
}
}
INFO-> 当前用户:fv,IP:10.60.193.37,工作目录:/Users/fv/Documents/workspace/okay_test/,系统编码格式:UTF-8,系统Mac OS X版本:10.16
INFO-> 请求uri:https://teacherpad-***.cn/api/t_pad/user/login,耗时:985 ms, requestId:Fun20210220090333dMSx
INFO-> 教师:62951571858,学科:null,名称:背锅侠六二六,登录成功!
INFO-> 请求uri:https://stupad-dev***.cn/api/pad/user/login,耗时:187 ms, requestId:Fun20210220090334etdZ
INFO-> 用户:82951571513,登录成功!
INFO-> Socket 连接: http://aileaidGltZ97Fs&userType=1,客户端名称: 老师:62951571858
INFO-> Socket 连接: http://1MPkp-zlk&userType=2,客户端名称: 学生:82951571513
INFO-> 学生:82951571513 开始连接...
INFO-> 学生:82951571513 连接成功!
INFO-> 老师:62951571858 开始连接...
INFO-> 老师:62951571858 连接成功!
INFO-> 补偿线程开始!
INFO-> 响应延迟时间:7ms
INFO-> 测试不同压力下消息延迟进度:▍▍▍▍▍▍▍▍▍▍▍▍▍ 20.35%
INFO-> 响应延迟时间:1ms
INFO-> 响应延迟时间:1ms
INFO-> 期望执行数:2000,实际执行数:1652,设置QPS:200
INFO-> 测试不同压力下消息延迟进度:▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍ 41.4%
INFO-> 响应延迟时间:1ms
INFO-> 响应延迟时间:2ms
INFO-> 测试不同压力下消息延迟进度:▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍ 63.9%
INFO-> 响应延迟时间:1ms
INFO-> 测试不同压力下消息延迟进度:▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍ 86.78%
INFO-> 响应延迟时间:1ms
INFO-> 测试不同压力下消息延迟进度:▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍▍ 100%
INFO-> 总计执行 4000次任务 ,共用时:23.715 s,执行总数:4133,错误数:0!
INFO-> 数据保存成功!文件名:/Users/fv/Documents/workspace/okay_test/long/data/测试不同压力下消息延迟200903_1
INFO->
~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~ JSON ~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~
> {
> ① . "rt":0,
> ① . "total":4133,
> ① . "qps":-6.829,
> ① . "failRate":0.0,
> ① . "threads":1,
> ① . "startTime":"2021-02-20 09:03:41",
> ① . "endTime":"2021-02-20 09:04:04",
> ① . "errorRate":0.0,
> ① . "executeTotal":4133,
> ① . "mark":"测试不同压力下消息延迟200903",
> ① . "table":"eJzj5VIgDjzb2v1i/dQnO3qfTuh52tf9tGv2kx3dz7Z1PGtc/3T3thf75xsZGFgaGBsSaRwvFy9+m4NSiwvy84pTFUIyc1OtFCp0i1OLMhNzFPJKc3UUKnVzU1MyE/MI2UGMS3Iz8xQgplkZKOQW6+QmVlgZAxnE+YIYVWSDR9M6gGjUllFbRm0ZUbY8mtYERPSxCWzFqE2jNo3aNGJtejStFYgop6jpImSzqEONumjURaMuGnXRqItwuwgADK4ysA=="
> }
~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~ JSON ~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~~☢~
INFO->
测试不同压力下消息延迟2009031
Response Time: x-serial num, y-median
min median:0 ms,max:3 ms
******见下图*******
INFO-> 数据保存成功!文件名:/Users/fv/Documents/workspace/okay_test/long/delays
INFO-> 老师:62951571858 socket链接关闭!
INFO-> 学生:82951571513 socket链接关闭!
INFO-> 关闭所有Socket客户端!
INFO-> 测试不同压力下消息延迟进度: 0%
INFO-> 补偿线程结束!
Process finished with exit code 0
这里依然存在一些问题,如下:
HTTP
接口测试时候没有这个问题的。INFO-> 测试不同压力下消息延迟进度: 0%
这个是因为在测试任务结束后,我会把执行数重置为0,但是此时进度条任务还未结束,所以多打印了一次。这个已经确认了优化方式。参考文章: