Re: svn 1.8 causing locks to be broken on update

On Thu, Aug 15, 2013 at 6:19 PM, Bert Huijben <bert_at_qqmail.nl> wrote:

> Hi Felipe,****
>
> Do you have access to the repository configuration at the server?****
>
> Things we are most interested in is how exactly your authentication is
> setup. E.g. do you allow anonymous acces, and if yes in what way?****
>
> Posting your server configuration (with passwords and private information
> changed) would really help.****
>
> I don’t think the serf debug log is really giving much more information
> here, as there are (as far as I can tell) no requests that fail. The
> default server side logging (if not disabled) provides most likely more
> than enough information to look into this.****
>
> Last night (EU time) we spend some time with different scenarios in an
> attempt to reproduce your issue, but we were unable to.****
>
> Bert****
>
>
​Hi Bert. I do have access to the server. I posted the HTTP config here <
http://pastebin.com/ZefLnHA9>

- we do not allow anonymous access
- auth is handles by htpasswd valid-user (also use 'authz' to "hide" some
directories from some users, but I do not believe this is the case here)

I'm outputing the "access_log" of the virtualhost which runs SVN server
(apache). I hope I'm not spamming. I thought it was too small to paste on
pastebin...

ps. the 'error_log' was 0 bytes

This is output from a 1.6.15 client and 1.6.15 server. Locks are not broken
---BEGIN---
cat access_log_svntest_1.6.15
192.168.0.20 - - [15/Aug/2013:21:21:37 +1000] "OPTIONS / HTTP/1.1" 200 -
"-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:21:47 +1000] "OPTIONS / HTTP/1.1" 200 -
"-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:22:01 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:04 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:04 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:04 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:04 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/bc/4 HTTP/1.1" 207 659 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:22:06 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:06 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 3946 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:22:51 +1000] "OPTIONS
/svn/repos/Trunk/Lettus/lbin HTTP/1.1" 401 491 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:51 +1000] "OPTIONS
/svn/repos/Trunk/Lettus/lbin HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:51 +1000] "PROPFIND
/svn/repos/Trunk/Lettus/lbin HTTP/1.1" 207 712 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:51 +1000] "PROPFIND
/svn/repos/Trunk/Lettus/lbin/file1.txt HTTP/1.1" 207 698 "-" "SVN/1.6.15
(r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:51 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:51 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:51 +1000] "LOCK
/svn/repos/Trunk/Lettus/lbin/file1.txt HTTP/1.1" 200 371 "-" "SVN/1.6.15
(r1038135) neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:22:55 +1000] "OPTIONS
/svn/repos/Trunk/Lettus HTTP/1.1" 401 491 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:55 +1000] "OPTIONS
/svn/repos/Trunk/Lettus HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:55 +1000] "PROPFIND
/svn/repos/Trunk/Lettus HTTP/1.1" 207 702 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:55 +1000] "PROPFIND
/svn/repos/Trunk/Lettus HTTP/1.1" 207 702 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:55 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:55 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:22:55 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 1601 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:23:01 +1000] "OPTIONS /svn/repos/Trunk
HTTP/1.1" 401 491 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:01 +1000] "OPTIONS
/svn/repos/Trunk HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:01 +1000] "PROPFIND
/svn/repos/Trunk HTTP/1.1" 207 688 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:01 +1000] "PROPFIND
/svn/repos/Trunk HTTP/1.1" 207 688 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:01 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:01 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:01 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 2040 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:23:08 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:08 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:08 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:08 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:08 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:08 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:08 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 2473 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:23:19 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:19 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:19 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:19 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:19 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:19 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:19 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 2473 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - - [15/Aug/2013:21:23:33 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:33 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:33 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:33 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.6.15 (r1038135) neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:33 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:33 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
192.168.0.20 - felipe [15/Aug/2013:21:23:33 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 2473 "-" "SVN/1.6.15 (r1038135)
neon/0.25.5"
​---END---

And output from 1.8.1 client to 1.6.15 server (lock gets broken)

---BEGIN---
cat access_log_svntest_1.8.1
192.168.0.72 - - [15/Aug/2013:21:24:59 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - - [15/Aug/2013:21:25:14 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 97 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "PROPFIND
/svn/repos/!svn/bc/4 HTTP/1.1" 207 323 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - - [15/Aug/2013:21:25:14 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 97 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:14 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:15 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:15 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:15 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 3946 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - - [15/Aug/2013:21:25:28 +1000] "OPTIONS
/svn/repos/Trunk/Lettus/lbin HTTP/1.1" 401 491 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:28 +1000] "OPTIONS
/svn/repos/Trunk/Lettus/lbin HTTP/1.1" 200 189 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:28 +1000] "OPTIONS
/svn/repos/Trunk/Lettus/lbin HTTP/1.1" 200 97 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:28 +1000] "LOCK
/svn/repos/Trunk/Lettus/lbin/test.txt HTTP/1.1" 200 378 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - - [15/Aug/2013:21:25:43 +1000] "OPTIONS /svn/repos HTTP/1.1"
401 491 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:43 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 189 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:43 +1000] "OPTIONS /svn/repos
HTTP/1.1" 200 97 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:43 +1000] "PROPFIND /svn/repos
HTTP/1.1" 207 649 "-" "SVN/1.8.1 (x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:43 +1000] "PROPFIND
/svn/repos/!svn/vcc/default HTTP/1.1" 207 400 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:43 +1000] "PROPFIND
/svn/repos/!svn/bln/4 HTTP/1.1" 207 451 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
192.168.0.72 - felipe [15/Aug/2013:21:25:43 +1000] "REPORT
/svn/repos/!svn/vcc/default HTTP/1.1" 200 2471 "-" "SVN/1.8.1
(x64-microsoft-windows) serf/1.3.0"
---END---

​I'm using svn.exe from TSVN inside cygwin env.

--
Felipe​