search

eousphoros / mod-spdy

OpenSSL 1.0.2 and Apache 2.4.12 port for mod-spdy
Other
79 stars 23 forks source link

Problems with POST and SNI when mod_spdy is enabled #22

Closed digi24 closed 10 years ago

digi24 commented 10 years ago

I am using this module on Debian and Apache 2.4.10. My SSL config uses name based virtual hosts and server name indication (SNI). Browser is Chrome 38.0.2125.0.

As long as I stick to GET requests, everything works as expected and many POST requests work too. But some of the XMLHttpRequests send by POST from my browser are not answered. From the browser console the requests seem to be sent to the correct hostname.

Looking at the Apache logs, I see that these requests expired several minutes later, with error 408. They ended up being handled by the default SSL host, indicating that SNI was not used.

So in other words: The browser sends a POST request, Apache ignores SNI, answers from the wrong host and the browser does not accept this answer, as it originates from the wrong host and breaks some of the Javascript policies.

I cannot reproduce this problem every time with mod_spdy, but it does not seem to occur, when I turn off mod_spdy with SpdyEnabled off.

redstar3894 commented 10 years ago

Can confirm this issue with regard to the GET/POST requests. It seems to be intermittent and I originally thought it was due to how Chrome/Firefox handles SPDY, but with the latest fix in #20 / #19 it seems to have broken entirely. I don't have multiple SSL vhosts set up so I can't confirm the SNI issue.

digi24 commented 10 years ago

Thank you redstar3894 for your comments. The SNI and named virtual host stuff might indeed just be unrelated, and it is coincidence that a wrong hostname is logged with the 408 error. I could reproduce the problem with the default host, where SNI does not matter.

It seems that the probability of a stuck POST request is higher, when the content length of the posted multipart form data is larger.

v998 commented 10 years ago

I am not sure if I got the same problem too. But I didn't use SNI on my host. When I post new post (usuallymany words) in WordPress, it said it was disconnected. In apache log, it shows.

[Thu Aug 21 16:06:27.322076 2014] [fcgid:warn] [pid 30724:tid 2742016912] (103)Software caused connection abort: [remote 123.203.116.58:50401] mod_fcgid: can't get data from http client, referer: https://www.iniiter.com/wordpress/wp-admin/post-new.php

and I can't save the post, (press save but still waited for long time, then timeout)

BTW, Owncloud can't upload files, my situation is similar to http://forum.owncloud.org/viewtopic.php?f=29&t=22791

eousphoros commented 10 years ago

Can you see if the same problem exists in the 2.4.7 branch?

Kijewski commented 10 years ago

I can confirm that the 2.4.7 branch works very well with POST and XHR for a few hundred requests a day.

ghostwheel42 commented 10 years ago

I fear my NPN patch (ghostwheel42/mod-spdy@d661a5aa41c41fa7c6a0bb56af0535a222f7b928) could be the culprit. I only converted the hooks to the new "optional function" implementation (http://svn.apache.org/r1487772), but I am by no means an apache insider, so there's a fair chance I got it wrong.

eousphoros commented 10 years ago

Well we have a long weekend ahead and my android project is at a good stopping point. I have been threatening to setup a 2.4.10 environment. Suppose now is as good of time as any. Thank you for the links.

https://54.213.234.151/ I'll be playing here this weekend.

Ok have the playground setup. Apache 2.4.10 installed. Current branch for 2.4.10 built and installed. Verified at least NPN/mod-spdy gets advertised. Now to build a quick set of tests for posts and what not.

priyadi commented 10 years ago

I ran into this exact some problem, and found out after trying to submit a very long Wordpress post (using Apache 2.4.10 with mod_spdy revision https://github.com/eousphoros/mod-spdy/commit/bfc0fb777f81a638ace992552c6f3a9445d7f8fd)

I can get around this problem by porting the old NPN patch to 2.4.10, and using mod_spdy before ghostwheel42's patches (rev https://github.com/eousphoros/mod-spdy/commit/20ce1211e85d363cd6ccbe87ef5d31524ee578ad)

v998 commented 10 years ago

@priyadi Any demos that I can test out?

priyadi commented 10 years ago

@v998

I spoke too soon. The problem still exists, but now sometimes it works. Normal POSTs are OK most of the time. File uploads almost always don't work (hung for a very long time). No error messages in Apache logs, and nothing in Chrome side either.

I'm still testing this with a local Centos 6 VM, so no public demo at the moment.

priyadi commented 10 years ago

I tried downgrading to Apache 2.4.7, using mod_spdy from branch apache-2.4.7. I got the exact same result.

I also found out maximum size of file I can upload is exactly 47755 bytes. One byte larger and it will hang.

priyadi commented 10 years ago

Log files with LogLevel debug and SpdyDebugLoggingVerbosity 5, after POST-ing a large file.

Apache 2.4.7, mpm worker, old NPN patch, mod_spdy from branch apache-2.4.7:

[Sun Oct 05 17:20:52.093370 2014] [ssl:info] [pid 11124:tid 140690579257088] [client 192.168.244.1:37245] AH01964: Connection to child 131 established (server example.net:443)
[Sun Oct 05 17:20:52.093901 2014] [ssl:debug] [pid 11124:tid 140690579257088] ssl_engine_kernel.c(1911): [client 192.168.244.1:37245] AH02043: SSL virtual host for servername example.net found
[Sun Oct 05 17:20:52.106550 2014] [ssl:info] [pid 11122:tid 140690684086016] [client 192.168.244.1:37246] AH01964: Connection to child 0 established (server example.net:443)
[Sun Oct 05 17:20:52.111145 2014] [ssl:debug] [pid 11122:tid 140690684086016] ssl_engine_kernel.c(1911): [client 192.168.244.1:37246] AH02043: SSL virtual host for servername example.net found
[Sun Oct 05 17:20:52.111798 2014] [ssl:debug] [pid 11124:tid 140690579257088] ssl_engine_kernel.c(1844): [client 192.168.244.1:37245] AH02041: Protocol: TLSv1.2, Cipher: ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)
[Sun Oct 05 17:20:52.111843 2014] [ssl:info] [pid 11124:tid 140690579257088] (70014)End of file found: [client 192.168.244.1:37245] AH01991: SSL input filter read failed.
[Sun Oct 05 17:20:52.111887 2014] [ssl:info] [pid 11124:tid 140690579257088] (70014)End of file found: [client 192.168.244.1:37245] AH01991: SSL input filter read failed.
[Sun Oct 05 17:20:52.121282 2014] [ssl:debug] [pid 11122:tid 140690684086016] ssl_engine_kernel.c(1844): [client 192.168.244.1:37246] AH02041: Protocol: TLSv1.2, Cipher: ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)
[Sun Oct 05 17:20:52.121369 2014] [ssl:debug] [pid 11122:tid 140690684086016] ssl_engine_io.c(1430): [client 192.168.244.1:37246] SSL NPN negotiated protocol: 'spdy/3.1'
[Sun Oct 05 17:20:52.121530 2014] [spdy:info] [pid 11122:tid 140690684086016] [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:INFO:mod_spdy.cc(454)] Starting SPDY/3.1 session
[Sun Oct 05 17:20:52.121682 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(494)] Received SETTING (flags=
[Sun Oct 05 17:20:52.121739 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(494)] Received SETTING (flags=
[Sun Oct 05 17:20:52.121894 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE2:spdy_session.cc(460)] Received SYN_STREAM; opening stream 1
[Sun Oct 05 17:20:52.121994 2014] [spdy:debug] [pid 11122:tid 140690705065728] mod_spdy/apache/log_message_handler.cc(121): [remote 192.168.244.1:37246] [stream 1] [mod_spdy/0.9.4.1-0] [11122:11129:VERBOSE3:apache_spdy_stream_task_factory.cc(115)] Starting stream task
[Sun Oct 05 17:20:52.124792 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.126034 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.127790 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.137700 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.138964 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.140158 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.140398 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.141590 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.142833 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.143042 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.144287 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.145533 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.145749 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.146968 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.148146 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.148330 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.149539 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.150736 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.150997 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.152179 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.153361 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.153652 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2852)
[Sun Oct 05 17:20:52.154842 2014] [spdy:debug] [pid 11122:tid 140690684086016] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:37246] [mod_spdy/0.9.4.1-0] [11122:11182:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2792)

Apache 2.4.10, mpm worker, new NPN patch, mod_spdy from branch apache-2.4.10:

[Sun Oct 05 18:06:13.657930 2014] [ssl:info] [pid 18050:tid 140073439823616] [client 192.168.244.1:38452] AH01964: Connection to child 73 established (server example.net:443)
[Sun Oct 05 18:06:13.658381 2014] [ssl:debug] [pid 18050:tid 140073439823616] ssl_engine_kernel.c(1909): [client 192.168.244.1:38452] AH02043: SSL virtual host for servername example.net found
[Sun Oct 05 18:06:13.672072 2014] [ssl:debug] [pid 18050:tid 140073439823616] ssl_engine_kernel.c(1842): [client 192.168.244.1:38452] AH02041: Protocol: TLSv1.2, Cipher: ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)
[Sun Oct 05 18:06:13.681616 2014] [ssl:debug] [pid 18050:tid 140073439823616] ssl_engine_io.c(1433): [client 192.168.244.1:38452] AH02306: SSL NPN negotiated protocol: 'spdy/3.10\xe1\x01De\x7f'
[Sun Oct 05 18:06:13.681929 2014] [spdy:info] [pid 18050:tid 140073439823616] [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:INFO:mod_spdy.cc(479)] Starting SPDY/3.1 session
[Sun Oct 05 18:06:13.682081 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(494)] Received SETTING (flags=
[Sun Oct 05 18:06:13.682191 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE2:spdy_session.cc(460)] Received SYN_STREAM; opening stream 1
[Sun Oct 05 18:06:13.682250 2014] [spdy:debug] [pid 18050:tid 140073628038912] mod_spdy/apache/log_message_handler.cc(121): [remote 192.168.244.1:38452] [stream 1] [mod_spdy/0.9.4.1-0] [18050:18055:VERBOSE3:apache_spdy_stream_task_factory.cc(115)] Starting stream task
[Sun Oct 05 18:06:13.684814 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(631)] [stream 1] Received WINDOW_UPDATE(268304384) frame
[Sun Oct 05 18:06:13.686376 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4088)
[Sun Oct 05 18:06:13.687525 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.688661 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.688894 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=3720)
[Sun Oct 05 18:06:13.690227 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4088)
[Sun Oct 05 18:06:13.691363 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.691509 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.692649 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=3131)
[Sun Oct 05 18:06:13.693807 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=752)
[Sun Oct 05 18:06:13.695125 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4088)
[Sun Oct 05 18:06:13.695309 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.696436 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.696583 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=3720)
[Sun Oct 05 18:06:13.697899 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4088)
[Sun Oct 05 18:06:13.699026 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.699183 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=4096)
[Sun Oct 05 18:06:13.700326 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=2984)
[Sun Oct 05 18:06:13.701495 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=1504)
[Sun Oct 05 18:06:13.701644 2014] [spdy:debug] [pid 18050:tid 140073439823616] mod_spdy/apache/log_message_handler.cc(107): [client 192.168.244.1:38452] [mod_spdy/0.9.4.1-0] [18050:18172:VERBOSE4:spdy_session.cc(325)] [stream 1] Received DATA (length=605)

Curiously, all the SPDY frames sums to 65536, every time.

priyadi commented 10 years ago

Everything appears to be working after I reversed https://github.com/eousphoros/mod-spdy/commit/314aa59c41c0da143d9f4ea23931d814ef6126ac , probably because the code don't actually do SPDY/3.1 yet.

I've created pull request https://github.com/eousphoros/mod-spdy/pull/25 for this.

eousphoros commented 10 years ago

Pull request merged. If someone can confirm that pr #25 fixes the issue we can close this out. Thank you @priyadi

v998 commented 10 years ago

@eousphoros The OwnCloud and WordPress issue seems to be fixed by #25 , but I'm not sure whether the problem related to @digi24 is fixed or not. However, It is very likely that the problems of @digi24 and the WP/OC are the same.(fixed)

priyadi commented 10 years ago

@v998 @eousphoros With regards to SNI, I've been using this with SNI in the last few days without any problem.

eousphoros commented 10 years ago

Closing this out for now then. If it is still an issue please re-open.

coroaes commented 9 years ago

I have POST issues with Apache 2.4.7 and mod-spdy and SNI. Im running Ubuntu 14.04 LTS, I have compiled with the branch 2.4.7. The POST request when spdy is active doesnt log, the request doesnt arrive at the server.

JMLX42 commented 9 years ago

+1 I have the same issue with the apache-2.4.7 branch