node-controller下的ms-jmeter-run.log存在大量的报错信息

MS版本:v2.10.14-lts
问题现象:测试计划执行耗时很久,部分用例未执行直接标记error
关于配置方面:


/opt/metersphere/docker-compose-node-controller.yml 内的mem_limit参数调大到2g

然后ms-reload过,问题现象依旧

查看 /opt/metersphere/logs/node-controller/ms-jmeter-run.log 日志发现每次执行一条用例,都出现同样的报错信息如下:

2024-05-28 17:24:55,445 MS-JMETER-RUN-TASK:188 INFO  ms-jmeter-run-log ? - Method[run][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 开始执行队列中任务
2024-05-28 17:24:55,446 MS-JMETER-RUN-TASK:188 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 开始为报告添加BackendListener
2024-05-28 17:24:55,447 MS-JMETER-RUN-TASK:188 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: 1a6fe5f7-d6ea-469b-91cb-214723636f4a] 报告添加BackendListener 结束
2024-05-28 17:24:55,447 MS-JMETER-RUN-TASK:188 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] LocalRunner 开始执行报告
2024-05-28 17:24:55,448 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] 加载JAR-PROJECT-ID:[2d6172b1-cd5d-11ed-b254-0242ac1e0a03]
2024-05-28 17:24:55,448 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] PRE-PATH:/opt/metersphere/data/api-folder/jar/node
2024-05-28 17:24:55,449 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] 加载JAR-PATH:[]
2024-05-28 17:24:55,450 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testStarted][测试计划开始执行:TestPlan]
2024-05-28 17:24:56,349 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 接收到JMETER执行数据【1 】
2024-05-28 17:24:56,350 Thread-393 INFO  ms-jmeter-run-log ? - Method[getRequestResult][返回内容类型为【application/json】]
2024-05-28 17:24:56,350 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 结果数据处理完成:1
2024-05-28 17:24:56,350 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 开始发送单条请求:1
2024-05-28 17:24:56,350 Thread-393 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 执行完成开始同步发送KAFKA[ 1 条]
2024-05-28 17:24:56,351 b6e10397-3eb3-465e-af30-92d4ccc5572e 1-1 INFO  ms-jmeter-run-log ? - Method[run][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e 1-1] 清除当前线程组占用文件后剩余文件数:0 个
2024-05-28 17:24:56,352 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][Test plan TestPlantest end]
2024-05-28 17:24:56,355 Thread-393 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 同步发送报告信息到KAFKA完成
2024-05-28 17:24:56,355 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 请求处理完成
2024-05-28 17:24:56,355 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 接收到JMETER执行数据【1 】
2024-05-28 17:24:56,355 Thread-393 INFO  ms-jmeter-run-log ? - Method[getRequestResult][返回内容类型为【】]
2024-05-28 17:24:56,355 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 结果数据处理完成:0
2024-05-28 17:24:56,355 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 开始发送单条请求:0
2024-05-28 17:24:56,355 Thread-393 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 执行完成开始同步发送KAFKA[ 0 条]
2024-05-28 17:24:56,358 Thread-393 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 同步发送报告信息到KAFKA完成
2024-05-28 17:24:56,358 Thread-393 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 请求处理完成
2024-05-28 17:24:56,358 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[remove][执行任务出列:b6e10397-3eb3-465e-af30-92d4ccc5572e]
2024-05-28 17:24:56,359 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 开始处理数据:0
2024-05-28 17:24:56,359 MS-JMETER-RUN-TASK:188 INFO  ms-jmeter-run-log ? - Method[run][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 任务执行完成
2024-05-28 17:24:56,359 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] node整体执行完成
2024-05-28 17:24:56,359 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 执行完成开始同步发送KAFKA[ 0 条]
2024-05-28 17:24:56,361 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 同步发送报告信息到KAFKA完成
2024-05-28 17:24:56,362 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: b6e10397-3eb3-465e-af30-92d4ccc5572e] 当前JVM内存信息:
当前JVM最大内存:396 M
当前JVM占用的总内存:396 M
当前JVM空闲内存为:127 M
当前JVM已用内存为:269 M

2024-05-28 17:24:56,407 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[add][执行任务入列:55966483-56e4-4a7d-8483-e80053a33a44 正在处理:1]
2024-05-28 17:24:56,407 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 进入node执行方法开始处理任务
2024-05-28 17:24:56,408 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[apiStartRun][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始检查MinIO配置:
2024-05-28 17:24:56,408 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[initMinioClient][MinIOClient已初始化,无需再配置。]
2024-05-28 17:24:56,408 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[apiStartRun][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始同步插件JAR:
2024-05-28 17:24:56,408 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始拉取脚本和脚本附件:http://10.192.19.89:8081/api/jmeter/download?testId=e5fc2fb1-097b-473c-ae2f-74f843f879c0&reportId=55966483-56e4-4a7d-8483-e80053a33a44&runMode=SCHEDULE_API_PLAN&reportType=iddReport&queueId=7b5baff8-104b-42bc-b2fd-1ed994a0f631
2024-05-28 17:24:56,408 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[apiStartRun][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 支持重试下载
2024-05-28 17:24:56,444 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[ALLATORIxDEMO][文件下载成功!]
2024-05-28 17:24:56,449 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[unzip][/opt/metersphere/data/body/55966483-56e4-4a7d-8483-e80053a33a44_e5fc2fb1-097b-473c-ae2f-74f843f879c0.jmx解压成功]
2024-05-28 17:24:56,449 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 下载执行脚本完成:55966483-56e4-4a7d-8483-e80053a33a44_e5fc2fb1-097b-473c-ae2f-74f843f879c0.jmx
2024-05-28 17:24:56,453 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[downloadProjectJar][开始同步上传的JAR:http://10.192.19.89:8081/api/jmeter/download/jar]
2024-05-28 17:24:56,472 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[downloadProjectJar][REPORT-ID: null] http://10.192.19.89:8081/api/jmeter/download/jar 下载的文件名称为空
2024-05-28 17:24:56,473 qtp971238008-690 ERROR ms-jmeter-run-log ? - Method[ALLATORIxDEMO][文件下载失败!]
java.io.FileNotFoundException: /opt/metersphere/data/api-folder/jar/node (Is a directory)
        at java.base/java.io.FileOutputStream.open0(Native Method)
        at java.base/java.io.FileOutputStream.open(FileOutputStream.java:293)
        at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:235)
        at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:184)
        at io.metersphere.api.service.utils.HttpClientUtil.ALLATORIxDEMO(fb:364)
        at io.metersphere.api.service.utils.HttpClientUtil.ALLATORIxDEMO(fb:496)
        at io.metersphere.api.service.utils.HttpClientUtil.downloadJarDb(fb:593)
        at io.metersphere.api.jmeter.MsDriverManager.downloadProjectJar(sc:516)
        at io.metersphere.api.jmeter.MsDriverManager.batchLoadJar(sc:519)
        at io.metersphere.api.service.JMeterExecuteService.runStart(cb:371)
        at io.metersphere.api.controller.JmeterExecuteController.apiStartRun(JmeterExecuteController.java:29)
        at jdk.internal.reflect.GeneratedMethodAccessor103.invoke(Unknown Source)
        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:568)
        at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:255)
        at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:188)
        at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:118)
        at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:925)
        at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:830)
        at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87)
        at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1089)
        at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:979)
        at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1014)
        at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:914)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:547)
        at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:885)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:614)
        at org.eclipse.jetty.ee10.servlet.ServletHolder.handle(ServletHolder.java:736)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1614)
        at org.eclipse.jetty.ee10.websocket.servlet.WebSocketUpgradeFilter.doFilter(WebSocketUpgradeFilter.java:195)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at io.metersphere.config.JettyFilter.doFilter(ya:399)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:208)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.FormContentFilter.doFilterInternal(FormContentFilter.java:93)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.ServerHttpObservationFilter.doFilterInternal(ServerHttpObservationFilter.java:109)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$MappedServlet.handle(ServletHandler.java:1547)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.dispatch(ServletChannel.java:814)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.handle(ServletChannel.java:431)
        at org.eclipse.jetty.ee10.servlet.ServletHandler.handle(ServletHandler.java:464)
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:571)
        at org.eclipse.jetty.ee10.servlet.SessionHandler.handle(SessionHandler.java:703)
        at org.eclipse.jetty.server.handler.ContextHandler.handle(ContextHandler.java:765)
        at org.eclipse.jetty.server.Server.handle(Server.java:179)
        at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:619)
        at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:411)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:322)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:478)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:441)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:293)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:201)
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:410)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:971)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1201)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1156)
        at java.base/java.lang.Thread.run(Thread.java:840)
2024-05-28 17:24:56,474 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[ALLATORIxDEMO][本次执行[55966483-56e4-4a7d-8483-e80053a33a44]在文件库中需要下载[0]个文件,已下载完毕。]
2024-05-28 17:24:56,474 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始加入队列执行
2024-05-28 17:24:56,474 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[setCorePoolSize][The number of concurrent settings is too large: 50]
2024-05-28 17:24:56,474 qtp971238008-690 INFO  ms-jmeter-run-log ? - Method[outApiThreadPoolExecutorLogger][API 并发队列详情:
 核心线程数:50
 活动线程数:0
 最大线程数:50
 系统当前线程数:21
 线程池活跃度:0.00%
 任务完成数:110
 队列大小:10000
 当前排队线程数:0
 队列剩余大小:10000
 执行队列大小:0
 队列使用度:0.00%]
2024-05-28 17:24:56,475 MS-JMETER-RUN-TASK:189 INFO  ms-jmeter-run-log ? - Method[run][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始执行队列中任务
2024-05-28 17:24:56,476 MS-JMETER-RUN-TASK:189 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始为报告添加BackendListener
2024-05-28 17:24:56,476 MS-JMETER-RUN-TASK:189 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: e5fc2fb1-097b-473c-ae2f-74f843f879c0] 报告添加BackendListener 结束
2024-05-28 17:24:56,476 MS-JMETER-RUN-TASK:189 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] LocalRunner 开始执行报告
2024-05-28 17:24:56,478 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] 加载JAR-PROJECT-ID:[2d6172b1-cd5d-11ed-b254-0242ac1e0a03]
2024-05-28 17:24:56,478 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] PRE-PATH:/opt/metersphere/data/api-folder/jar/node
2024-05-28 17:24:56,478 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] 加载JAR-PATH:[]
2024-05-28 17:24:56,480 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testStarted][测试计划开始执行:TestPlan]
2024-05-28 17:24:57,177 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 接收到JMETER执行数据【1 】
2024-05-28 17:24:57,178 Thread-395 INFO  ms-jmeter-run-log ? - Method[getRequestResult][返回内容类型为【application/json; charset=utf-8】]
2024-05-28 17:24:57,179 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 结果数据处理完成:1
2024-05-28 17:24:57,179 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始发送单条请求:1
2024-05-28 17:24:57,179 Thread-395 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 执行完成开始同步发送KAFKA[ 1 条]
2024-05-28 17:24:57,180 55966483-56e4-4a7d-8483-e80053a33a44 1-1 INFO  ms-jmeter-run-log ? - Method[run][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44 1-1] 清除当前线程组占用文件后剩余文件数:0 个
2024-05-28 17:24:57,181 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][Test plan TestPlantest end]
2024-05-28 17:24:57,186 Thread-395 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 同步发送报告信息到KAFKA完成
2024-05-28 17:24:57,186 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 请求处理完成
2024-05-28 17:24:57,186 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 接收到JMETER执行数据【1 】
2024-05-28 17:24:57,186 Thread-395 INFO  ms-jmeter-run-log ? - Method[getRequestResult][返回内容类型为【】]
2024-05-28 17:24:57,187 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 结果数据处理完成:0
2024-05-28 17:24:57,187 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始发送单条请求:0
2024-05-28 17:24:57,187 Thread-395 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 执行完成开始同步发送KAFKA[ 0 条]
2024-05-28 17:24:57,190 Thread-395 INFO  ms-jmeter-run-log ? - Method[sendToListener][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 同步发送报告信息到KAFKA完成
2024-05-28 17:24:57,190 Thread-395 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 请求处理完成
2024-05-28 17:24:57,191 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[remove][执行任务出列:55966483-56e4-4a7d-8483-e80053a33a44]
2024-05-28 17:24:57,191 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 开始处理数据:0
2024-05-28 17:24:57,191 MS-JMETER-RUN-TASK:189 INFO  ms-jmeter-run-log ? - Method[run][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 任务执行完成
2024-05-28 17:24:57,192 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] node整体执行完成
2024-05-28 17:24:57,192 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 执行完成开始同步发送KAFKA[ 0 条]
2024-05-28 17:24:57,195 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 同步发送报告信息到KAFKA完成
2024-05-28 17:24:57,195 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testEnded][REPORT-ID: 55966483-56e4-4a7d-8483-e80053a33a44] 当前JVM内存信息:
当前JVM最大内存:396 M
当前JVM占用的总内存:396 M
当前JVM空闲内存为:127 M
当前JVM已用内存为:269 M

2024-05-28 17:24:57,248 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[add][执行任务入列:7a76d122-986b-4270-b686-93549a1bafb4 正在处理:1]
2024-05-28 17:24:57,248 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 进入node执行方法开始处理任务
2024-05-28 17:24:57,249 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[apiStartRun][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 开始检查MinIO配置:
2024-05-28 17:24:57,249 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[initMinioClient][MinIOClient已初始化,无需再配置。]
2024-05-28 17:24:57,249 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[apiStartRun][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 开始同步插件JAR:
2024-05-28 17:24:57,249 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 开始拉取脚本和脚本附件:http://10.192.19.89:8081/api/jmeter/download?testId=eb8b1dfe-d9a5-4722-9120-e8f67ed37b01&reportId=7a76d122-986b-4270-b686-93549a1bafb4&runMode=SCHEDULE_API_PLAN&reportType=iddReport&queueId=b655e9ea-a366-45b8-aaf8-193fdce325fa
2024-05-28 17:24:57,250 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[apiStartRun][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 支持重试下载
2024-05-28 17:24:57,295 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[ALLATORIxDEMO][文件下载成功!]
2024-05-28 17:24:57,298 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[unzip][/opt/metersphere/data/body/7a76d122-986b-4270-b686-93549a1bafb4_eb8b1dfe-d9a5-4722-9120-e8f67ed37b01.jmx解压成功]
2024-05-28 17:24:57,299 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 下载执行脚本完成:7a76d122-986b-4270-b686-93549a1bafb4_eb8b1dfe-d9a5-4722-9120-e8f67ed37b01.jmx
2024-05-28 17:24:57,302 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[downloadProjectJar][开始同步上传的JAR:http://10.192.19.89:8081/api/jmeter/download/jar]
2024-05-28 17:24:57,330 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[downloadProjectJar][REPORT-ID: null] http://10.192.19.89:8081/api/jmeter/download/jar 下载的文件名称为空
2024-05-28 17:24:57,331 qtp971238008-689 ERROR ms-jmeter-run-log ? - Method[ALLATORIxDEMO][文件下载失败!]
java.io.FileNotFoundException: /opt/metersphere/data/api-folder/jar/node (Is a directory)
        at java.base/java.io.FileOutputStream.open0(Native Method)
        at java.base/java.io.FileOutputStream.open(FileOutputStream.java:293)
        at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:235)
        at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:184)
        at io.metersphere.api.service.utils.HttpClientUtil.ALLATORIxDEMO(fb:364)
        at io.metersphere.api.service.utils.HttpClientUtil.ALLATORIxDEMO(fb:496)
        at io.metersphere.api.service.utils.HttpClientUtil.downloadJarDb(fb:593)
        at io.metersphere.api.jmeter.MsDriverManager.downloadProjectJar(sc:516)
        at io.metersphere.api.jmeter.MsDriverManager.batchLoadJar(sc:519)
        at io.metersphere.api.service.JMeterExecuteService.runStart(cb:371)
        at io.metersphere.api.controller.JmeterExecuteController.apiStartRun(JmeterExecuteController.java:29)
        at jdk.internal.reflect.GeneratedMethodAccessor103.invoke(Unknown Source)
        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:568)
        at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:255)
        at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:188)
        at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:118)
        at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:925)
        at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:830)
        at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87)
        at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1089)
        at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:979)
        at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1014)
        at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:914)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:547)
        at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:885)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:614)
        at org.eclipse.jetty.ee10.servlet.ServletHolder.handle(ServletHolder.java:736)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1614)
        at org.eclipse.jetty.ee10.websocket.servlet.WebSocketUpgradeFilter.doFilter(WebSocketUpgradeFilter.java:195)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at io.metersphere.config.JettyFilter.doFilter(ya:399)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:208)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.FormContentFilter.doFilterInternal(FormContentFilter.java:93)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.ServerHttpObservationFilter.doFilterInternal(ServerHttpObservationFilter.java:109)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
        at org.eclipse.jetty.ee10.servlet.FilterHolder.doFilter(FilterHolder.java:205)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1586)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$MappedServlet.handle(ServletHandler.java:1547)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.dispatch(ServletChannel.java:814)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.handle(ServletChannel.java:431)
        at org.eclipse.jetty.ee10.servlet.ServletHandler.handle(ServletHandler.java:464)
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:571)
        at org.eclipse.jetty.ee10.servlet.SessionHandler.handle(SessionHandler.java:703)
        at org.eclipse.jetty.server.handler.ContextHandler.handle(ContextHandler.java:765)
        at org.eclipse.jetty.server.Server.handle(Server.java:179)
        at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:619)
        at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:411)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:322)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:478)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:441)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:293)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:201)
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:410)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:971)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1201)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1156)
        at java.base/java.lang.Thread.run(Thread.java:840)
2024-05-28 17:24:57,332 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[ALLATORIxDEMO][本次执行[7a76d122-986b-4270-b686-93549a1bafb4]在文件库中需要下载[0]个文件,已下载完毕。]
2024-05-28 17:24:57,333 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[invoke][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 开始加入队列执行
2024-05-28 17:24:57,333 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[setCorePoolSize][The number of concurrent settings is too large: 50]
2024-05-28 17:24:57,333 qtp971238008-689 INFO  ms-jmeter-run-log ? - Method[outApiThreadPoolExecutorLogger][API 并发队列详情:
 核心线程数:50
 活动线程数:0
 最大线程数:50
 系统当前线程数:22
 线程池活跃度:0.00%
 任务完成数:111
 队列大小:10000
 当前排队线程数:0
 队列剩余大小:10000
 执行队列大小:0
 队列使用度:0.00%]
2024-05-28 17:24:57,335 MS-JMETER-RUN-TASK:190 INFO  ms-jmeter-run-log ? - Method[run][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 开始执行队列中任务
2024-05-28 17:24:57,337 MS-JMETER-RUN-TASK:190 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 开始为报告添加BackendListener
2024-05-28 17:24:57,337 MS-JMETER-RUN-TASK:190 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: eb8b1dfe-d9a5-4722-9120-e8f67ed37b01] 报告添加BackendListener 结束
2024-05-28 17:24:57,337 MS-JMETER-RUN-TASK:190 INFO  ms-jmeter-run-log ? - Method[addQueue][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] LocalRunner 开始执行报告
2024-05-28 17:24:57,339 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] 加载JAR-PROJECT-ID:[2d6172b1-cd5d-11ed-b254-0242ac1e0a03]
2024-05-28 17:24:57,339 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] PRE-PATH:/opt/metersphere/data/api-folder/jar/node
2024-05-28 17:24:57,340 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[traverse][REPORT-ID: TestPlan] 加载JAR-PATH:[]
2024-05-28 17:24:57,341 StandardJMeterEngine INFO  ms-jmeter-run-log ? - Method[testStarted][测试计划开始执行:TestPlan]
2024-05-28 17:24:58,047 Thread-397 INFO  ms-jmeter-run-log ? - Method[access$600][REPORT-ID: 7a76d122-986b-4270-b686-93549a1bafb4] 接收到JMETER执行数据【1 】

[The number of concurrent settings is too large: 50]
对于接口自动化用例来说,并发数不要设置太大,因为复杂的自动化用例占用内存较高,同时执行太多用例会导致长时间pengding最终超时error,建议将资源池最大并发数调整为10再执行。