[svn.haxx.se] · SVN Dev · SVN Users · SVN Org · TSVN Dev · TSVN Users · Subclipse Dev · Subclipse Users · this month's index

Error "mod_dav_svn close_stream: error closing write stream" using a write-through proxy

From: Thorsten Schöning <tschoening_at_am-soft.de>
Date: Sat, 15 Aug 2015 16:16:51 +0200

Hi all,

I have several SVN repos served by svnserve for the last years and am
now in the process of implementing access using mod_dav_svn. Our repos
are hosted on a company internal private server with no access from
the public, besides the usual VPN/SSH stuff to some edge firewalls
etc. To make access easier for some customers we decided to make the
repos publicly available using a standard master-slave-setup using
mod_dav_svn, where a public VM is acting as read-only mirror for the
synced repos and is configured as write-through proxy using
SVNMasterURI with our internal repo hoster server as target.

I guess the important part now is how this internal server is reached:
We establish a tunnel using SSH from the internal server to the public
VM and this endpoint in the public VM is configured as SVNMasterURI,
something like http://private.example.org:3692. The tunnel is
forwarding all the traffic to our internal httpd with mod_dav_svn
enabled as well. So besides this SSH tunnel I think everything is
setup like one can read in the official documentation.

Using the private server directly I can read and write to my repos
using svnserve and mod_dav_svn concurrently. Using the public VM I can
read my repos and check them out, update locally comitted changes
etc., but commits are failing. I get the following error in the httpd
log of the target of SVNMasterURI:

> [Sat Aug 15 11:35:13.591510 2015] [dav:error] [client a.b.c.d:36370] mod_dav_svn close_stream: error closing write stream [500, #185004]
> [Sat Aug 15 11:35:13.591525 2015] [dav:error] [client a.b.c.d:36370] Unexpected end of svndiff input [500, #185004]

Looking at the complete logs for one commit it looks like some
operations succeed and in the filesystem I can see that transaction
files get created and such, bit the commit in general doesn't succeed.
It looks like processing the diff from the changed file itself is the
problem somehow.

I already googled for hours for hints, but the messages seem too
general to me, I get things like wrong OpenSSL, to large commits
because of LimitRequestBody and such and none of those apply to my
problem. I'm only committing a very small text file, am using a more
current OpenSSL than the mentioned one etc.

So I would like to ask you if you could be so kind looking at my log
of the commit and provide some hints on what I need to
test/change/whatever? Thanks!

> [Sat Aug 15 11:35:13.391403 2015] [http:trace4] [pid 1:tid 1] http_request.c(301): [client a.b.c.d:36366] Headers received from client:
> [Sat Aug 15 11:35:13.391459 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] Host: private.example.org:3692
> [Sat Aug 15 11:35:13.391473 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] Authorization: Basic xyz
> [Sat Aug 15 11:35:13.391485 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] User-Agent: SVN/1.8.14 (x86_64-unknown-linux-gnu) serf/1.3.7
> [Sat Aug 15 11:35:13.391497 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] Accept-Encoding: gzip
> [Sat Aug 15 11:35:13.391508 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] DAV: http://subversion.tigris.org/xmlns/dav/svn/depth, http://subversion.tigris.org/xmlns/dav/svn/mergeinfo, http://subversion.tigris.org/xmlns/dav/svn/log-revprops
> [Sat Aug 15 11:35:13.391520 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] X-Forwarded-For: 83.218.37.17
> [Sat Aug 15 11:35:13.391532 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] X-Forwarded-Host: public.example.org
> [Sat Aug 15 11:35:13.391543 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] X-Forwarded-Server: public.example.org
> [Sat Aug 15 11:35:13.391554 2015] [http:trace4] [pid 1:tid 1] http_request.c(305): [client a.b.c.d:36366] Connection: close
> [Sat Aug 15 11:35:13.391674 2015] [authz_core:debug] [pid 1:tid 1] mod_authz_core.c(802): [client a.b.c.d:36366] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.391714 2015] [authz_core:debug] [pid 1:tid 1] mod_authz_core.c(802): [client a.b.c.d:36366] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.452289 2015] [authz_svn:debug] [pid 1:tid 1] mod_authz_svn.c(407): [client a.b.c.d:36366] Path to authz file is /home/ams_svn_repos/Src/Testprodukt/conf/authz
> [Sat Aug 15 11:35:13.452442 2015] [authz_svn:info] [pid 1:tid 1] [client a.b.c.d:36366] Access granted: 'tschoening_at_am-soft.de' MKACTIVITY Testprodukt:
> [Sat Aug 15 11:35:13.462429 2015] [http:trace3] [pid 1:tid 1] http_filters.c(979): [client a.b.c.d:36366] Response sent with status 201, headers:
> [Sat Aug 15 11:35:13.462457 2015] [http:trace5] [pid 1:tid 1] http_filters.c(986): [client a.b.c.d:36366] Date: Sat, 15 Aug 2015 09:35:13 GMT
> [Sat Aug 15 11:35:13.462469 2015] [http:trace5] [pid 1:tid 1] http_filters.c(989): [client a.b.c.d:36366] Server: Apache/2.4.7 (Ubuntu)
> [Sat Aug 15 11:35:13.462481 2015] [http:trace4] [pid 1:tid 1] http_filters.c(808): [client a.b.c.d:36366] Cache-Control: no-cache
> [Sat Aug 15 11:35:13.462492 2015] [http:trace4] [pid 1:tid 1] http_filters.c(808): [client a.b.c.d:36366] Location: http://private.example.org:3692/src/Testprodukt/!svn/act/ccc1b698-e402-42e0-afab-4b63de21ded1
> [Sat Aug 15 11:35:13.462503 2015] [http:trace4] [pid 1:tid 1] http_filters.c(808): [client a.b.c.d:36366] Content-Length: 71
> [Sat Aug 15 11:35:13.462514 2015] [http:trace4] [pid 1:tid 1] http_filters.c(808): [client a.b.c.d:36366] Connection: close
> [Sat Aug 15 11:35:13.462524 2015] [http:trace4] [pid 1:tid 1] http_filters.c(808): [client a.b.c.d:36366] Content-Type: text/html; charset=ISO-8859-1
> [Sat Aug 15 11:35:13.477266 2015] [http:trace4] [pid 2:tid 2] http_request.c(301): [client a.b.c.d:36367] Headers received from client:
> [Sat Aug 15 11:35:13.477291 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] Host: private.example.org:3692
> [Sat Aug 15 11:35:13.477298 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] Authorization: Basic xyz
> [Sat Aug 15 11:35:13.477305 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] User-Agent: SVN/1.8.14 (x86_64-unknown-linux-gnu) serf/1.3.7
> [Sat Aug 15 11:35:13.477312 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] Content-Type: text/xml
> [Sat Aug 15 11:35:13.477319 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] Accept-Encoding: gzip
> [Sat Aug 15 11:35:13.477325 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] DAV: http://subversion.tigris.org/xmlns/dav/svn/depth, http://subversion.tigris.org/xmlns/dav/svn/mergeinfo, http://subversion.tigris.org/xmlns/dav/svn/log-revprops
> [Sat Aug 15 11:35:13.477332 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] X-Forwarded-For: 83.218.37.17
> [Sat Aug 15 11:35:13.477338 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] X-Forwarded-Host: public.example.org
> [Sat Aug 15 11:35:13.477345 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] X-Forwarded-Server: public.example.org
> [Sat Aug 15 11:35:13.477350 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] Connection: close
> [Sat Aug 15 11:35:13.477367 2015] [http:trace4] [pid 2:tid 2] http_request.c(305): [client a.b.c.d:36367] Content-Length: 231
> [Sat Aug 15 11:35:13.477445 2015] [authz_core:debug] [pid 2:tid 2] mod_authz_core.c(802): [client a.b.c.d:36367] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.477457 2015] [authz_core:debug] [pid 2:tid 2] mod_authz_core.c(802): [client a.b.c.d:36367] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.534725 2015] [authz_svn:debug] [pid 2:tid 2] mod_authz_svn.c(407): [client a.b.c.d:36367] Path to authz file is /home/ams_svn_repos/Src/Testprodukt/conf/authz
> [Sat Aug 15 11:35:13.534867 2015] [authz_svn:info] [pid 2:tid 2] [client a.b.c.d:36367] Access granted: 'tschoening_at_am-soft.de' CHECKOUT Testprodukt:
> [Sat Aug 15 11:35:13.535987 2015] [http:trace3] [pid 2:tid 2] http_filters.c(979): [client a.b.c.d:36367] Response sent with status 201, headers:
> [Sat Aug 15 11:35:13.536000 2015] [http:trace5] [pid 2:tid 2] http_filters.c(986): [client a.b.c.d:36367] Date: Sat, 15 Aug 2015 09:35:13 GMT
> [Sat Aug 15 11:35:13.536007 2015] [http:trace5] [pid 2:tid 2] http_filters.c(989): [client a.b.c.d:36367] Server: Apache/2.4.7 (Ubuntu)
> [Sat Aug 15 11:35:13.536014 2015] [http:trace4] [pid 2:tid 2] http_filters.c(808): [client a.b.c.d:36367] Cache-Control: no-cache
> [Sat Aug 15 11:35:13.536021 2015] [http:trace4] [pid 2:tid 2] http_filters.c(808): [client a.b.c.d:36367] Location: http://private.example.org:3692/src/Testprodukt/!svn/wbl/ccc1b698-e402-42e0-afab-4b63de21ded1/273
> [Sat Aug 15 11:35:13.536027 2015] [http:trace4] [pid 2:tid 2] http_filters.c(808): [client a.b.c.d:36367] Content-Length: 71
> [Sat Aug 15 11:35:13.536033 2015] [http:trace4] [pid 2:tid 2] http_filters.c(808): [client a.b.c.d:36367] Connection: close
> [Sat Aug 15 11:35:13.536039 2015] [http:trace4] [pid 2:tid 2] http_filters.c(808): [client a.b.c.d:36367] Content-Type: text/html; charset=ISO-8859-1
> [Sat Aug 15 11:35:13.547193 2015] [http:trace4] [pid 1:tid 3] http_request.c(301): [client a.b.c.d:36368] Headers received from client:
> [Sat Aug 15 11:35:13.547224 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] Host: private.example.org:3692
> [Sat Aug 15 11:35:13.547236 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] Authorization: Basic xyz
> [Sat Aug 15 11:35:13.547247 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] User-Agent: SVN/1.8.14 (x86_64-unknown-linux-gnu) serf/1.3.7
> [Sat Aug 15 11:35:13.547257 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] Accept-Encoding: gzip
> [Sat Aug 15 11:35:13.547268 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] DAV: http://subversion.tigris.org/xmlns/dav/svn/depth, http://subversion.tigris.org/xmlns/dav/svn/mergeinfo, http://subversion.tigris.org/xmlns/dav/svn/log-revprops
> [Sat Aug 15 11:35:13.547279 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] X-Forwarded-For: 83.218.37.17
> [Sat Aug 15 11:35:13.547289 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] X-Forwarded-Host: public.example.org
> [Sat Aug 15 11:35:13.547299 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] X-Forwarded-Server: public.example.org
> [Sat Aug 15 11:35:13.547309 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] Connection: close
> [Sat Aug 15 11:35:13.547333 2015] [http:trace4] [pid 1:tid 3] http_request.c(305): [client a.b.c.d:36368] Content-Length: 445
> [Sat Aug 15 11:35:13.547408 2015] [authz_core:debug] [pid 1:tid 3] mod_authz_core.c(802): [client a.b.c.d:36368] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.547427 2015] [authz_core:debug] [pid 1:tid 3] mod_authz_core.c(802): [client a.b.c.d:36368] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.552111 2015] [authz_svn:debug] [pid 1:tid 3] mod_authz_svn.c(407): [client a.b.c.d:36368] Path to authz file is /home/ams_svn_repos/Src/Testprodukt/conf/authz
> [Sat Aug 15 11:35:13.552230 2015] [authz_svn:info] [pid 1:tid 3] [client a.b.c.d:36368] Access granted: 'tschoening_at_am-soft.de' PROPPATCH Testprodukt:
> [Sat Aug 15 11:35:13.557181 2015] [http:trace3] [pid 1:tid 3] http_filters.c(979): [client a.b.c.d:36368] Response sent with status 207, headers:
> [Sat Aug 15 11:35:13.557258 2015] [http:trace5] [pid 1:tid 3] http_filters.c(986): [client a.b.c.d:36368] Date: Sat, 15 Aug 2015 09:35:13 GMT
> [Sat Aug 15 11:35:13.557267 2015] [http:trace5] [pid 1:tid 3] http_filters.c(989): [client a.b.c.d:36368] Server: Apache/2.4.7 (Ubuntu)
> [Sat Aug 15 11:35:13.557276 2015] [http:trace4] [pid 1:tid 3] http_filters.c(808): [client a.b.c.d:36368] Content-Length: 526
> [Sat Aug 15 11:35:13.557285 2015] [http:trace4] [pid 1:tid 3] http_filters.c(808): [client a.b.c.d:36368] Connection: close
> [Sat Aug 15 11:35:13.557293 2015] [http:trace4] [pid 1:tid 3] http_filters.c(808): [client a.b.c.d:36368] Content-Type: text/xml; charset=\\"utf-8\\"
> [Sat Aug 15 11:35:13.566144 2015] [http:trace4] [pid 2:tid 4] http_request.c(301): [client a.b.c.d:36369] Headers received from client:
> [Sat Aug 15 11:35:13.566177 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] Host: private.example.org:3692
> [Sat Aug 15 11:35:13.566190 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] Authorization: Basic xyz
> [Sat Aug 15 11:35:13.566201 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] User-Agent: SVN/1.8.14 (x86_64-unknown-linux-gnu) serf/1.3.7
> [Sat Aug 15 11:35:13.566211 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] Content-Type: text/xml
> [Sat Aug 15 11:35:13.566222 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] Accept-Encoding: gzip
> [Sat Aug 15 11:35:13.566232 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] DAV: http://subversion.tigris.org/xmlns/dav/svn/depth, http://subversion.tigris.org/xmlns/dav/svn/mergeinfo, http://subversion.tigris.org/xmlns/dav/svn/log-revprops
> [Sat Aug 15 11:35:13.566243 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] X-Forwarded-For: 83.218.37.17
> [Sat Aug 15 11:35:13.566253 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] X-Forwarded-Host: public.example.org
> [Sat Aug 15 11:35:13.566264 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] X-Forwarded-Server: public.example.org
> [Sat Aug 15 11:35:13.566274 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] Connection: close
> [Sat Aug 15 11:35:13.566284 2015] [http:trace4] [pid 2:tid 4] http_request.c(305): [client a.b.c.d:36369] Content-Length: 231
> [Sat Aug 15 11:35:13.566357 2015] [authz_core:debug] [pid 2:tid 4] mod_authz_core.c(802): [client a.b.c.d:36369] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.566377 2015] [authz_core:debug] [pid 2:tid 4] mod_authz_core.c(802): [client a.b.c.d:36369] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.569896 2015] [authz_svn:debug] [pid 2:tid 4] mod_authz_svn.c(407): [client a.b.c.d:36369] Path to authz file is /home/ams_svn_repos/Src/Testprodukt/conf/authz
> [Sat Aug 15 11:35:13.570015 2015] [authz_svn:info] [pid 2:tid 4] [client a.b.c.d:36369] Access granted: 'tschoening_at_am-soft.de' CHECKOUT Testprodukt:/trunk/testmurksd
> [Sat Aug 15 11:35:13.571032 2015] [http:trace3] [pid 2:tid 4] http_filters.c(979): [client a.b.c.d:36369] Response sent with status 201, headers:
> [Sat Aug 15 11:35:13.571054 2015] [http:trace5] [pid 2:tid 4] http_filters.c(986): [client a.b.c.d:36369] Date: Sat, 15 Aug 2015 09:35:13 GMT
> [Sat Aug 15 11:35:13.571066 2015] [http:trace5] [pid 2:tid 4] http_filters.c(989): [client a.b.c.d:36369] Server: Apache/2.4.7 (Ubuntu)
> [Sat Aug 15 11:35:13.571078 2015] [http:trace4] [pid 2:tid 4] http_filters.c(808): [client a.b.c.d:36369] Cache-Control: no-cache
> [Sat Aug 15 11:35:13.571089 2015] [http:trace4] [pid 2:tid 4] http_filters.c(808): [client a.b.c.d:36369] Location: http://private.example.org:3692/src/Testprodukt/!svn/wrk/ccc1b698-e402-42e0-afab-4b63de21ded1/trunk/testmurksd
> [Sat Aug 15 11:35:13.571101 2015] [http:trace4] [pid 2:tid 4] http_filters.c(808): [client a.b.c.d:36369] Content-Length: 71
> [Sat Aug 15 11:35:13.571111 2015] [http:trace4] [pid 2:tid 4] http_filters.c(808): [client a.b.c.d:36369] Connection: close
> [Sat Aug 15 11:35:13.571122 2015] [http:trace4] [pid 2:tid 4] http_filters.c(808): [client a.b.c.d:36369] Content-Type: text/html; charset=ISO-8859-1
> [Sat Aug 15 11:35:13.582679 2015] [http:trace4] [pid 1:tid 5] http_request.c(301): [client a.b.c.d:36370] Headers received from client:
> [Sat Aug 15 11:35:13.582712 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] Host: private.example.org:3692
> [Sat Aug 15 11:35:13.582724 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] Authorization: Basic xyz
> [Sat Aug 15 11:35:13.582735 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] User-Agent: SVN/1.8.14 (x86_64-unknown-linux-gnu) serf/1.3.7
> [Sat Aug 15 11:35:13.582745 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] Content-Type: application/vnd.svn-svndiff
> [Sat Aug 15 11:35:13.582756 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] Accept-Encoding: gzip
> [Sat Aug 15 11:35:13.582766 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] DAV: http://subversion.tigris.org/xmlns/dav/svn/depth, http://subversion.tigris.org/xmlns/dav/svn/mergeinfo, http://subversion.tigris.org/xmlns/dav/svn/log-revprops
> [Sat Aug 15 11:35:13.582777 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] X-SVN-Version-Name: 273
> [Sat Aug 15 11:35:13.582788 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] X-SVN-Base-Fulltext-MD5: 6cbc8906f80deaaa41445363942219e5
> [Sat Aug 15 11:35:13.582798 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] X-SVN-Result-Fulltext-MD5: b5f6dd4cb3b45cc428106ef3cdf3003c
> [Sat Aug 15 11:35:13.582818 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] X-Forwarded-For: 83.218.37.17
> [Sat Aug 15 11:35:13.582830 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] X-Forwarded-Host: public.example.org
> [Sat Aug 15 11:35:13.582840 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] X-Forwarded-Server: public.example.org
> [Sat Aug 15 11:35:13.582850 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] Connection: close
> [Sat Aug 15 11:35:13.582861 2015] [http:trace4] [pid 1:tid 5] http_request.c(305): [client a.b.c.d:36370] Content-Length: 29
> [Sat Aug 15 11:35:13.582916 2015] [authz_core:debug] [pid 1:tid 5] mod_authz_core.c(802): [client a.b.c.d:36370] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.582935 2015] [authz_core:debug] [pid 1:tid 5] mod_authz_core.c(802): [client a.b.c.d:36370] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.585847 2015] [authz_svn:debug] [pid 1:tid 5] mod_authz_svn.c(407): [client a.b.c.d:36370] Path to authz file is /home/ams_svn_repos/Src/Testprodukt/conf/authz
> [Sat Aug 15 11:35:13.586783 2015] [authz_svn:info] [pid 1:tid 5] [client a.b.c.d:36370] Access granted: 'tschoening_at_am-soft.de' PUT Testprodukt:/trunk/testmurksd
> [Sat Aug 15 11:35:13.587640 2015] [authz_core:debug] [pid 1:tid 5] mod_authz_core.c(802): [client a.b.c.d:36370] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.587665 2015] [authz_core:debug] [pid 1:tid 5] mod_authz_core.c(802): [client a.b.c.d:36370] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.590199 2015] [authz_svn:debug] [pid 1:tid 5] mod_authz_svn.c(407): [client a.b.c.d:36370] Path to authz file is /home/ams_svn_repos/Src/Testprodukt/conf/authz
> [Sat Aug 15 11:35:13.590231 2015] [authz_svn:info] [pid 1:tid 5] [client a.b.c.d:36370] Access granted: 'tschoening_at_am-soft.de' GET Testprodukt:/trunk/testmurksd
> [Sat Aug 15 11:35:13.591510 2015] [dav:error] [pid 1:tid 5] [client a.b.c.d:36370] mod_dav_svn close_stream: error closing write stream [500, #185004]
> [Sat Aug 15 11:35:13.591525 2015] [dav:error] [pid 1:tid 5] [client a.b.c.d:36370] Unexpected end of svndiff input [500, #185004]
> [Sat Aug 15 11:35:13.591549 2015] [http:trace3] [pid 1:tid 5] http_filters.c(979): [client a.b.c.d:36370] Response sent with status 500, headers:
> [Sat Aug 15 11:35:13.591558 2015] [http:trace5] [pid 1:tid 5] http_filters.c(986): [client a.b.c.d:36370] Date: Sat, 15 Aug 2015 09:35:13 GMT
> [Sat Aug 15 11:35:13.591565 2015] [http:trace5] [pid 1:tid 5] http_filters.c(989): [client a.b.c.d:36370] Server: Apache/2.4.7 (Ubuntu)
> [Sat Aug 15 11:35:13.591572 2015] [http:trace4] [pid 1:tid 5] http_filters.c(808): [client a.b.c.d:36370] Content-Length: 227
> [Sat Aug 15 11:35:13.591578 2015] [http:trace4] [pid 1:tid 5] http_filters.c(808): [client a.b.c.d:36370] Connection: close
> [Sat Aug 15 11:35:13.591584 2015] [http:trace4] [pid 1:tid 5] http_filters.c(808): [client a.b.c.d:36370] Content-Type: text/xml; charset=\\"utf-8\\"
> [Sat Aug 15 11:35:13.693576 2015] [http:trace4] [pid 2:tid 6] http_request.c(301): [client a.b.c.d:36371] Headers received from client:
> [Sat Aug 15 11:35:13.693638 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] Host: private.example.org:3692
> [Sat Aug 15 11:35:13.693652 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] Authorization: Basic xyz
> [Sat Aug 15 11:35:13.693663 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] User-Agent: SVN/1.8.14 (x86_64-unknown-linux-gnu) serf/1.3.7
> [Sat Aug 15 11:35:13.693674 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] Accept-Encoding: gzip
> [Sat Aug 15 11:35:13.693684 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] DAV: http://subversion.tigris.org/xmlns/dav/svn/depth, http://subversion.tigris.org/xmlns/dav/svn/mergeinfo, http://subversion.tigris.org/xmlns/dav/svn/log-revprops
> [Sat Aug 15 11:35:13.693695 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] X-Forwarded-For: 83.218.37.17
> [Sat Aug 15 11:35:13.693706 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] X-Forwarded-Host: public.example.org
> [Sat Aug 15 11:35:13.693716 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] X-Forwarded-Server: public.example.org
> [Sat Aug 15 11:35:13.693726 2015] [http:trace4] [pid 2:tid 6] http_request.c(305): [client a.b.c.d:36371] Connection: close
> [Sat Aug 15 11:35:13.693798 2015] [authz_core:debug] [pid 2:tid 6] mod_authz_core.c(802): [client a.b.c.d:36371] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.693824 2015] [authz_core:debug] [pid 2:tid 6] mod_authz_core.c(802): [client a.b.c.d:36371] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
> [Sat Aug 15 11:35:13.698101 2015] [authz_svn:debug] [pid 2:tid 6] mod_authz_svn.c(407): [client a.b.c.d:36371] Path to authz file is /home/ams_svn_repos/Src/Testprodukt/conf/authz
> [Sat Aug 15 11:35:13.698214 2015] [authz_svn:info] [pid 2:tid 6] [client a.b.c.d:36371] Access granted: 'tschoening_at_am-soft.de' DELETE Testprodukt:
> [Sat Aug 15 11:35:13.699243 2015] [http:trace3] [pid 2:tid 6] http_filters.c(979): [client a.b.c.d:36371] Response sent with status 204, headers:
> [Sat Aug 15 11:35:13.699265 2015] [http:trace5] [pid 2:tid 6] http_filters.c(986): [client a.b.c.d:36371] Date: Sat, 15 Aug 2015 09:35:13 GMT
> [Sat Aug 15 11:35:13.699277 2015] [http:trace5] [pid 2:tid 6] http_filters.c(989): [client a.b.c.d:36371] Server: Apache/2.4.7 (Ubuntu)
> [Sat Aug 15 11:35:13.699289 2015] [http:trace4] [pid 2:tid 6] http_filters.c(808): [client a.b.c.d:36371] Content-Length: 0
> [Sat Aug 15 11:35:13.699300 2015] [http:trace4] [pid 2:tid 6] http_filters.c(808): [client a.b.c.d:36371] Connection: close

My ssh log:

> ssh -vvv -o TCPKeepAlive=no -o ServerAliveInterval=2 -p 12321 -N -R localhost:3692:private.example.org:80 public.example.org

> OpenSSH_6.6.1, OpenSSL 1.0.1f 6 Jan 2014
> Authenticated to public.example.org ([w.x.y.z]:12321).
> debug1: Remote connections from localhost:3692 forwarded to local address private.example.org:80
> debug2: fd 3 setting TCP_NODELAY
> debug3: packet_set_tos: set IP_TOS 0x10
> debug1: Requesting no-more-sessions_at_openssh.com
> debug1: Entering interactive session.
> debug1: remote forward success for: listen 3692, connect private.example.org:80
> debug1: All remote forwarding requests processed
> debug1: client_input_channel_open: ctype forwarded-tcpip rchan 2 win 2097152 max 32768
> debug1: client_request_forwarded_tcpip: listen localhost port 3692, originator 127.0.0.1 port 48451
> debug2: fd 4 setting O_NONBLOCK
> debug1: connect_next: host private.example.org ([a.b.c.e]:80) in progress, fd=4
> debug2: fd 4 setting TCP_NODELAY
> debug3: fd 4 is O_NONBLOCK
> debug3: fd 4 is O_NONBLOCK
> debug1: channel 0: new [127.0.0.1]
> debug1: confirm forwarded-tcpip
> debug3: channel 0: waiting for connection
> debug1: channel 0: connected to private.example.org port 80
> debug2: channel 0: read<=0 rfd 4 len 0
> debug2: channel 0: read failed
> debug2: channel 0: close_read
> debug2: channel 0: input open -> drain
> debug2: channel 0: ibuf empty
> debug2: channel 0: send eof
> debug2: channel 0: input drain -> closed
> debug2: channel 0: rcvd eof
> debug2: channel 0: output open -> drain
> debug2: channel 0: obuf empty
> debug2: channel 0: close_write
> debug2: channel 0: output drain -> closed
> debug2: channel 0: rcvd close
> debug3: channel 0: will not send data after close
> debug2: channel 0: send close
> debug2: channel 0: is dead
> debug2: channel 0: garbage collecting
> debug1: channel 0: free: 127.0.0.1, nchannels 1
> debug3: channel 0: status: The following connections are open:
> #0 127.0.0.1 (t4 r2 i3/0 o3/0 fd 4/4 cc -1)
>
> debug1: client_input_channel_open: ctype forwarded-tcpip rchan 2 win 2097152 max 32768
> debug1: client_request_forwarded_tcpip: listen localhost port 3692, originator 127.0.0.1 port 48452
> debug2: fd 4 setting O_NONBLOCK
> debug1: connect_next: host private.example.org ([a.b.c.e]:80) in progress, fd=4
> debug2: fd 4 setting TCP_NODELAY
> debug3: fd 4 is O_NONBLOCK
> debug3: fd 4 is O_NONBLOCK
> debug1: channel 0: new [127.0.0.1]
> debug1: confirm forwarded-tcpip
> debug3: channel 0: waiting for connection
> debug1: channel 0: connected to private.example.org port 80
> debug2: channel 0: read<=0 rfd 4 len 0
> debug2: channel 0: read failed
> debug2: channel 0: close_read
> debug2: channel 0: input open -> drain
> debug2: channel 0: ibuf empty
> debug2: channel 0: send eof
> debug2: channel 0: input drain -> closed
> debug2: channel 0: rcvd eof
> debug2: channel 0: output open -> drain
> debug2: channel 0: obuf empty
> debug2: channel 0: close_write
> debug2: channel 0: output drain -> closed
> debug2: channel 0: rcvd close
> debug3: channel 0: will not send data after close
> debug2: channel 0: send close
> debug2: channel 0: is dead
> debug2: channel 0: garbage collecting
> debug1: channel 0: free: 127.0.0.1, nchannels 1
> debug3: channel 0: status: The following connections are open:
> #0 127.0.0.1 (t4 r2 i3/0 o3/0 fd 4/4 cc -1)
>
> debug1: client_input_channel_open: ctype forwarded-tcpip rchan 2 win 2097152 max 32768
> debug1: client_request_forwarded_tcpip: listen localhost port 3692, originator 127.0.0.1 port 48453
> debug2: fd 4 setting O_NONBLOCK
> debug1: connect_next: host private.example.org ([a.b.c.e]:80) in progress, fd=4
> debug2: fd 4 setting TCP_NODELAY
> debug3: fd 4 is O_NONBLOCK
> debug3: fd 4 is O_NONBLOCK
> debug1: channel 0: new [127.0.0.1]
> debug1: confirm forwarded-tcpip
> debug3: channel 0: waiting for connection
> debug1: channel 0: connected to private.example.org port 80
> debug2: channel 0: read<=0 rfd 4 len 0
> debug2: channel 0: read failed
> debug2: channel 0: close_read
> debug2: channel 0: input open -> drain
> debug2: channel 0: ibuf empty
> debug2: channel 0: send eof
> debug2: channel 0: input drain -> closed
> debug2: channel 0: rcvd eof
> debug2: channel 0: output open -> drain
> debug2: channel 0: obuf empty
> debug2: channel 0: close_write
> debug2: channel 0: output drain -> closed
> debug2: channel 0: rcvd close
> debug3: channel 0: will not send data after close
> debug2: channel 0: send close
> debug2: channel 0: is dead
> debug2: channel 0: garbage collecting
> debug1: channel 0: free: 127.0.0.1, nchannels 1
> debug3: channel 0: status: The following connections are open:
> #0 127.0.0.1 (t4 r2 i3/0 o3/0 fd 4/4 cc -1)
>
> debug1: client_input_channel_open: ctype forwarded-tcpip rchan 2 win 2097152 max 32768
> debug1: client_request_forwarded_tcpip: listen localhost port 3692, originator 127.0.0.1 port 48454
> debug2: fd 4 setting O_NONBLOCK
> debug1: connect_next: host private.example.org ([a.b.c.e]:80) in progress, fd=4
> debug2: fd 4 setting TCP_NODELAY
> debug3: fd 4 is O_NONBLOCK
> debug3: fd 4 is O_NONBLOCK
> debug1: channel 0: new [127.0.0.1]
> debug1: confirm forwarded-tcpip
> debug3: channel 0: waiting for connection
> debug1: channel 0: connected to private.example.org port 80
> debug2: channel 0: read<=0 rfd 4 len 0
> debug2: channel 0: read failed
> debug2: channel 0: close_read
> debug2: channel 0: input open -> drain
> debug2: channel 0: ibuf empty
> debug2: channel 0: send eof
> debug2: channel 0: input drain -> closed
> debug2: channel 0: rcvd eof
> debug2: channel 0: output open -> drain
> debug2: channel 0: obuf empty
> debug2: channel 0: close_write
> debug2: channel 0: output drain -> closed
> debug2: channel 0: rcvd close
> debug3: channel 0: will not send data after close
> debug2: channel 0: send close
> debug2: channel 0: is dead
> debug2: channel 0: garbage collecting
> debug1: channel 0: free: 127.0.0.1, nchannels 1
> debug3: channel 0: status: The following connections are open:
> #0 127.0.0.1 (t4 r2 i3/0 o3/0 fd 4/4 cc -1)
>
> debug1: client_input_channel_open: ctype forwarded-tcpip rchan 2 win 2097152 max 32768
> debug1: client_request_forwarded_tcpip: listen localhost port 3692, originator 127.0.0.1 port 48455
> debug2: fd 4 setting O_NONBLOCK
> debug1: connect_next: host private.example.org ([a.b.c.e]:80) in progress, fd=4
> debug2: fd 4 setting TCP_NODELAY
> debug3: fd 4 is O_NONBLOCK
> debug3: fd 4 is O_NONBLOCK
> debug1: channel 0: new [127.0.0.1]
> debug1: confirm forwarded-tcpip
> debug3: channel 0: waiting for connection
> debug1: channel 0: connected to private.example.org port 80
> debug2: channel 0: read<=0 rfd 4 len 0
> debug2: channel 0: read failed
> debug2: channel 0: close_read
> debug2: channel 0: input open -> drain
> debug2: channel 0: ibuf empty
> debug2: channel 0: send eof
> debug2: channel 0: input drain -> closed
> debug2: channel 0: rcvd eof
> debug2: channel 0: output open -> drain
> debug2: channel 0: obuf empty
> debug2: channel 0: close_write
> debug2: channel 0: output drain -> closed
> debug2: channel 0: rcvd close
> debug3: channel 0: will not send data after close
> debug2: channel 0: send close
> debug2: channel 0: is dead
> debug2: channel 0: garbage collecting
> debug1: channel 0: free: 127.0.0.1, nchannels 1
> debug3: channel 0: status: The following connections are open:
> #0 127.0.0.1 (t4 r2 i3/0 o3/0 fd 4/4 cc -1)
>
> debug1: client_input_channel_open: ctype forwarded-tcpip rchan 2 win 2097152 max 32768
> debug1: client_request_forwarded_tcpip: listen localhost port 3692, originator 127.0.0.1 port 48456
> debug2: fd 4 setting O_NONBLOCK
> debug1: connect_next: host private.example.org ([a.b.c.e]:80) in progress, fd=4
> debug2: fd 4 setting TCP_NODELAY
> debug3: fd 4 is O_NONBLOCK
> debug3: fd 4 is O_NONBLOCK
> debug1: channel 0: new [127.0.0.1]
> debug1: confirm forwarded-tcpip
> debug3: channel 0: waiting for connection
> debug1: channel 0: connected to private.example.org port 80
> debug2: channel 0: read<=0 rfd 4 len 0
> debug2: channel 0: read failed
> debug2: channel 0: close_read
> debug2: channel 0: input open -> drain
> debug2: channel 0: ibuf empty
> debug2: channel 0: send eof
> debug2: channel 0: input drain -> closed
> debug2: channel 0: rcvd eof
> debug2: channel 0: output open -> drain
> debug2: channel 0: obuf empty
> debug2: channel 0: close_write
> debug2: channel 0: output drain -> closed
> debug2: channel 0: rcvd close
> debug3: channel 0: will not send data after close
> debug2: channel 0: send close
> debug2: channel 0: is dead
> debug2: channel 0: garbage collecting
> debug1: channel 0: free: 127.0.0.1, nchannels 1
> debug3: channel 0: status: The following connections are open:
> #0 127.0.0.1 (t4 r2 i3/0 o3/0 fd 4/4 cc -1)

Mit freundlichen Grüßen,

Thorsten Schöning

-- 
Thorsten Schöning       E-Mail: Thorsten.Schoening_at_AM-SoFT.de
AM-SoFT IT-Systeme      http://www.AM-SoFT.de/
Telefon...........05151-  9468- 55
Fax...............05151-  9468- 88
Mobil..............0178-8 9468- 04
AM-SoFT GmbH IT-Systeme, Brandenburger Str. 7c, 31789 Hameln
AG Hannover HRB 207 694 - Geschäftsführer: Andreas Muchow
Received on 2015-08-15 16:17:15 CEST

This is an archived mail posted to the Subversion Users mailing list.

This site is subject to the Apache Privacy Policy and the Apache Public Forum Archive Policy.