Sudheer Vinukonda created TS-3415:
-------------------------------------

             Summary: H2 sending FIN stream too early
                 Key: TS-3415
                 URL: https://issues.apache.org/jira/browse/TS-3415
             Project: Traffic Server
          Issue Type: Bug
          Components: HTTP/2
            Reporter: Sudheer Vinukonda


With H2 running on the docs/jenkins server, some of us have noticed empty pages 
on chrome/FF when hitting those servers. Looking at the H2 traces, it seems 
that ATS is sending FIN streams (both SPDY_SESSION_RECV_HEADERS and 
SPDY_SESSION_RECV_DATA with fin flag set to true) before sending the data 
frames. This makes the browser display blank pages.

Example:

{code}
t=31890437 [st=140]    SPDY_SESSION_RECV_DATA
                       --> fin = true
                       --> size = 0
                       --> stream_id = 1
t=31890437 [st=140]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 2591
                       --> stream_id = 1

{code}

Detailed traces below:

{code}
1112054: SPDY_SESSION
ci.trafficserver.apache.org:443 (DIRECT)
Start Time: 2015-02-26 13:30:14.060

t=31890297 [st=  0] +SPDY_SESSION  [dt=?]
                     --> host = "ci.trafficserver.apache.org:443"
                     --> proxy = "DIRECT"
t=31890297 [st=  0]    SPDY_SESSION_INITIALIZED
                       --> protocol = "h2-14"
                       --> source_dependency = 1112049 (SOCKET)
t=31890297 [st=  0]    SPDY_SESSION_SEND_SETTINGS
                       --> settings = ["[id:3 flags:0 value:1000]","[id:4 
flags:0 value:10485760]"]
t=31890297 [st=  0]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 10420224
                       --> window_size = 10485760
t=31890297 [st=  0]    SPDY_SESSION_SENT_WINDOW_UPDATE_FRAME
                       --> delta = 10420224
                       --> stream_id = 0
t=31890297 [st=  0]    SPDY_SESSION_SYN_STREAM
                       --> fin = true
                       --> :authority: ci.trafficserver.apache.org
                           :method: GET
                           :path: /job/tsqa-master/180/consoleFull
                           :scheme: https
                           accept: 
text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8
                           accept-encoding: gzip, deflate, sdch
                           accept-language: en-US,en;q=0.8
                           cache-control: max-age=0
                           cookie: [71 bytes were stripped]
                           user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 
10_9_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/40.0.2214.115 
Safari/537.36
                       --> spdy_priority = 0
                       --> stream_id = 1
                       --> unidirectional = false
t=31890335 [st= 38]    SPDY_SESSION_RECV_SETTINGS
                       --> clear_persisted = false
                       --> host = "ci.trafficserver.apache.org:443"
t=31890335 [st= 38]    SPDY_SESSION_RECV_SETTING
                       --> flags = 0
                       --> id = 1
                       --> value = 4096
t=31890335 [st= 38]    SPDY_SESSION_RECV_SETTING
                       --> flags = 0
                       --> id = 2
                       --> value = 0
t=31890335 [st= 38]    SPDY_SESSION_RECV_SETTING
                       --> flags = 0
                       --> id = 3
                       --> value = 100
t=31890436 [st=139]    SPDY_SESSION_RECV_HEADERS
                       --> fin = true
                       --> :status: 200
                           age: 1
                           cache-control: no-cache,no-store,must-revalidate
                           content-encoding: gzip
                           content-type: text/html;charset=UTF-8
                           date: Thu, 26 Feb 2015 21:30:14 GMT
                           expires: Thu, 01 Jan 1970 00:00:00 GMT
                           server: ATS/5.3.0
                           set-cookie: [67 bytes were stripped]
                           strict-transport-security: max-age=86400
                           via: http/1.1 ATS (ApacheTrafficServer/5.3.0 [cMsSf 
])
                           x-frame-options: sameorigin
                           x-hudson: 1.395
                           x-hudson-cli-port: 40666
                           x-hudson-theme: default
                           x-instance-identity: 
MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEA5JuJJnF3WReAyfHqknx9O1JV3chT6Iihwk/UIOYpZ6tLORwKrzPYVO6ho7cpwT9Jtfj91lb9Gpx0FZnhGZXsdh9SMYG8gUfGo3DRy9dLkXjNu5kYo9PQk701PvnCQJSByIpzGWTCoIwfgWTGqMEuGYZ5TvRbLnmm9j9ujo2SQpOeAUXi9f0d27wcb/MOYv8H8nICgsAcpp1AaTcboabfsJYWNodDDPYvtCjfIbQhBov5qDFnsmYXbmWavXaJ2ZGI24jSmdebFcto7NlbLLAI0JFHFualDGvfcY51NGaiK1+MNxllYsEnVSP6kSdxEtJSHOxN7z6oRVGKExh104YyDQIDAQAB
                           x-jenkins: 1.598
                           x-jenkins-cli-port: 40666
                           x-jenkins-cli2-port: 40666
                           x-jenkins-session: 906fc928
                       --> stream_id = 1
t=31890437 [st=140]    SPDY_SESSION_RECV_DATA
                       --> fin = true
                       --> size = 0
                       --> stream_id = 1
t=31890437 [st=140]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 2591
                       --> stream_id = 1
t=31890437 [st=140]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -2591
                       --> window_size = 10483169
t=31890437 [st=140]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 2591
                       --> window_size = 10485760
t=31890437 [st=140]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 5592
                       --> stream_id = 1
t=31890437 [st=140]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -5592
                       --> window_size = 10480168
t=31890437 [st=140]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 5592
                       --> window_size = 10485760
t=31890437 [st=140]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 2591
                       --> stream_id = 1
t=31890437 [st=140]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -2591
                       --> window_size = 10483169
t=31890437 [st=140]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 2591
                       --> window_size = 10485760
t=31890488 [st=191]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 3900
                       --> stream_id = 1
t=31890488 [st=191]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -3900
                       --> window_size = 10481860
t=31890488 [st=191]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 3900
                       --> window_size = 10485760
t=31890488 [st=191]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 1692
                       --> stream_id = 1
t=31890488 [st=191]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -1692
                       --> window_size = 10484068
t=31890488 [st=191]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 1692
                       --> window_size = 10485760
t=31890488 [st=191]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 6491
                       --> stream_id = 1
t=31890488 [st=191]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -6491
                       --> window_size = 10479269
t=31890488 [st=191]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 6491
                       --> window_size = 10485760
t=31890488 [st=191]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 1692
                       --> stream_id = 1
t=31890488 [st=191]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -1692
                       --> window_size = 10484068
t=31890488 [st=191]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 1692
                       --> window_size = 10485760
t=31890488 [st=191]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 6491
                       --> stream_id = 1
t=31890488 [st=191]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -6491
                       --> window_size = 10479269
t=31890488 [st=191]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 6491
                       --> window_size = 10485760
t=31890488 [st=191]    SPDY_SESSION_RECV_DATA
                       --> fin = false
                       --> size = 647
                       --> stream_id = 1
t=31890488 [st=191]    SPDY_SESSION_UPDATE_RECV_WINDOW
                       --> delta = -647
                       --> window_size = 10485113
t=31890488 [st=191]    SPDY_STREAM_UPDATE_RECV_WINDOW
                       --> delta = 647
                       --> window_size = 10485760
t=31890488 [st=191]    SPDY_SESSION_RECV_DATA
                       --> fin = true
                       --> size = 0
                       --> stream_id = 1
t=31890490 [st=193]    SPDY_SESSION_SYN_STREAM
                       --> fin = true
                       --> :authority: ci.trafficserver.apache.org
                           :method: GET
                           :path: /favicon.ico
                           :scheme: https
                           accept: */*
                           accept-encoding: gzip, deflate, sdch
                           accept-language: en-US,en;q=0.8
                           cookie: [71 bytes were stripped]
                           if-modified-since: Fri, 30 Jan 2015 15:31:33 GMT
                           user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 
10_9_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/40.0.2214.115 
Safari/537.36
                       --> spdy_priority = 4
                       --> stream_id = 3
                       --> unidirectional = false
t=31890555 [st=258]    SPDY_SESSION_RECV_HEADERS
                       --> fin = true
                       --> :status: 304
                           date: Thu, 26 Feb 2015 21:30:14 GMT
                           expires: Mon, 09 Feb 2015 19:39:27 GMT
                           server: ATS/5.3.0
                           strict-transport-security: max-age=86400
                           via: http/1.1 ATS (ApacheTrafficServer/5.3.0 
[cSsNfU])
                       --> stream_id = 3
t=31890555 [st=258]    SPDY_SESSION_RECV_DATA
                       --> fin = true
                       --> size = 0
                       --> stream_id = 3
{code} 





--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Reply via email to