Re: Commit error using 1.8.0 final

On Tue, Jun 18, 2013 at 3:20 PM, Mark Phippard <markphip_at_gmail.com> wrote:
> On Mon, Jun 17, 2013 at 4:00 PM, Lieven Govaerts <svnlgo_at_mobsol.be> wrote:
>> On Mon, Jun 17, 2013 at 9:22 PM, Mark Phippard <markphip_at_gmail.com> wrote:
>>> I updated my Windows laptop to 1.8.0 final. I am trying to commit a
>>> change for Subclipse to tigris.org and it is failing. The error
>>> message is not that helpful. Any ideas how to get better error
>>> information?
>>
>> There might be a higher level option, but in serf you can enable lots
>> of debug logging, at compile time in serf_private.h.
>> SSL_VERBOSE gives huge amount of log lines but is probably what you
>> need here, other interesting flags are SOCK_VERBOSE and CONN_VERBOSE.
>
> I turned on everything and ran command again. Attaching the output.
>

Extract from the debugging output:

> [2013-06-18T09:17:40.050463-04] [l:204.11.125.145:65477 r:204.16.104.146:80] ./outgoing.c: --- socket_sendv:
> CHECKOUT /svn/subclipse/!svn/ver/5706/trunk/www/update_1.10.x/features HTTP/1.1
> Host: subclipse.tigris.org
> Authorization: Basic xxxxxxxxxxxxxxxxxx
> User-Agent: SVN/1.8.0 (x86_64-apple-darwin11.4.2) serf/1.2.1
> Content-Type: text/xml
> Accept-Encoding: gzip
> DAV: http://subversion.tigris.org/xmlns/dav/svn/depth
> DAV: http://subversion.tigris.org/xmlns/dav/svn/mergeinfo
> DAV: http://subversion.tigris.org/xmlns/dav/svn/log-revprops
> Transfer-Encoding: chunked
>
> e5
> <?xml version="1.0" encoding="utf-8"?><D:checkout xmlns:D="DAV:"><D:activity-set><D:href>/svn/subclipse/!svn/act/5ea43b8a-0d4e-4ef0-8c62-> 5be2e0291f8e</D:href></D:activity-set><D:apply-to-version></D:apply-to-version></D:checkout>
> 0
>
> -(703)-
> [2013-06-18T09:17:40.171850-04] [l:204.11.125.145:65477 r:204.16.104.146:80] buckets/socket_buckets.c: --- socket_recv:
> HTTP/1.1 201 Created
> Date: Tue, 18 Jun 2013 13:17:37 GMT
> Server: Apache
> HelmLoginID: markphip
> Cache-Control: no-cache
> Location: http://subclipse.tigris.org/svn/subclipse/!svn/wrk/5ea43b8a-0d4e-4ef0-8c62-5be2e0291f8e/trunk/www/update_1.10.x/features
> Content-Length: 349
> Content-Type: text/html; charset=ISO-8859-1
>
>
> -(323)-
> [2013-06-18T09:17:40.173550-04] [l:204.11.125.145:65477 r:204.16.104.146:80] buckets/socket_buckets.c: socket_recv error 70014

This response has content-length:349 yet no data is received in the
response body. Serf then gets an APR_EOF on the socket.

So basically seen from the client, the server aborts the connection
mid-response unexpectedly. Not even a Connection:Close header was set.

Can you verify in your wireshark trace that you see the same thing as
what's logged?

The question here is why the server aborts the connection.

> [2013-06-18T09:17:41.021136-04] [l:204.11.125.145:65477 r:204.16.104.146:80] ./outgoing.c: closed socket, status 0
> [2013-06-18T09:17:41.021182-04] ./outgoing.c: reset connection 0x588d4a28
> [2013-06-18T09:17:41.021217-04] ./outgoing.c: created socket for conn 0x588d4a28, status 0
> [2013-06-18T09:17:41.021301-04] [l:204.11.125.145:65478 r:204.16.104.146:80] ./outgoing.c: connected socket for conn 0x588d4a28, status 36
> [2013-06-18T09:17:41.149533-04] [l:204.11.125.145:65478 r:204.16.104.146:80] ./outgoing.c: --- socket_sendv:
> DELETE /svn/subclipse/!svn/act/5ea43b8a-0d4e-4ef0-8c62-5be2e0291f8e HTTP/1.1
> Host: subclipse.tigris.org
..
> HTTP/1.1 204 No Content
> Date: Tue, 18 Jun 2013 13:17:38 GMT
> Server: Apache
> HelmLoginID: markphip
> Content-Length: 0
> Content-Type: text/plain
>
>
> -(148)-

This 204 response is not a problem, there's a special case for
Content-Length == 0 && code == 204 in serf (response_buckets.c).

> I also created a test project on tigris. So far, all commits are
> working for that project -- which I guess is good news. Maybe some
> odd scenario I am running into.
>
> I am just committing some new files though, so not sure what I am
> doing that would be "weird".
>
> --
> Thanks
>
> Mark Phippard
> http://markphip.blogspot.com/

hth,

Lieven