[
https://issues.apache.org/jira/browse/TS-3521?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14494423#comment-14494423
]
Susan Hinrichs commented on TS-3521:
------------------------------------
Doing a more complete debug capture staring with a completely new HTTP/2
connection attempting to contact https://www.google.com
{code}
[Apr 14 12:13:11.289] Server {0x7ffff40ad700} DEBUG: (http2_seq)
[HttpSessionAccept2:mainEvent 0x7fffe8018840] accepted connection from
192.168.126.130:49866 transport type = 4
[Apr 14 12:13:11.289] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] session
born, netvc 0x7fffe8018840
[Apr 14 12:13:11.290] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_read_connection_preface, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_read_connection_preface, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] received
connection preface
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] receiving
frame header
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] frame
header length=12, type=4, flags=0x0, streamid=0
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] completed
frame read, 34 bytes available
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] Received
SETTINGS frame.
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] setting
param=4 value=131072
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] setting
param=5 value=16384
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] receiving
frame header
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] frame
header length=4, type=8, flags=0x0, streamid=0
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] completed
frame read, 13 bytes available
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] Received
WINDOW_UPDATE frame.
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] receiving
frame header
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] frame
header length=0, type=4, flags=0x1, streamid=0
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] completed
frame read, 0 bytes available
[Apr 14 12:13:11.310] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] Received
SETTINGS frame.
[Apr 14 12:13:28.553] Server {0x7ffff3fac700} DEBUG: (http2_seq)
[HttpSessionAccept2:mainEvent 0x7fffe801a580] accepted connection from
192.168.126.130:49877 transport type = 4
[Apr 14 12:13:28.553] Server {0x7ffff3fac700} DEBUG: (http2_cs) [129] session
born, netvc 0x7fffe801a580
[Apr 14 12:13:28.553] Server {0x7ffff3fac700} DEBUG: (http2_cs) [129]
[&Http2ClientSession::state_read_connection_preface, VC_EVENT_READ_READY]
[Apr 14 12:13:28.556] Server {0x7ffff3fac700} DEBUG: (http2_cs) [129] session
closed
[Apr 14 12:13:28.556] Server {0x7ffff3fac700} DEBUG: (http2_cs) [129] session
destroy
[Apr 14 12:14:09.504] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:14:09.504] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] receiving
frame header
[Apr 14 12:14:09.504] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] frame
header length=8, type=6, flags=0x0, streamid=0
[Apr 14 12:14:09.504] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:14:09.504] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] completed
frame read, 8 bytes available
[Apr 14 12:14:09.504] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] Received
PING frame.
[Apr 14 12:15:07.512] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:15:07.512] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] receiving
frame header
[Apr 14 12:15:07.512] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] frame
header length=8, type=6, flags=0x0, streamid=0
[Apr 14 12:15:07.512] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118]
[&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY]
[Apr 14 12:15:07.512] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] completed
frame read, 8 bytes available
[Apr 14 12:15:07.512] Server {0x7ffff40ad700} DEBUG: (http2_cs) [118] Received
PING frame.
{code}
Looking at the PCAP of the connection, the server (ATS) sends some data. The
client sends a SSL alert and FIN's the connection.
> Several web sites don't load when HTTP/2 is enabled in ATS
> ----------------------------------------------------------
>
> Key: TS-3521
> URL: https://issues.apache.org/jira/browse/TS-3521
> Project: Traffic Server
> Issue Type: Bug
> Components: HTTP/2
> Reporter: Susan Hinrichs
>
> I'm running in transparent forward proxy mode. If I don't have HTTP2
> enabled, I can load the following sites from my Firefox 36.04 client running
> on Win 7
> http://busey.com (which redirects to URLs under https://www.busey.com)
> https://www.google.com
> When I enable http2, these sites don't load. My Firefox tab stays empty (or
> shows the previous site).
> If I turn on debug http2, I see the following from ATS
> For busey
> {code}
> [Apr 14 12:05:46.544] Server {0x7ffff3fac700} DEBUG: (http2_seq)
> [HttpSessionAccept2:mainEvent 0x7fffe8018840] accepted connection from
> 192.168.126.130:49851 transport type = 4
> [Apr 14 12:05:46.544] Server {0x7ffff3fac700} DEBUG: (http2_cs) [102] session
> born, netvc 0x7fffe8018840
> [Apr 14 12:05:46.544] Server {0x7ffff3fac700} DEBUG: (http2_cs) [102]
> [&Http2ClientSession::state_read_connection_preface, VC_EVENT_READ_READY]
> [Apr 14 12:05:46.549] Server {0x7ffff3fac700} DEBUG: (http2_cs) [102] session
> closed
> [Apr 14 12:05:46.549] Server {0x7ffff3fac700} DEBUG: (http2_cs) [102] session
> destroy
> {code}
> For https://www.google.com
> {code}
> [Apr 14 12:06:49.201] Server {0x7ffff3eab700} DEBUG: (http2_seq)
> [HttpSessionAccept2:mainEvent 0x7fffe8018840] accepted connection from
> 192.168.126.130:49852 transport type = 4
> [Apr 14 12:06:49.201] Server {0x7ffff3eab700} DEBUG: (http2_cs) [103] session
> born, netvc 0x7fffe8018840
> [Apr 14 12:06:49.201] Server {0x7ffff3eab700} DEBUG: (http2_cs) [103]
> [&Http2ClientSession::state_read_connection_preface, VC_EVENT_READ_READY]
> [Apr 14 12:06:49.214] Server {0x7ffff3eab700} DEBUG: (http2_cs) [103] session
> closed
> [Apr 14 12:06:49.214] Server {0x7ffff3eab700} DEBUG: (http2_cs) [103] session
> destroy
> {code}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
