Fixed: Failed to parse multipart servlet request； nested exception is java.io.IOException

前言

今天在生产环境发生一个很诡异的bug，后台管理系统有一个文件上传功能。

奇怪的是，有时候文件上传可以成功,有时候上传文件会失败,诡异的很。

后台抓过来错误日志报错如下：

Servlet.service() for servlet [dispatcherServlet] in context with path [/xxxx/test-api] threw exception [Request processing failed; nested exception is org.springframework.web.multipart.MultipartException: Failed to parse multipart servlet request; nested exception is java.io.IOException: org.apache.tomcat.util.http.fileupload.impl.IOFileUploadException: Processing of multipart/form-data request failed. Stream closed] with root cause
java.io.IOException: Stream closed
        at org.apache.catalina.connector.InputBuffer.throwIfClosed(InputBuffer.java:527)
        at org.apache.catalina.connector.InputBuffer.read(InputBuffer.java:338)
        at org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:132)
        at java.io.FilterInputStream.read(FilterInputStream.java:133)
        at org.apache.tomcat.util.http.fileupload.util.LimitedInputStream.read(LimitedInputStream.java:132)
        at org.apache.tomcat.util.http.fileupload.MultipartStream$ItemInputStream.makeAvailable(MultipartStream.java:975)
        at org.apache.tomcat.util.http.fileupload.MultipartStream$ItemInputStream.read(MultipartStream.java:879)
        at java.io.FilterInputStream.read(FilterInputStream.java:133)
        at org.apache.tomcat.util.http.fileupload.util.LimitedInputStream.read(LimitedInputStream.java:132)
        at java.io.FilterInputStream.read(FilterInputStream.java:107)
        at org.apache.tomcat.util.http.fileupload.util.Streams.copy(Streams.java:98)
        at org.apache.tomcat.util.http.fileupload.FileUploadBase.parseRequest(FileUploadBase.java:291)
        at org.apache.catalina.connector.Request.parseParts(Request.java:2922)
        at org.apache.catalina.connector.Request.parseParameters(Request.java:3253)
        at org.apache.catalina.connector.Request.getParameter(Request.java:1142)
        at org.apache.catalina.connector.RequestFacade.getParameter(RequestFacade.java:381)
        at javax.servlet.ServletRequestWrapper.getParameter(ServletRequestWrapper.java:158)
        at org.springframework.security.web.firewall.StrictHttpFirewall$StrictFirewalledRequest.getParameter(StrictHttpFirewall.java:687)
        at javax.servlet.ServletRequestWrapper.getParameter(ServletRequestWrapper.java:158)
        at javax.servlet.ServletRequestWrapper.getParameter(ServletRequestWrapper.java:158)
        at javax.servlet.ServletRequestWrapper.getParameter(ServletRequestWrapper.java:158)

问题分析

针对报错信息，刚开始谷歌搜了一些资料

有的说需要配置修改下temp路径：

有的说是tomcat的一个bug，需要打开如下设置：

spring:
  mvc:
    hiddenmethod:
      filter:
        enabled: true

但是测试后发现并不好使。

于是尝试将Spring Boot 内置的tomcat 换成了Jetty,

但是发现切换成Jetty 之后虽然可以上传了，但是上传的图片都是一半，有另外一半压根显示不出来，也就是说上传的图片是残缺不完整的。

于是我又换成了Undertow ,发现问题和使用tomcat 一样。

直到饭后吃过饭后我继续追查日志，才发现一个问题根源：

有一个过滤器最开始是这么写的：

@Order(1)
@WebFilter(filterName = "appTokenAuthenticationFilter",urlPatterns = {"/*"},description = "APP接口权限过滤器")
@Slf4j
public class AppTokenAuthenticationFilter extends OncePerRequestFilter {

    @Autowired
    private CustomerInfoAppService customerInfoAppService;

    @Autowired
    private UserInfoLoginService userInfoLoginService;

    @Override
    protected void doFilterInternal(HttpServletRequest request, HttpServletResponse response, FilterChain filterChain) throws ServletException, IOException {

        try {
            MyAppThreadFactory.myCachedThreadPool.execute(() -> {
		        String token = request.getParameter("token_id");
		        String appType= request.getHeader("appType");
                ...
        } catch (Exception e) {
            log.error("更新出错",e);
        }
        //继续调用其他过滤器
        filterChain.doFilter(request,response);
    }
}

初步看好像也没啥的，但是这里却是最可疑的：

String token = request.getParameter("token_id");
String appType= request.getHeader("appType");

解决方案

当我尝试将这两行代码放到线程外面后果然好了。

修复后代码如下：

当在过滤器中开启一个线程，并且把request,response 同时带进这个线程中：

@Order(1)
@WebFilter(filterName = "appTokenAuthenticationFilter",urlPatterns = {"/*"},description = "APP接口权限过滤器")
@Slf4j
public class AppTokenAuthenticationFilter extends OncePerRequestFilter {

    @Autowired
    private CustomerInfoAppService customerInfoAppService;

    @Autowired
    private UserInfoLoginService userInfoLoginService;

    @Override
    protected void doFilterInternal(HttpServletRequest request, HttpServletResponse response, FilterChain filterChain) throws ServletException, IOException {
        try {
		    String token = request.getParameter("token_id");
		    String appType= request.getHeader("appType");
            MyAppThreadFactory.myCachedThreadPool.execute(() -> {
                if(null!= SmartStringUtils.trimToNull(appType)&&null!=SmartStringUtils.trimToNull(token)){
                   ...
            });
        } catch (Exception e) {
            log.error("更新出错",e);
        }
        //继续调用其他过滤器
        filterChain.doFilter(request,response);
    }
}

思考

为什么呢？

这里大胆猜测下，

• 首先我们知道tomcat,Jetty,Undertow 都是基于Servlet API设计的web容器

• 其次，每发出一次请求都是一个线程中处理，经过过滤器, 然后request和response 由于传入了新开的线程，当同时有两个线程先后请求的时候可能会出现一个数据错位。

为了解释清楚，我们这里假设有两个管理员在执行上传文件操作：

• 第一个用户张三发出了一个请求，系统为该用户分配一个线程A 去处理。

• 后来发现新开了一个线程 W 要去处理点东西，将线程A的request 和response 传递了进入。

• 第二个用户李四这时候也发出了一个请求，系统同样为该用户分配一个线程B 去处理。

• 后来发现新开了一个线程 W 要去处理点东西，将线程B 的request 和response 传递了进入。

• B 等了一会，结果W现场先结束，把线程A的request 和response 返回了，但是由于线程A

• 没有等待W 处理完，由于自己已经完成了文件上传，因此就把inputstream 关闭了。

• 而这时候线程B 读到了线程A 已经关闭的request 和response。

因此，这个问题就产生了，除此之外，一定要保证自己打开的inputstream 等I/O流用完后要记得关闭。

总结

多线程下操作数据还是要多当心,用完的I/O流要记得关闭，否则可能会造成内存溢出。