咨询一下：同样host的访问，进入了不同的server_name虚拟机，报错408

woaifr20041825

线上配置了若干个server_name
server {
listen 80;
server_name www.xxx.com
}
server {
listen 80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

fengmo.q

hi,

根据你的配置，www.xxx.com是80端口的默认server，nginx中一个请求如果没有host头，或者host头的值没有匹配上任意的server_name，则这个请求会交给默认server处理。

另外一个请求刚来的时候，nginx在接收到该请求的host头之前，会根据端口及地址找到默认server，并使用它，直到nginx确切的知道该请求host头里面指定的值，再根据它重新寻找server来处理.

所以你这里状态码为408（请求超时），如果你确定都是使用s.yyy.com来访问的，极有可能是nginx在接收到该请求的host头之前已经读超时了，所以打日志的时候，打印的host为www.xxx.com （当然你的log_format 应该使用的是$host变量的话，$host变量取值为，当host头不为空，则打印host头的值，否则打印server_name）

在 2012年8月31日下午1:38，fangfang <woaifr...@126.com>写道：

线上配置了若干个server_name
server {
listen 80;
server_name www.xxx.com
}
server {
listen 80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

Jinglong
Software Engineer
Server Platforms Team at Taobao

woaifr20041825

多谢pengqi ，提问时没有说太明白，不好意思：

log_format格式如下：

log_format main '$remote_addr $http_x_forwarded_for $remote_user [$time_local] "$request" '
'$status $body_bytes_sent "$http_referer" '
'"$http_user_agent" "$http_cookie" "$request_time" "$upstream_cache_status" "$server_name" "$gzip_ratio" "$host"';

client_header_timeout设置了30s：
client_header_timeout 30s;

下面两种请求都出现了：

113.135.89.1 - - [31/Aug/2012:10:27:19 +0800] "GET /**** HTTP/1.1" 408 0 "http://s.yyy.com/xyz" "Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 2.0.50727; .NET4.0C; .NET4.0E)" "-" "5.303" "-" "www.xxx.com" "-" "s.yyy.com"

219.159.151.130 - - [31/Aug/2012:18:01:33 +0800] "GET /**** HTTP/1.1" 200 12379 "-" "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; Trident/4.0; KB974489)" "-" "0.300" "-" "s.yyy.com" "3.13" "s.yyy.com"

还有两个问题不太明白：
1、request_time:5.303s<client_header_timeout:30s
这个是为什咩？

2、如果是nginx在接收到该请求的host头之前就超时了，那么$host是不是应该打印成www.xxx.com（这个我提问时没有说清楚：） )

在 2012-08-31 17:39:34，pengqi <fen...@gmail.com> 写道：

hi,

根据你的配置，www.xxx.com是80端口的默认server，nginx中一个请求如果没有host头，或者host头的值没有匹配上任意的server_name，则这个请求会交给默认server处理。

另外一个请求刚来的时候，nginx在接收到该请求的host头之前，会根据端口及地址找到默认server，并使用它，直到nginx确切的知道该请求host头里面指定的值，再根据它重新寻找server来处理.

所以你这里状态码为408（请求超时），如果你确定都是使用s.yyy.com来访问的，极有可能是nginx在接收到该请求的host头之前已经读超时了，所以打日志的时候，打印的host为www.xxx.com （当然你的log_format 应该使用的是$host变量的话，$host变量取值为，当host头不为空，则打印host头的值，否则打印server_name）

在 2012年8月31日下午1:38，fangfang <woaifr...@126.com>写道：
线上配置了若干个server_name
server {
listen 80;
server_name www.xxx.com
}
server {
listen 80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

fengmo.q

hi,

你确定只有一个地方设置了 client_header_timeout ，且为30s 吗？

在 2012年8月31日下午7:31，方 <woaifr...@126.com>写道：

多谢pengqi ，提问时没有说太明白，不好意思：

log_format格式如下：

log_format main '$remote_addr $http_x_forwarded_for $remote_user [$time_local] "$request" '
                '$status $body_bytes_sent "$http_referer" '
                '"$http_user_agent" "$http_cookie" "$request_time" "$upstream_cache_status" "$server_name" "$gzip_ratio" "$host"';

client_header_timeout设置了30s：
client_header_timeout 30s;

下面两种请求都出现了：

113.135.89.1 - - [31/Aug/2012:10:27:19 +0800] "GET /**** HTTP/1.1" 408 0 "http://s.yyy.com/xyz" "Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 2.0.50727; .NET4.0C; .NET4.0E)" "-" "5.303" "-" "www.xxx.com" "-" "s.yyy.com"

219.159.151.130 - - [31/Aug/2012:18:01:33 +0800] "GET /**** HTTP/1.1" 200 12379 "-" "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; Trident/4.0; KB974489)" "-" "0.300" "-" "s.yyy.com" "3.13" "s.yyy.com"

还有两个问题不太明白：
1、request_time:5.303s<client_header_timeout:30s
这个是为什咩？

2、如果是nginx在接收到该请求的host头之前就超时了，那么$host是不是应该打印成www.xxx.com（这个我提问时没有说清楚：） )

在 2012-08-31 17:39:34，pengqi <fen...@gmail.com> 写道：

hi,

根据你的配置，www.xxx.com是80端口的默认server，nginx中一个请求如果没有host头，或者host头的值没有匹配上任意的server_name，则这个请求会交给默认server处理。

另外一个请求刚来的时候，nginx在接收到该请求的host头之前，会根据端口及地址找到默认server，并使用它，直到nginx确切的知道该请求host头里面指定的值，再根据它重新寻找server来处理.

所以你这里状态码为408（请求超时），如果你确定都是使用s.yyy.com来访问的，极有可能是nginx在接收到该请求的host头之前已经读超时了，所以打日志的时候，打印的host为www.xxx.com （当然你的log_format 应该使用的是$host变量的话，$host变量取值为，当host头不为空，则打印host头的值，否则打印server_name）

在 2012年8月31日下午1:38，fangfang <woaifr...@126.com>写道：
线上配置了若干个server_name
server {
listen       80;
server_name www.xxx.com
}
server {
listen       80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

Jinglong
Software Engineer
Server Platforms Team at Taobao

woaifr20041825

是的，只有一个地方配置了。

同一台服务器，两个请求的host相同，都是408，但是超时时间不一致，一个是0.003，一个是30.003，30.003的是正常的。

多谢~~

在 2012-08-31 20:31:46，pengqi <fen...@gmail.com> 写道：

hi,

你确定只有一个地方设置了 client_header_timeout ，且为30s 吗？

在 2012年8月31日下午7:31，方 <woaifr...@126.com>写道：

多谢pengqi ，提问时没有说太明白，不好意思：

log_format格式如下：

log_format main '$remote_addr $http_x_forwarded_for $remote_user [$time_local] "$request" '
                '$status $body_bytes_sent "$http_referer" '
                '"$http_user_agent" "$http_cookie" "$request_time" "$upstream_cache_status" "$server_name" "$gzip_ratio" "$host"';

client_header_timeout设置了30s：
client_header_timeout 30s;

下面两种请求都出现了：

113.135.89.1 - - [31/Aug/2012:10:27:19 +0800] "GET /**** HTTP/1.1" 408 0 "http://s.yyy.com/xyz" "Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 2.0.50727; .NET4.0C; .NET4.0E)" "-" "5.303" "-" "www.xxx.com" "-" "s.yyy.com"

219.159.151.130 - - [31/Aug/2012:18:01:33 +0800] "GET /**** HTTP/1.1" 200 12379 "-" "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; Trident/4.0; KB974489)" "-" "0.300" "-" "s.yyy.com" "3.13" "s.yyy.com"

还有两个问题不太明白：
1、request_time:5.303s<client_header_timeout:30s
这个是为什咩？

2、如果是nginx在接收到该请求的host头之前就超时了，那么$host是不是应该打印成www.xxx.com（这个我提问时没有说清楚：） )

在 2012-08-31 17:39:34，pengqi <fen...@gmail.com> 写道：

hi,

根据你的配置，www.xxx.com是80端口的默认server，nginx中一个请求如果没有host头，或者host头的值没有匹配上任意的server_name，则这个请求会交给默认server处理。

另外一个请求刚来的时候，nginx在接收到该请求的host头之前，会根据端口及地址找到默认server，并使用它，直到nginx确切的知道该请求host头里面指定的值，再根据它重新寻找server来处理.

所以你这里状态码为408（请求超时），如果你确定都是使用s.yyy.com来访问的，极有可能是nginx在接收到该请求的host头之前已经读超时了，所以打日志的时候，打印的host为www.xxx.com （当然你的log_format 应该使用的是$host变量的话，$host变量取值为，当host头不为空，则打印host头的值，否则打印server_name）

在 2012年8月31日下午1:38，fangfang <woaifr...@126.com>写道：
线上配置了若干个server_name
server {
listen       80;
server_name www.xxx.com
}
server {
listen       80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

fengmo.q

hi,

处理时间为0.003s这种请求出现可能的情况是客户端三次握手之后，没有发送数据包，快要到30s超时的时候才开始发送请求头，因为nginx记录请求的起始时间点为收到请求的第一个数据包开始，所以可能会出现这样的情况。

在 2012年8月31日下午10:00，方 <woaifr...@126.com>写道：

是的，只有一个地方配置了。

同一台服务器，两个请求的host相同，都是408，但是超时时间不一致，一个是0.003，一个是30.003，30.003的是正常的。

多谢~~

在 2012-08-31 20:31:46，pengqi <fen...@gmail.com> 写道：

hi,

你确定只有一个地方设置了 client_header_timeout ，且为30s 吗？

在 2012年8月31日下午7:31，方 <woaifr...@126.com>写道：

多谢pengqi ，提问时没有说太明白，不好意思：

log_format格式如下：

log_format main '$remote_addr $http_x_forwarded_for $remote_user [$time_local] "$request" '
                '$status $body_bytes_sent "$http_referer" '
                '"$http_user_agent" "$http_cookie" "$request_time" "$upstream_cache_status" "$server_name" "$gzip_ratio" "$host"';

client_header_timeout设置了30s：
client_header_timeout 30s;

下面两种请求都出现了：

113.135.89.1 - - [31/Aug/2012:10:27:19 +0800] "GET /**** HTTP/1.1" 408 0 "http://s.yyy.com/xyz" "Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 2.0.50727; .NET4.0C; .NET4.0E)" "-" "5.303" "-" "www.xxx.com" "-" "s.yyy.com"

219.159.151.130 - - [31/Aug/2012:18:01:33 +0800] "GET /**** HTTP/1.1" 200 12379 "-" "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; Trident/4.0; KB974489)" "-" "0.300" "-" "s.yyy.com" "3.13" "s.yyy.com"

还有两个问题不太明白：
1、request_time:5.303s<client_header_timeout:30s
这个是为什咩？

2、如果是nginx在接收到该请求的host头之前就超时了，那么$host是不是应该打印成www.xxx.com（这个我提问时没有说清楚：） )

在 2012-08-31 17:39:34，pengqi <fen...@gmail.com> 写道：

hi,

根据你的配置，www.xxx.com是80端口的默认server，nginx中一个请求如果没有host头，或者host头的值没有匹配上任意的server_name，则这个请求会交给默认server处理。

另外一个请求刚来的时候，nginx在接收到该请求的host头之前，会根据端口及地址找到默认server，并使用它，直到nginx确切的知道该请求host头里面指定的值，再根据它重新寻找server来处理.

所以你这里状态码为408（请求超时），如果你确定都是使用s.yyy.com来访问的，极有可能是nginx在接收到该请求的host头之前已经读超时了，所以打日志的时候，打印的host为www.xxx.com （当然你的log_format 应该使用的是$host变量的话，$host变量取值为，当host头不为空，则打印host头的值，否则打印server_name）

在 2012年8月31日下午1:38，fangfang <woaifr...@126.com>写道：
线上配置了若干个server_name
server {
listen       80;
server_name www.xxx.com
}
server {
listen       80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

Jinglong
Software Engineer
Server Platforms Team at Taobao

fengmo.q

另外nginx重新根据host头的值查找server的时间点是在收完所有请求头之后（此时并未接收请求体），我看到你的一个超时的请求是post类型，另外请求体接收也有超时时间(由client_body_timeout指令控制)，所以就有可能出现$host值和$server_name值一致但是返回408的请求。

在 2012年9月1日上午12:49，pengqi <fen...@gmail.com>写道：

hi,

处理时间为0.003s这种请求出现可能的情况是客户端三次握手之后，没有发送数据包，快要到30s超时的时候才开始发送请求头，因为nginx记录请求的起始时间点为收到请求的第一个数据包开始，所以可能会出现这样的情况。

在 2012年8月31日下午10:00，方 <woaifr...@126.com>写道：

是的，只有一个地方配置了。

同一台服务器，两个请求的host相同，都是408，但是超时时间不一致，一个是0.003，一个是30.003，30.003的是正常的。

多谢~~

在 2012-08-31 20:31:46，pengqi <fen...@gmail.com> 写道：

hi,

你确定只有一个地方设置了 client_header_timeout ，且为30s 吗？

在 2012年8月31日下午7:31，方 <woaifr...@126.com>写道：

多谢pengqi ，提问时没有说太明白，不好意思：

log_format格式如下：

log_format main '$remote_addr $http_x_forwarded_for $remote_user [$time_local] "$request" '
                '$status $body_bytes_sent "$http_referer" '
                '"$http_user_agent" "$http_cookie" "$request_time" "$upstream_cache_status" "$server_name" "$gzip_ratio" "$host"';

client_header_timeout设置了30s：
client_header_timeout 30s;

下面两种请求都出现了：

113.135.89.1 - - [31/Aug/2012:10:27:19 +0800] "GET /**** HTTP/1.1" 408 0 "http://s.yyy.com/xyz" "Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 2.0.50727; .NET4.0C; .NET4.0E)" "-" "5.303" "-" "www.xxx.com" "-" "s.yyy.com"

219.159.151.130 - - [31/Aug/2012:18:01:33 +0800] "GET /**** HTTP/1.1" 200 12379 "-" "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; Trident/4.0; KB974489)" "-" "0.300" "-" "s.yyy.com" "3.13" "s.yyy.com"

还有两个问题不太明白：
1、request_time:5.303s<client_header_timeout:30s
这个是为什咩？

2、如果是nginx在接收到该请求的host头之前就超时了，那么$host是不是应该打印成www.xxx.com（这个我提问时没有说清楚：） )

在 2012-08-31 17:39:34，pengqi <fen...@gmail.com> 写道：

hi,

根据你的配置，www.xxx.com是80端口的默认server，nginx中一个请求如果没有host头，或者host头的值没有匹配上任意的server_name，则这个请求会交给默认server处理。

另外一个请求刚来的时候，nginx在接收到该请求的host头之前，会根据端口及地址找到默认server，并使用它，直到nginx确切的知道该请求host头里面指定的值，再根据它重新寻找server来处理.

所以你这里状态码为408（请求超时），如果你确定都是使用s.yyy.com来访问的，极有可能是nginx在接收到该请求的host头之前已经读超时了，所以打日志的时候，打印的host为www.xxx.com （当然你的log_format 应该使用的是$host变量的话，$host变量取值为，当host头不为空，则打印host头的值，否则打印server_name）

在 2012年8月31日下午1:38，fangfang <woaifr...@126.com>写道：
线上配置了若干个server_name
server {
listen       80;
server_name www.xxx.com
}
server {
listen       80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

Jinglong
Software Engineer
Server Platforms Team at Taobao

woaifr20041825

多谢pengqi，恍然大悟

       client_body_timeout也是设置了也是30s，我一直钻的是client_header_timeout ，牛角尖了，哈哈
       request_time的起始点是在ngx_http_init_request设置的，而ngx_http_init_request是rev可读触发的，原来想当然了~
       真是太感谢了~

      刚全部复现了一下，哈哈，接下来要琢磨一下参数的值了，再次感谢~

祝好~
方芳

在 2012-09-01 00:55:46，pengqi <fen...@gmail.com> 写道：

另外nginx重新根据host头的值查找server的时间点是在收完所有请求头之后（此时并未接收请求体），我看到你的一个超时的请求是post类型，另外请求体接收也有超时时间(由client_body_timeout指令控制)，所以就有可能出现$host值和$server_name值一致但是返回408的请求。

在 2012年9月1日上午12:49，pengqi <fen...@gmail.com>写道：

hi,

处理时间为0.003s这种请求出现可能的情况是客户端三次握手之后，没有发送数据包，快要到30s超时的时候才开始发送请求头，因为nginx记录请求的起始时间点为收到请求的第一个数据包开始，所以可能会出现这样的情况。

在 2012年8月31日下午10:00，方 <woaifr...@126.com>写道：

是的，只有一个地方配置了。

同一台服务器，两个请求的host相同，都是408，但是超时时间不一致，一个是0.003，一个是30.003，30.003的是正常的。

多谢~~

在 2012-08-31 20:31:46，pengqi <fen...@gmail.com> 写道：

hi,

你确定只有一个地方设置了 client_header_timeout ，且为30s 吗？

在 2012年8月31日下午7:31，方 <woaifr...@126.com>写道：

多谢pengqi ，提问时没有说太明白，不好意思：

log_format格式如下：

log_format main '$remote_addr $http_x_forwarded_for $remote_user [$time_local] "$request" '
                '$status $body_bytes_sent "$http_referer" '
                '"$http_user_agent" "$http_cookie" "$request_time" "$upstream_cache_status" "$server_name" "$gzip_ratio" "$host"';

client_header_timeout设置了30s：
client_header_timeout 30s;

下面两种请求都出现了：

113.135.89.1 - - [31/Aug/2012:10:27:19 +0800] "GET /**** HTTP/1.1" 408 0 "http://s.yyy.com/xyz" "Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 2.0.50727; .NET4.0C; .NET4.0E)" "-" "5.303" "-" "www.xxx.com" "-" "s.yyy.com"

219.159.151.130 - - [31/Aug/2012:18:01:33 +0800] "GET /**** HTTP/1.1" 200 12379 "-" "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; Trident/4.0; KB974489)" "-" "0.300" "-" "s.yyy.com" "3.13" "s.yyy.com"

还有两个问题不太明白：
1、request_time:5.303s<client_header_timeout:30s
这个是为什咩？

2、如果是nginx在接收到该请求的host头之前就超时了，那么$host是不是应该打印成www.xxx.com（这个我提问时没有说清楚：） )

在 2012-08-31 17:39:34，pengqi <fen...@gmail.com> 写道：

hi,

根据你的配置，www.xxx.com是80端口的默认server，nginx中一个请求如果没有host头，或者host头的值没有匹配上任意的server_name，则这个请求会交给默认server处理。

另外一个请求刚来的时候，nginx在接收到该请求的host头之前，会根据端口及地址找到默认server，并使用它，直到nginx确切的知道该请求host头里面指定的值，再根据它重新寻找server来处理.

所以你这里状态码为408（请求超时），如果你确定都是使用s.yyy.com来访问的，极有可能是nginx在接收到该请求的host头之前已经读超时了，所以打日志的时候，打印的host为www.xxx.com （当然你的log_format 应该使用的是$host变量的话，$host变量取值为，当host头不为空，则打印host头的值，否则打印server_name）

在 2012年8月31日下午1:38，fangfang <woaifr...@126.com>写道：
线上配置了若干个server_name
server {
listen       80;
server_name www.xxx.com
}
server {
listen       80;
server_name s.yyy.com
}。
现有同样的s.yyy.com请求访问服务器，但是有少量请求打出的access日志显示server_name为：www.xxx.com，并且状态码为408，且request_time<client_header_timeout；
大部分请求是正常的，都是进入了s.yyy.com

请帮忙解答，多谢~

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

--

Jinglong
Software Engineer
Server Platforms Team at Taobao

--

Jinglong
Software Engineer
Server Platforms Team at Taobao