On 21/11/2012, at 9:06 PM, 黄英才 <[email protected]> wrote:
> HI!all
> When i test thd spdy plugin .i got the follow error
> my ts's error.log :
>
> 20121122.10h57m06s [spdy] client is version 2, but we implement version 3
> 20121122.10h57m07s [spdy] client is version 2, but we implement version 3
> 20121122.10h57m37s [spdy] client is version 2, but we implement version 3
> 20121122.10h57m37s RESPONSE: sent 58.215.133.110 status 400 (Invalid HTTP
> Request) for '/'
> 20121122.10h57m38s [spdy] client is version 2, but we implement version 3
> 20121122.10h57m49s [spdy] client is version 2, but we implement version 3
> 20121122.10h57m50s [spdy] client is version 2, but we implement version 3
> 20121122.11h00m38s [spdy] client is version 2, but we implement version 3
> 20121122.11h00m38s [spdy] client is version 2, but we implement version 3
> 20121122.11h01m09s [spdy] client is version 2, but we implement version 3
These messages are just log noise.
> 20121122.11h01m09s RESPONSE: sent 58.215.133.110 status 400 (Invalid HTTP
> Request) for '/'
The SPDY logs look ok; there's no indication from there what is causing the 400
response. Try using the Chrome net://internals UI to examine the SPDY flow.
Otherwise, the next step might be to crank up the logging on the ATS side.
>
>
> <image.png>
>
>
> Enable the debug at records.config .I got the follow
> [Nov 22 11:22:15.180] Server {0x2af73cbab700} DIAG: (spdy.plugin)
> spdy_vconn_io:289 received 45 bytes
> [Nov 22 11:22:15.181] Server {0x2af73cbab700} DIAG: (spdy.protocol)
> recv_syn_stream:67 [0x2af76c00eeb0/21] received CONTROL_SYN_STREAM frame
> stream=21 associated=0 priority=0
> [Nov 22 11:22:15.182] Server {0x2af73cbab700} DIAG: (spdy.http)
> initiate_host_resolution:298 resolving hostname 'spdy.myccdn.com'
> [Nov 22 11:22:15.189] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:148 [0x2af76c004a80/21] received TS_EVENT_HOST_LOOKUP event
> [Nov 22 11:22:15.189] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:169 [0x2af76c00eeb0/21] resolved spdy.myccdn.com =>
> 58.215.133.110
> [Nov 22 11:22:15.190] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:148 [0x2af76c004a80/21] received TS_EVENT_VCONN_WRITE_READY
> event
> [Nov 22 11:22:15.190] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> debug_http_header:174 [0x2af76c004a80/21] http request (350 of 350 bytes):
> GET https://spdy.myccdn.com//index.html HTTP/1.1
> accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
> accept-charset: GBK,utf-8;q=0.7,*;q=0.3
> accept-encoding: gzip,deflate,sdch
> accept-language: zh-CN,zh;q=0.8
> user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/535.7 (KHTML, like
> Gecko) Chrome/16.0.912.63 Safari/535.7
>
>
> [Nov 22 11:22:15.190] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:148 [0x2af76c004a80/21] received TS_EVENT_VCONN_WRITE_READY
> event
> [Nov 22 11:22:15.191] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:148 [0x2af76c004a80/21] received TS_EVENT_VCONN_WRITE_READY
> event
> [Nov 22 11:22:15.194] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:148 [0x2af76c004a80/21] received TS_EVENT_VCONN_READ_READY
> event
> [Nov 22 11:22:15.194] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> read_http_headers:126 [0x2af76c004a80/21] received 276 header bytes
> [Nov 22 11:22:15.194] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> debug_http_header:174 [0x2af76c004a80/21] http request (264 of 264 bytes):
> HTTP/1.1 200 OK
> Date: Thu, 22 Nov 2012 03:22:15 GMT
> Server: ATS/3.3.1-dev
> Last-Modified: Thu, 20 Sep 2012 20:13:13 GMT
> ETag: "c-4ca27c0c7f70b"
> Accept-Ranges: bytes
> Content-Length: 12
> Content-Type: text/html; charset=UTF-8
> Age: 0
> Connection: keep-alive
>
>
> [Nov 22 11:22:15.194] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> http_send_response:86 [0x2af76c00eeb0/21] skipping Connection header
> [Nov 22 11:22:15.195] Server {0x2af723e8c4c0} DIAG: (spdy.protocol)
> spdy_send_syn_reply:101 [0x2af76c00eeb0/21] sending CONTROL_SYN_REPLY
> hdr.datalen=21
> [Nov 22 11:22:15.195] Server {0x2af723e8c4c0} DIAG: (spdy.protocol)
> spdy_send_data_frame:153 [0x2af76c00eeb0/21] sending DATA flags=0
> hdr.datalen=12
> [Nov 22 11:22:15.195] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:148 [0x2af76c004a80/21] received TS_EVENT_VCONN_READ_READY
> event
> [Nov 22 11:22:15.196] Server {0x2af723e8c4c0} DIAG: (spdy.protocol)
> spdy_send_data_frame:153 [0x2af76c00eeb0/21] sending DATA flags=0
> hdr.datalen=224
> [Nov 22 11:22:15.196] Server {0x2af723e8c4c0} DIAG: (spdy.http)
> spdy_stream_io:148 [0x2af76c004a80/21] received TS_EVENT_VCONN_EOS event
> [Nov 22 11:22:15.197] Server {0x2af723e8c4c0} DIAG: (spdy.protocol)
> spdy_send_data_frame:153 [0x2af76c00eeb0/21] sending DATA flags=1
> hdr.datalen=0
> [Nov 22 11:22:16.181] Server {0x2af73cbab700} DIAG: (spdy.plugin)
> spdy_vconn_io:289 received 12 bytes
> [Nov 22 11:22:16.182] Server {0x2af73cbab700} DIAG: (spdy.protocol)
> recv_ping:134 [0x2af76c00eeb0] received PING id=19
> [Nov 22 11:22:16.183] Server {0x2af73cbab700} DIAG: (spdy.protocol)
> spdy_send_ping:184 [0x2af76c00eeb0] sending PING id=19
>
>
> My remap.config
>
> regex_map https://([a-zA-Z0-9\.-]+)/ http://127.0.0.1:81/
>
>
> My apache2 listen 81 (none https)
> and vim /var/www/html/index.html
> google spdy
>
> Test on chrome(the version of 16.0.912.63)
>
>
> Anyone can help me or give me some advice?
> thanks
