iris ：Unsolicited response received on idle HTTP channel starting with “[]\n“； err=

超、凢脫俗 2022-01-31 02:13 64阅读 0赞

最近在用 golang 开发一个项目, 项目用的是 iris 这个web框架。

我们的这个项目是用golang 实现的ADX (AD EXCHNAGE), 说白了就是一个广告的开关，

说白了就是移动端请求ADX,ADX 再去请求第三方广告平台（简称DSP），然后把广告信息返回给移动端进行展示。

最近测试服务器报出一个诡异的的日志打印，服务器没处理一个请求都会打印这个日志，日志如下：

2016/11/23 03:35:17 Unsolicited response received on idle HTTP channel starting with “\[\]\\n”; err=  
这个日志打印是 net/http/transport.go, 代码如下：  
![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L3poaXpoZW5nZ3Vhbg_size_16_color_FFFFFF_t_70]

找到了打印日志的地方，是什么原因导致的这个问题，还是不清楚。最后google 上的一篇文章，解释了这个问题的根源。

原文地址：https://codereview.appspot.com/86740044

以下引用原文：  
![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L3poaXpoZW5nZ3Vhbg_size_16_color_FFFFFF_t_70 1]  
中文大致意思是: **后台服务器如果处理慢，导致代理（nginx 等）认为超时，代理把这个连接关闭，正好这个时候，后台server 的响应已经发出，这时候 net/http/transport.go 就会检查到这个响应，并认为是不可预期的，从而打印这个日志**。

Github 上有 demo 可以复现这个问题，有兴趣继续研究的同学，可以访问：https://gist.github.com/cespare/f4352a11bac5cced9458。

问题定位到这里，解决办法就两种，要么加快后端server的处理速度，要么调整代理的超时时间。

[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L3poaXpoZW5nZ3Vhbg_size_16_color_FFFFFF_t_70]: /images/20220131/0e06c9ea0ebf465ea2d5832bb67f7c74.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L3poaXpoZW5nZ3Vhbg_size_16_color_FFFFFF_t_70 1]: /images/20220131/e7110642d7b04c3f8a3e9510863e0e1f.png