Comment 18 for bug 185986

Revision history for this message
indium (indium) wrote :

Further to #17: I've switched off the debug in autofs and set 'DEBUG most' in /etc/davfs2/davfs2.conf .

it seems to be not able to connect/find the server. With a windows machine or lftp I never experience any delay and with davfs it is nearly always there at the first attempt.

davfs outputs to /var/log/debug the following (while executing the 'ls' command as in #17):

Start of davfs:
Jan 12 13:18:00 my-laptop mount.davfs: davfs2 1.4.6
Jan 12 13:18:00 my-laptop mount.davfs: /sbin/mount.davfs https://webdavserver.com/somedavdir /srv/dav/somedavdir -o rw,nodev,uid=1000,gid=1000
Jan 12 13:39:44 my-laptop mount.davfs: davfs2 1.4.6
Jan 12 13:39:44 my-laptop mount.davfs: /sbin/mount.davfs https://webdavserver.com/somedavdir /srv/dav/somedavdir -o rw,nodev,uid=1000,gid=1000
Jan 12 13:39:44 my-laptop mount.davfs: Configuration:

Then, right after doing a 'ls' on an unmounted webdav directory:

Jan 12 13:42:10 my-laptop mount.davfs: SELECT: 1
Jan 12 13:42:10 my-laptop mount.davfs: FUSE_LOOKUP:
Jan 12 13:42:10 my-laptop mount.davfs: p 0x1ea48c0, tst
Jan 12 13:42:10 my-laptop mount.davfs: lookup /somedavdir/tst
Jan 12 13:42:10 my-laptop mount.davfs: Running pre_send hooks
Jan 12 13:42:10 my-laptop mount.davfs: Sending request headers:#012PROPFIND /somedavdir/tst/ HTTP/1.1#015#012User-Agent: davfs2/1.4.6 neon/0.29.3#015#012Connection: TE#015#012TE: trailers#015#012Host: webdavserver.com#015#012Depth: 1#015#012Content-Length: 286#015#012Content-Type: application/xml#015#012Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx#015#012#015
Jan 12 13:42:10 my-laptop mount.davfs: Sending request-line and headers:
Jan 12 13:42:10 my-laptop mount.davfs: Sending request body:
Jan 12 13:42:10 my-laptop mount.davfs: Request sent; retry is 1.
Jan 12 13:42:40 my-laptop mount.davfs: Aborted request (-2): Could not read status line

(note the 30seconds delay)
(so here it aborts, but it will do another attempt:)

Jan 12 13:42:40 my-laptop mount.davfs: sess: Closing connection.
Jan 12 13:42:40 my-laptop mount.davfs: sess: Connection closed.
Jan 12 13:42:40 my-laptop mount.davfs: Request ends, status 0 class 0xx, error line:#012Could not read status line: connection timed out
Jan 12 13:42:40 my-laptop mount.davfs: Running destroy hooks.
Jan 12 13:42:40 my-laptop mount.davfs: Request ends.
Jan 12 13:42:40 my-laptop mount.davfs: RET: Success
Jan 12 13:42:40 my-laptop mount.davfs: tidy: 0 of 35 nodes changed
Jan 12 13:42:40 my-laptop mount.davfs: cache-size: 0 MiBytes.
Jan 12 13:42:40 my-laptop mount.davfs: SELECT: 1
Jan 12 13:42:40 my-laptop mount.davfs: FUSE_LOOKUP:
Jan 12 13:42:40 my-laptop mount.davfs: p 0x1ea48c0, .Trash
Jan 12 13:42:40 my-laptop mount.davfs: lookup /somedavdir/.Trash
Jan 12 13:42:40 my-laptop mount.davfs: Running pre_send hooks
Jan 12 13:42:40 my-laptop mount.davfs: Sending request headers:#012PROPFIND /somedavdir/ HTTP/1.1#015#012User-Agent: davfs2/1.4.6 neon/0.29.3#015#012Connection: TE#015#012TE: trailers#015#012Host: webdavserver.com#015#012Depth: 1#015#012Content-Length: 286#015#012Content-Type: application/xml#015#012Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx#015#012#015
Jan 12 13:42:40 my-laptop mount.davfs: Sending request-line and headers:
Jan 12 13:42:40 my-laptop mount.davfs: Connecting to 231.321.123.123
Jan 12 13:42:40 my-laptop mount.davfs: Sending request body:
Jan 12 13:42:40 my-laptop mount.davfs: Request sent; retry is 0.
Jan 12 13:42:40 my-laptop mount.davfs: [status-line] < HTTP/1.1 207 Multi-Status#015

(so here it works again. note that it mentions the (faked) IP address now)

Jan 12 13:42:40 my-laptop mount.davfs: [hdr] Date: Wed, 12 Jan 2011 12:42:41 GMT#015
Jan 12 13:42:40 my-laptop mount.davfs: Header Name: [date], Value: [Wed, 12 Jan 2011 12:42:41 GMT]
Jan 12 13:42:40 my-laptop mount.davfs: [hdr] Server: Microsoft-IIS/6.0#015
Jan 12 13:42:40 my-laptop mount.davfs: Header Name: [server], Value: [Microsoft-IIS/6.0]
Jan 12 13:42:40 my-laptop mount.davfs: [hdr] Content-Type: text/xml#015
Jan 12 13:42:40 my-laptop mount.davfs: Header Name: [content-type], Value: [text/xml]
Jan 12 13:42:40 my-laptop mount.davfs: [hdr] Transfer-Encoding: chunked#015
Jan 12 13:42:40 my-laptop mount.davfs: Header Name: [transfer-encoding], Value: [chunked]
Jan 12 13:42:40 my-laptop mount.davfs: [hdr] #015
Jan 12 13:42:40 my-laptop mount.davfs: End of headers.

(and then all is well. The next 'ls' command right after gets a 'normal' or 'immediately' response).

Since I can't resolve these problems, I revert to lftp many times. This never shows these delays. Also I don't think that the server is unresponsive.