Closed Bug 1069724 Opened 10 years ago Closed 10 years ago

Multiple HTTP2 Connections on shift-reload

Categories

(Core :: Networking: HTTP, defect)

Product:
Core
Component:
Networking: HTTP
Platform:
x86_64
Linux
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla36

People

(Reporter: gregw, Assigned: mcmanus)

References

Details

(Whiteboard: [spdy])

Attachments

(1 file)

all conn reuse after conn pool flush on shift-reload
1.29 KB, patch
u408661
: review+
Details | Diff | Splinter Review 
User Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:32.0) Gecko/20100101 Firefox/32.0
Build ID: 20140830210550

Steps to reproduce:

Using 35.0a1 (2014-09-19) built from a checkout

Load a page with HTTP2 that contains multiple images (eg https://webtide.com/http2-tests/push/)

Click shift-reload


Actual results:

Multiple parallel connections are opened to reload the page, which each HTTP2 connection only being used for 1 request an then closed.   Here is a log from Jetty illustrating what the server sees for a shift-reload of a similar page to the one list above (note every request is #3 stream id and that multiple connections are open at the same time):



2014-09-19 12:37:57.397:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Close HTTP2ServerConnection@3c04ae68{FILLING} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for bb0bd19[SSLEngine[hostname=127.0.0.1 port=58199] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for bb0bd19[SSLEngine[hostname=127.0.0.1 port=58199] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.401:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Open HTTP2ServerConnection@1c166fb5{IDLE} 
2014-09-19 12:37:57.416:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-16: HTTP2 Request #3/5112899b:
GET https://localhost:8443/http2/tiles/ HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:57.423:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Close HTTP2ServerConnection@1c166fb5{FILLING} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 17de6276[SSLEngine[hostname=127.0.0.1 port=58202] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 17de6276[SSLEngine[hostname=127.0.0.1 port=58202] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.519:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Open HTTP2ServerConnection@5cab664e{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 1f33130b[SSLEngine[hostname=127.0.0.1 port=58200] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 1f33130b[SSLEngine[hostname=127.0.0.1 port=58200] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 186dc153[SSLEngine[hostname=127.0.0.1 port=58204] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 186dc153[SSLEngine[hostname=127.0.0.1 port=58204] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.526:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Open HTTP2ServerConnection@3fc80516{IDLE} 
2014-09-19 12:37:57.527:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Open HTTP2ServerConnection@6565e278{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 47f614a8[SSLEngine[hostname=127.0.0.1 port=58201] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 47f614a8[SSLEngine[hostname=127.0.0.1 port=58201] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.532:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Open HTTP2ServerConnection@7ae65110{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 2bbc14d9[SSLEngine[hostname=127.0.0.1 port=58205] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 2bbc14d9[SSLEngine[hostname=127.0.0.1 port=58205] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.535:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Open HTTP2ServerConnection@2a352ed7{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 64367723[SSLEngine[hostname=127.0.0.1 port=58203] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 64367723[SSLEngine[hostname=127.0.0.1 port=58203] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.537:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@210b9d3f{IDLE} 
2014-09-19 12:37:57.604:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-17: HTTP2 Request #3/40879b2e:
GET https://localhost:8443/http2/tiles/tile02.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:57.611:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-16: HTTP2 Request #3/3adbc923:
GET https://localhost:8443/http2/tiles/tile01.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:57.618:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/6ba210a4:
GET https://localhost:8443/http2/tiles/tile03.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:57.619:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-21: HTTP2 Request #3/4b562ab9:
GET https://localhost:8443/http2/tiles/tile05.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:57.626:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-26: HTTP2 Request #3/35306e66:
GET https://localhost:8443/http2/tiles/tile00.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:57.639:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Close HTTP2ServerConnection@5cab664e{IDLE} 
2014-09-19 12:37:57.639:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/3810ab45:
GET https://localhost:8443/http2/tiles/tile04.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 780cb232[SSLEngine[hostname=127.0.0.1 port=58206] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 780cb232[SSLEngine[hostname=127.0.0.1 port=58206] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.647:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Open HTTP2ServerConnection@6f9d8908{IDLE} 
2014-09-19 12:37:57.684:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Close HTTP2ServerConnection@210b9d3f{FILLING} 
2014-09-19 12:37:57.684:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Close HTTP2ServerConnection@6565e278{FILLING} 
2014-09-19 12:37:57.684:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Close HTTP2ServerConnection@2a352ed7{FILLING} 
2014-09-19 12:37:57.686:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Close HTTP2ServerConnection@7ae65110{FILLING} 
2014-09-19 12:37:57.686:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Close HTTP2ServerConnection@3fc80516{FILLING} 
2014-09-19 12:37:57.697:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-21: HTTP2 Request #3/1ac9ae5f:
GET https://localhost:8443/http2/tiles/tile06.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 5be39284[SSLEngine[hostname=127.0.0.1 port=58208] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 5be39284[SSLEngine[hostname=127.0.0.1 port=58208] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.722:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Open HTTP2ServerConnection@2a9f1a9f{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 77ea65d4[SSLEngine[hostname=127.0.0.1 port=58209] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 77ea65d4[SSLEngine[hostname=127.0.0.1 port=58209] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 117ce597[SSLEngine[hostname=127.0.0.1 port=58210] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 117ce597[SSLEngine[hostname=127.0.0.1 port=58210] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.732:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@66678b9b{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 2084f851[SSLEngine[hostname=127.0.0.1 port=58211] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 2084f851[SSLEngine[hostname=127.0.0.1 port=58211] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 3a16c7d7[SSLEngine[hostname=127.0.0.1 port=58207] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 3a16c7d7[SSLEngine[hostname=127.0.0.1 port=58207] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:57.736:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Open HTTP2ServerConnection@56530d3e{IDLE} 
2014-09-19 12:37:57.738:DBUG:oejh.HTTP2Connection:qtp1072591677-22: HTTP2 Open HTTP2ServerConnection@26e682f4{IDLE} 
2014-09-19 12:37:57.741:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Open HTTP2ServerConnection@f2dbd01{IDLE} 
2014-09-19 12:37:57.772:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Close HTTP2ServerConnection@6f9d8908{FILLING} 
2014-09-19 12:37:57.782:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-21: HTTP2 Request #3/63f08a3d:
GET https://localhost:8443/http2/tiles/tile08.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:57.895:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Close HTTP2ServerConnection@2a9f1a9f{FILLING} 
2014-09-19 12:37:57.939:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/32e8c035:
GET https://localhost:8443/http2/tiles/tile07.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.011:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Close HTTP2ServerConnection@26e682f4{FILLING} 
2014-09-19 12:37:58.014:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-23: HTTP2 Request #3/13519990:
GET https://localhost:8443/http2/tiles/tile09.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.043:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Close HTTP2ServerConnection@66678b9b{FILLING} 
2014-09-19 12:37:58.045:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-17: HTTP2 Request #3/5a81cc0f:
GET https://localhost:8443/http2/tiles/tile10.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.118:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/4a1d8f37:
GET https://localhost:8443/http2/tiles/tile11.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 6474566[SSLEngine[hostname=127.0.0.1 port=58212] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 6474566[SSLEngine[hostname=127.0.0.1 port=58212] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.138:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Open HTTP2ServerConnection@1b7f5016{IDLE} 
2014-09-19 12:37:58.138:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@f2dbd01{FILLING} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 72aa875e[SSLEngine[hostname=127.0.0.1 port=58214] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 72aa875e[SSLEngine[hostname=127.0.0.1 port=58214] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 5f8d207[SSLEngine[hostname=127.0.0.1 port=58216] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 5f8d207[SSLEngine[hostname=127.0.0.1 port=58216] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.161:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Open HTTP2ServerConnection@22882c5b{IDLE} 
2014-09-19 12:37:58.162:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@4f461f2b{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 1a930769[SSLEngine[hostname=127.0.0.1 port=58213] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 1a930769[SSLEngine[hostname=127.0.0.1 port=58213] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.178:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@1bf8100d{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 1709b190[SSLEngine[hostname=127.0.0.1 port=58215] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 1709b190[SSLEngine[hostname=127.0.0.1 port=58215] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.188:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Open HTTP2ServerConnection@9e7c040{IDLE} 
2014-09-19 12:37:58.189:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/18a94fb8:
GET https://localhost:8443/http2/tiles/tile12.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.308:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Close HTTP2ServerConnection@56530d3e{FILLING} 
2014-09-19 12:37:58.309:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Close HTTP2ServerConnection@1b7f5016{FILLING} 
2014-09-19 12:37:58.310:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-17: HTTP2 Request #3/3198f5ca:
GET https://localhost:8443/http2/tiles/tile13.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.310:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-22: HTTP2 Request #3/3895b3fc:
GET https://localhost:8443/http2/tiles/tile14.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.336:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/303578fb:
GET https://localhost:8443/http2/tiles/tile16.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.343:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/324aa33e:
GET https://localhost:8443/http2/tiles/tile15.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 1bb03bd5[SSLEngine[hostname=127.0.0.1 port=58218] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 1bb03bd5[SSLEngine[hostname=127.0.0.1 port=58218] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.360:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Open HTTP2ServerConnection@1e87d027{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 5946b401[SSLEngine[hostname=127.0.0.1 port=58217] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 5946b401[SSLEngine[hostname=127.0.0.1 port=58217] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.388:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Open HTTP2ServerConnection@4108d36f{IDLE} 
2014-09-19 12:37:58.408:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Close HTTP2ServerConnection@9e7c040{FILLING} 
2014-09-19 12:37:58.411:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Close HTTP2ServerConnection@22882c5b{FILLING} 
2014-09-19 12:37:58.417:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@1bf8100d{FILLING} 
2014-09-19 12:37:58.418:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Close HTTP2ServerConnection@4f461f2b{FILLING} 
2014-09-19 12:37:58.437:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-26: HTTP2 Request #3/77fdd676:
GET https://localhost:8443/http2/tiles/tile18.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.493:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@1e87d027{FILLING} 
2014-09-19 12:37:58.571:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/3ad2019:
GET https://localhost:8443/http2/tiles/tile17.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 7a3c0467[SSLEngine[hostname=127.0.0.1 port=58219] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 7a3c0467[SSLEngine[hostname=127.0.0.1 port=58219] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.577:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@4181e8b3{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 4795979f[SSLEngine[hostname=127.0.0.1 port=58220] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 4795979f[SSLEngine[hostname=127.0.0.1 port=58220] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.583:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@45be745b{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 4bfa75a5[SSLEngine[hostname=127.0.0.1 port=58223] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 6eae9599[SSLEngine[hostname=127.0.0.1 port=58221] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 4bfa75a5[SSLEngine[hostname=127.0.0.1 port=58223] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 6eae9599[SSLEngine[hostname=127.0.0.1 port=58221] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.601:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Open HTTP2ServerConnection@4c9e48f0{IDLE} 
2014-09-19 12:37:58.601:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@7f0e4080{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 9a45f01[SSLEngine[hostname=127.0.0.1 port=58222] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 9a45f01[SSLEngine[hostname=127.0.0.1 port=58222] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.602:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Open HTTP2ServerConnection@737e66df{IDLE} 
2014-09-19 12:37:58.617:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/17c5a743:
GET https://localhost:8443/http2/tiles/tile19.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.719:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/3107f6bb:
GET https://localhost:8443/http2/tiles/tile21.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.720:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Close HTTP2ServerConnection@4181e8b3{FILLING} 
2014-09-19 12:37:58.725:DBUG:oejh.HTTP2Connection:qtp1072591677-22: HTTP2 Close HTTP2ServerConnection@4108d36f{FILLING} 
2014-09-19 12:37:58.725:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/5517905d:
GET https://localhost:8443/http2/tiles/tile22.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.725:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-17: HTTP2 Request #3/3b6f35c9:
GET https://localhost:8443/http2/tiles/tile20.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 545c091[SSLEngine[hostname=127.0.0.1 port=58225] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 545c091[SSLEngine[hostname=127.0.0.1 port=58225] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 70ed771[SSLEngine[hostname=127.0.0.1 port=58224] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 70ed771[SSLEngine[hostname=127.0.0.1 port=58224] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.742:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Open HTTP2ServerConnection@723a7b5c{IDLE} 
2014-09-19 12:37:58.743:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@3176a1df{IDLE} 
2014-09-19 12:37:58.745:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/4efa8050:
GET https://localhost:8443/http2/tiles/tile23.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.854:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Close HTTP2ServerConnection@4c9e48f0{FILLING} 
2014-09-19 12:37:58.855:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Close HTTP2ServerConnection@737e66df{FILLING} 
2014-09-19 12:37:58.858:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Close HTTP2ServerConnection@7f0e4080{FILLING} 
2014-09-19 12:37:58.858:DBUG:oejh.HTTP2Connection:qtp1072591677-22: HTTP2 Close HTTP2ServerConnection@45be745b{FILLING} 
2014-09-19 12:37:58.859:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-17: HTTP2 Request #3/7a7d95cc:
GET https://localhost:8443/http2/tiles/tile24.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:58.984:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Close HTTP2ServerConnection@723a7b5c{FILLING} 
2014-09-19 12:37:58.985:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-26: HTTP2 Request #3/752e4ddf:
GET https://localhost:8443/http2/tiles/tile25.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 637fe344[SSLEngine[hostname=127.0.0.1 port=58227] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 637fe344[SSLEngine[hostname=127.0.0.1 port=58227] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:58.995:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Open HTTP2ServerConnection@5fbe00dd{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 4c0dfceb[SSLEngine[hostname=127.0.0.1 port=58226] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 4c0dfceb[SSLEngine[hostname=127.0.0.1 port=58226] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.014:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Open HTTP2ServerConnection@7cf19b46{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 7fd21fb4[SSLEngine[hostname=127.0.0.1 port=58228] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 7fd21fb4[SSLEngine[hostname=127.0.0.1 port=58228] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.027:DBUG:oejh.HTTP2Connection:qtp1072591677-22: HTTP2 Open HTTP2ServerConnection@37b70549{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 55cb75d6[SSLEngine[hostname=127.0.0.1 port=58230] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 55cb75d6[SSLEngine[hostname=127.0.0.1 port=58230] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.028:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Open HTTP2ServerConnection@294e89e2{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 5d69dcb6[SSLEngine[hostname=127.0.0.1 port=58229] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 5d69dcb6[SSLEngine[hostname=127.0.0.1 port=58229] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.035:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Open HTTP2ServerConnection@30e9919e{IDLE} 
2014-09-19 12:37:59.070:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/35795edb:
GET https://localhost:8443/http2/tiles/tile27.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.122:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Close HTTP2ServerConnection@3176a1df{FILLING} 
2014-09-19 12:37:59.148:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Close HTTP2ServerConnection@5fbe00dd{FILLING} 
2014-09-19 12:37:59.149:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-16: HTTP2 Request #3/383452bf:
GET https://localhost:8443/http2/tiles/tile30.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.181:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-22: HTTP2 Request #3/4f8c4cbe:
GET https://localhost:8443/http2/tiles/tile26.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.197:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@294e89e2{FILLING} 
2014-09-19 12:37:59.198:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-26: HTTP2 Request #3/1453e55f:
GET https://localhost:8443/http2/tiles/tile28.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.247:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Close HTTP2ServerConnection@7cf19b46{FILLING} 
2014-09-19 12:37:59.247:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Close HTTP2ServerConnection@37b70549{FILLING} 
2014-09-19 12:37:59.248:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-22: HTTP2 Request #3/2340184e:
GET https://localhost:8443/http2/tiles/tile29.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 1e0e455d[SSLEngine[hostname=127.0.0.1 port=58235] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 1e0e455d[SSLEngine[hostname=127.0.0.1 port=58235] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 7f37d186[SSLEngine[hostname=127.0.0.1 port=58233] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 7f37d186[SSLEngine[hostname=127.0.0.1 port=58233] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for eec6988[SSLEngine[hostname=127.0.0.1 port=58236] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for eec6988[SSLEngine[hostname=127.0.0.1 port=58236] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.261:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@39f6215e{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 683aff68[SSLEngine[hostname=127.0.0.1 port=58234] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 683aff68[SSLEngine[hostname=127.0.0.1 port=58234] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.262:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Open HTTP2ServerConnection@54093490{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 324c4d6a[SSLEngine[hostname=127.0.0.1 port=58232] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 324c4d6a[SSLEngine[hostname=127.0.0.1 port=58232] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.264:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@34717ca0{IDLE} 
2014-09-19 12:37:59.265:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Open HTTP2ServerConnection@32d57fda{IDLE} 
2014-09-19 12:37:59.260:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@60931dc7{IDLE} 
2014-09-19 12:37:59.331:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@30e9919e{FILLING} 
2014-09-19 12:37:59.351:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-26: HTTP2 Request #3/155070c1:
GET https://localhost:8443/http2/tiles/tile32.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.382:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-23: HTTP2 Request #3/77f8a1b6:
GET https://localhost:8443/http2/tiles/tile34.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.406:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-16: HTTP2 Request #3/2e4a713b:
GET https://localhost:8443/http2/tiles/tile35.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.409:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Close HTTP2ServerConnection@60931dc7{FILLING} 
2014-09-19 12:37:59.410:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-26: HTTP2 Request #3/1ea20629:
GET https://localhost:8443/http2/tiles/tile31.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.411:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-27: HTTP2 Request #3/7fa7a8b4:
GET https://localhost:8443/http2/tiles/tile33.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 1725369d[SSLEngine[hostname=127.0.0.1 port=58237] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 1725369d[SSLEngine[hostname=127.0.0.1 port=58237] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.415:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Open HTTP2ServerConnection@4b0acb91{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for add08aa[SSLEngine[hostname=127.0.0.1 port=58238] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for add08aa[SSLEngine[hostname=127.0.0.1 port=58238] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.442:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Open HTTP2ServerConnection@34fadbad{IDLE} 
2014-09-19 12:37:59.465:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Close HTTP2ServerConnection@39f6215e{FILLING} 
2014-09-19 12:37:59.469:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Close HTTP2ServerConnection@32d57fda{FILLING} 
2014-09-19 12:37:59.469:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Close HTTP2ServerConnection@34717ca0{FILLING} 
2014-09-19 12:37:59.470:DBUG:oejh.HTTP2Connection:qtp1072591677-25: HTTP2 Close HTTP2ServerConnection@54093490{FILLING} 
2014-09-19 12:37:59.473:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-21: HTTP2 Request #3/1ba509d7:
GET https://localhost:8443/http2/tiles/tile36.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.559:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Close HTTP2ServerConnection@4b0acb91{FILLING} 
2014-09-19 12:37:59.613:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/32c0fa07:
GET https://localhost:8443/http2/tiles/tile37.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 6dd8d5d1[SSLEngine[hostname=127.0.0.1 port=58240] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 17deec8c[SSLEngine[hostname=127.0.0.1 port=58242] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 17deec8c[SSLEngine[hostname=127.0.0.1 port=58242] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 6dd8d5d1[SSLEngine[hostname=127.0.0.1 port=58240] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.619:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@1c585a8c{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 3bb8685[SSLEngine[hostname=127.0.0.1 port=58239] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 24a3ad79[SSLEngine[hostname=127.0.0.1 port=58241] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 24a3ad79[SSLEngine[hostname=127.0.0.1 port=58241] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 565701e3[SSLEngine[hostname=127.0.0.1 port=58243] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 565701e3[SSLEngine[hostname=127.0.0.1 port=58243] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.620:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@64dd01e5{IDLE} 
2014-09-19 12:37:59.620:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Open HTTP2ServerConnection@25c20b9b{IDLE} 
2014-09-19 12:37:59.619:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@653fef36{IDLE} 
[S] ALPN protocol 'h2-14' selected for 3bb8685[SSLEngine[hostname=127.0.0.1 port=58239] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.623:DBUG:oejh.HTTP2Connection:qtp1072591677-22: HTTP2 Open HTTP2ServerConnection@619b35b1{IDLE} 
2014-09-19 12:37:59.687:DBUG:oejh.HTTP2Connection:qtp1072591677-22: HTTP2 Close HTTP2ServerConnection@34fadbad{FILLING} 
2014-09-19 12:37:59.689:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/7d5a935:
GET https://localhost:8443/http2/tiles/tile38.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.689:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/5a0921b4:
GET https://localhost:8443/http2/tiles/tile39.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.695:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-23: HTTP2 Request #3/77307908:
GET https://localhost:8443/http2/tiles/tile40.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.696:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-27: HTTP2 Request #3/267df7c2:
GET https://localhost:8443/http2/tiles/tile42.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.697:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-23: HTTP2 Request #3/2ca3094e:
GET https://localhost:8443/http2/tiles/tile41.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 53001913[SSLEngine[hostname=127.0.0.1 port=58244] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 53001913[SSLEngine[hostname=127.0.0.1 port=58244] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.724:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@6dcbde1e{IDLE} 
2014-09-19 12:37:59.770:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Close HTTP2ServerConnection@25c20b9b{FILLING} 
2014-09-19 12:37:59.771:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Close HTTP2ServerConnection@619b35b1{FILLING} 
2014-09-19 12:37:59.773:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Close HTTP2ServerConnection@653fef36{FILLING} 
2014-09-19 12:37:59.777:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Close HTTP2ServerConnection@64dd01e5{FILLING} 
2014-09-19 12:37:59.778:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-26: HTTP2 Request #3/3003c219:
GET https://localhost:8443/http2/tiles/tile43.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.779:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Close HTTP2ServerConnection@1c585a8c{FILLING} 
2014-09-19 12:37:59.841:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@6dcbde1e{FILLING} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 5a37a46[SSLEngine[hostname=127.0.0.1 port=58250] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 5a37a46[SSLEngine[hostname=127.0.0.1 port=58250] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 61451b7e[SSLEngine[hostname=127.0.0.1 port=58248] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 61451b7e[SSLEngine[hostname=127.0.0.1 port=58248] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 64d3b326[SSLEngine[hostname=127.0.0.1 port=58245] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 64d3b326[SSLEngine[hostname=127.0.0.1 port=58245] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.857:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Open HTTP2ServerConnection@3028dd4a{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 79c07355[SSLEngine[hostname=127.0.0.1 port=58247] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 79c07355[SSLEngine[hostname=127.0.0.1 port=58247] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 36cb9f39[SSLEngine[hostname=127.0.0.1 port=58249] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 36cb9f39[SSLEngine[hostname=127.0.0.1 port=58249] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.865:DBUG:oejh.HTTP2Connection:qtp1072591677-26: HTTP2 Open HTTP2ServerConnection@e8053ad{IDLE} 
2014-09-19 12:37:59.865:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Open HTTP2ServerConnection@1d07e2d1{IDLE} 
[S] ALPN protocols [h2-14, spdy/3.1, spdy/3, http/1.1] received from client for 61bfbf5b[SSLEngine[hostname=127.0.0.1 port=58246] SSL_NULL_WITH_NULL_NULL]
[S] ALPN protocol 'h2-14' selected for 61bfbf5b[SSLEngine[hostname=127.0.0.1 port=58246] SSL_NULL_WITH_NULL_NULL]
2014-09-19 12:37:59.868:DBUG:oejh.HTTP2Connection:qtp1072591677-22: HTTP2 Open HTTP2ServerConnection@42bad3a4{IDLE} 
2014-09-19 12:37:59.869:DBUG:oejh.HTTP2Connection:qtp1072591677-16: HTTP2 Open HTTP2ServerConnection@52e02b7b{IDLE} 
2014-09-19 12:37:59.870:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Open HTTP2ServerConnection@137387d{IDLE} 
2014-09-19 12:37:59.958:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-22: HTTP2 Request #3/6c5385af:
GET https://localhost:8443/http2/tiles/tile49.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.977:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-27: HTTP2 Request #3/5f659eed:
GET https://localhost:8443/http2/tiles/tile44.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:37:59.989:DBUG:oejh.HTTP2Connection:qtp1072591677-24: HTTP2 Close HTTP2ServerConnection@3028dd4a{FILLING} 
2014-09-19 12:38:00.041:DBUG:oejh.HTTP2Connection:qtp1072591677-23: HTTP2 Close HTTP2ServerConnection@1d07e2d1{FILLING} 
2014-09-19 12:38:00.043:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-27: HTTP2 Request #3/53bb6ecd:
GET https://localhost:8443/http2/tiles/tile45.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:38:00.085:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-24: HTTP2 Request #3/1a9e467b:
GET https://localhost:8443/http2/tiles/tile46.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:38:00.091:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@52e02b7b{FILLING} 
2014-09-19 12:38:00.110:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-25: HTTP2 Request #3/70c73f5b:
GET https://localhost:8443/http2/tiles/tile47.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:38:00.163:DBUG:oejhs.HttpChannelOverHTTP2:qtp1072591677-22: HTTP2 Request #3/5bf237b4:
GET https://localhost:8443/http2/tiles/tile48.jpg HTTP/2
user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:35.0) Gecko/20100101 Firefox/35.0
accept: image/png,image/*;q=0.8,*/*;q=0.5
accept-language: en-US,en;q=0.5
accept-encoding: gzip, deflate
referer: https://localhost:8443/http2/tiles/
pragma: no-cache
cache-control: no-cache


2014-09-19 12:38:00.163:DBUG:oejh.HTTP2Connection:qtp1072591677-27: HTTP2 Close HTTP2ServerConnection@137387d{FILLING} 
2014-09-19 12:38:00.168:DBUG:oejh.HTTP2Connection:qtp1072591677-21: HTTP2 Close HTTP2ServerConnection@42bad3a4{FILLING} 
2014-09-19 12:38:00.192:DBUG:oejh.HTTP2Connection:qtp1072591677-17: HTTP2 Close HTTP2ServerConnection@e8053ad{FILLING} 


Expected results:

A single new connection should be opened and all requests multiplexed over it.
Component: Untriaged → Networking: HTTP
Product: Firefox → Core
yeah I've seen this. Its surprisingly awkward to fix.

the good news is that I've been told shift reload is a very rarely used feature. (normal reload doesn't do this).
Whiteboard: [spdy]
No urgency on our side to see a fix... it is actually a very handy stress inducer and helped us discover a race in jetty with closing h2 connections :)
historically, with h1, our strategy has gone from making each new request associated with shift-reload

1] use a new connection
then 2] close the connection pool and use a new connection
then 3] have just the base page load close the connection pool and have each transaction use a new connection

I think now that we have the "close connection pool" in the mix, and can combine that with a transport that really doesn't like putting each transaction on a new connection we should move to 

4] have just the base page load close the connection pool. Each transaction can reuse a connection if available (which there won't be for the base page load, because it just closed the pool)

I confirmed that results in ctrl-shift-reload on a h2 page making 1 new connection per origin, and on an h1 page it makes 6 new connections per origin.
Assignee: nobody → mcmanus
Status: UNCONFIRMED → ASSIGNED
Ever confirmed: true
Attachment #8504907 - Flags: review?(hurley) → review+
(In reply to Carsten Book [:Tomcat] from comment #9)
> sorry Patrick, had to back this out in
> https://treeherder.mozilla.org/ui/#/jobs?repo=mozilla-
> inbound&revision=c7c0cb53949d since one of this changes caused
> https://treeherder.mozilla.org/ui/logviewer.
> html#?job_id=3242159&repo=mozilla-inbound

oh sorry, seems it was caused by the push before. Re-checkedin this changes in https://treeherder.mozilla.org/ui/#/jobs?repo=mozilla-inbound&revision=c9d867500a39
https://hg.mozilla.org/mozilla-central/rev/2220d82f3fc3
Status: ASSIGNED → RESOLVED
Closed: 10 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla36
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: