files stay in lost+found

2008-09-15
2013-04-16
  • Folks:

    Server: itouch running "Air Sharing" WebDAV application v1.0.0

    Client: is using the davfs2 version: 1.3.3 on a Fedora 8, Kernel: 2.6.26.3-14.fc8

    The cadaver client works correctly.

    I have no problem mounting the resource:

    I am using the "root" user for this test.

    /usr/local/sbin/mount.davfs http://10.222.222.130:8080/tmp/ /mnt/davfs

    I have configured a userid/password on the server.

    I have disabled "Locks" in my "/usr/local/etc/davfs2/davfs2.conf" file.

    The linux "df" command sees the resource (davfs2 file system).

    File listing are correct.

    Copying files from the davfs2 file system work correctly.

    Problem:

    When I copy a file to the davfs2 file system (upload) it always stays in the "lost+found" directory. The files never appear to be uploaded to the server.

    I do not see any log info in "/var/log/messages". I have enable debug using "debug most" in the config file.

    Using wireshark I do not see any packets being sent during the copy.

    I would glad to help debug this issue.

    ---Ron Henderson

     
    • Werner Baumann
      Werner Baumann
      2008-09-15

      Hello Ron,

      this is usually caused by problems when uploading files.

      First is to find the log messages. mount.davfs sends log messages to the syslog-daemon with facility LOG_DAEMON and severity LOG_DEBUG. I don't know where Fedoro puts this kind of messages. But most  probably there are more log-files than just messages.

      I you use wireshark to fetch the traffic it is just as well. But note: there are no packages send to the server *during* the copy. Files are uploaded some seconds after copying is complete. See the davfs2.conf-man-page: delay_upload. Please look for HEAD and PUT requests or send me the log, but include 10 to 20 seconds after cp returned.

      As it works with cadaver, there is a good chance that a broken serverside implementation of HEAD causes the problem (some hundreds of years after invetion of HTTP, more and more server appear that don't know how to respond on a HEAD-request). You may try option "if_match_bug 0" and/or "precheck 0".

      Cheers
      Werner

       
    • Werner:

      Was able to enable debug output on fedora and logging sent to: "/var/log/messages".

      Added both options "if_match_bug 0" and/or "precheck 0". Still did not work. Upload files remain in "lost+found".

      The file copied was:

      cp -v /tmp/aaaa.txt /mnt/davfs

      Here is a portion of the debug output for your diagnoses:

      Sep 15 19:24:50 thor mount.davfs: davfs2 1.3.3
      Sep 15 19:24:50 thor mount.davfs: /usr/local/sbin/mount.davfs http://10.222.222.130:8080/tmp/ /mnt/davfs
      Sep 15 19:24:50 thor mount.davfs: Configuration:
      Sep 15 19:24:50 thor mount.davfs:   url: http://10.222.222.130:8080/tmp/
      Sep 15 19:24:50 thor mount.davfs:   mount point: /mnt/davfs
      Sep 15 19:24:50 thor mount.davfs:   dav_user: davfs2
      Sep 15 19:24:50 thor mount.davfs:   dav_group: davfs2
      Sep 15 19:24:50 thor mount.davfs:   ignore_home: (null)
      Sep 15 19:24:50 thor mount.davfs:   conf: (null)
      Sep 15 19:24:50 thor mount.davfs:   user: 0
      Sep 15 19:24:50 thor mount.davfs:   netdev: 1
      Sep 15 19:24:50 thor mount.davfs:   mopts: 0xc0ed0006
      Sep 15 19:24:50 thor mount.davfs:   add_mopts: (null)
      Sep 15 19:24:50 thor mount.davfs:   kernel_fs: (null)
      Sep 15 19:24:50 thor mount.davfs:   buf_size: 0 KiB
      Sep 15 19:24:50 thor mount.davfs:   uid: 0
      Sep 15 19:24:50 thor mount.davfs:   gid: 0
      Sep 15 19:24:50 thor mount.davfs:   dir_umask: 06000
      Sep 15 19:24:50 thor mount.davfs:   file_umask: 06000
      Sep 15 19:24:50 thor mount.davfs:   dir_mode: 040755
      Sep 15 19:24:50 thor mount.davfs:   file_mode: 0100644
      Sep 15 19:24:50 thor mount.davfs:   scheme: http
      Sep 15 19:24:50 thor mount.davfs:   host: 10.222.222.130
      Sep 15 19:24:50 thor mount.davfs:   port: 8080
      Sep 15 19:24:50 thor mount.davfs:   path: /tmp/
      Sep 15 19:24:50 thor mount.davfs:   servercert: (null)
      Sep 15 19:24:50 thor mount.davfs:   secrets: (null)
      Sep 15 19:24:50 thor mount.davfs:   clicert: (null)
      Sep 15 19:24:50 thor mount.davfs:   p_host: (null)
      Sep 15 19:24:50 thor mount.davfs:   p_port: 8080
      Sep 15 19:24:50 thor mount.davfs:   useproxy: 0
      Sep 15 19:24:50 thor mount.davfs:   askauth: 1
      Sep 15 19:24:50 thor mount.davfs:   displayname: 0
      Sep 15 19:24:50 thor mount.davfs:   locks: 0
      Sep 15 19:24:50 thor mount.davfs:   lock_owner: (null)
      Sep 15 19:24:50 thor mount.davfs:   lock_timeout: 1800 s
      Sep 15 19:24:50 thor mount.davfs:   lock_refresh: 60 s
      Sep 15 19:24:50 thor mount.davfs:   expect100: 1
      Sep 15 19:24:50 thor mount.davfs:   if_match_bug: 0
      Sep 15 19:24:50 thor mount.davfs:   drop_weak_etags: 0
      Sep 15 19:24:50 thor mount.davfs:   allow_cookie: 0
      Sep 15 19:24:50 thor mount.davfs:   precheck: 0
      Sep 15 19:24:50 thor mount.davfs:   ignore_dav_header: 0
      Sep 15 19:24:50 thor mount.davfs:   connect_timeout: 10 s
      Sep 15 19:24:50 thor mount.davfs:   read_timeout: 30 s
      Sep 15 19:24:50 thor mount.davfs:   retry: 30 s
      Sep 15 19:24:50 thor mount.davfs:   max_retry: 300 s
      Sep 15 19:24:50 thor mount.davfs:   s_charset: (null)
      Sep 15 19:24:50 thor mount.davfs:   header: (null)
      Sep 15 19:24:50 thor mount.davfs:   sys_cache: /var/cache/davfs2
      Sep 15 19:24:50 thor mount.davfs:   cache_dir: /var/cache/davfs2
      Sep 15 19:24:50 thor mount.davfs:   backup_dir: lost+found
      Sep 15 19:24:50 thor mount.davfs:   cache_size: 50 MiB
      Sep 15 19:24:50 thor mount.davfs:   table_size: 1024
      Sep 15 19:24:50 thor mount.davfs:   dir_refresh: 60 s
      Sep 15 19:24:50 thor mount.davfs:   file_refresh: 1 s
      Sep 15 19:24:50 thor mount.davfs:   delay_upload: 1
      Sep 15 19:24:50 thor mount.davfs:   gui_optimize: 0
      Sep 15 19:24:50 thor mount.davfs:   debug: 0x7
      Sep 15 19:24:50 thor mount.davfs:   neon_debug: 0x3
      Sep 15 19:24:50 thor mount.davfs: mountpoint: /mnt/davfs
      Sep 15 19:24:50 thor mount.davfs: mounts in: /proc/mounts
      Sep 15 19:24:58 thor mount.davfs: PID file: /var/run/mount.davfs/mnt-davfs.pid
      Sep 15 19:24:58 thor mount.davfs: changing persona: euid 10003, gid 10003
      Sep 15 19:24:58 thor mount.davfs: Initializing webdav
      Sep 15 19:24:58 thor mount.davfs: HTTP session to http://10.222.222.130:8080 begins.
      Sep 15 19:24:58 thor mount.davfs: Initializing cache
      Sep 15 19:24:58 thor mount.davfs: Alignment of dav_node: 8
      Sep 15 19:24:58 thor mount.davfs: Checking cache directory
      Sep 15 19:24:58 thor mount.davfs:   /var/cache/davfs2/10.222.222.130-tmp+mnt-davfs+root
      Sep 15 19:24:58 thor mount.davfs: new node: (nil)->0x8ae26e8
      Sep 15 19:24:58 thor mount.davfs: Reading stored cache data
      Sep 15 19:24:58 thor mount.davfs: new node: 0x8ae26e8->0x8ae2750
      Sep 15 19:24:58 thor mount.davfs: Running pre_send hooks
      Sep 15 19:24:58 thor mount.davfs: Sending request headers:#012OPTIONS /tmp/ HTTP/1.1#015#012Host: 10.222.222.130:8080#015#012User-Agent: davfs2/1.3.3 neon/0.27.2#015#012Keep-Alive: #015#012Connection: TE, Keep-Alive#015#012TE: trailers#015#012#015
      Sep 15 19:24:58 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:24:58 thor mount.davfs: Doing DNS lookup on 10.222.222.130...
      Sep 15 19:24:58 thor mount.davfs: Connecting to 10.222.222.130
      Sep 15 19:24:59 thor mount.davfs: Request sent; retry is 0.
      Sep 15 19:24:59 thor mount.davfs: [status-line] < HTTP/1.1 200 OK#015
      Sep 15 19:24:59 thor mount.davfs: [hdr] DAV: 1,2#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [dav], Value: [1,2]
      Sep 15 19:24:59 thor mount.davfs: [hdr] MS-Author-Via: DAV#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [ms-author-via], Value: [DAV]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Allow: OPTIONS, PROPFIND, GET, HEAD, PROPPATCH, PUT, DELETE, COPY, MOVE, MKCOL, LOCK, UNLOCK, POST#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [allow], Value: [OPTIONS, PROPFIND, GET, HEAD, PROPPATCH, PUT, DELETE, COPY, MOVE, MKCOL, LOCK, UNLOCK, POST]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Content-Type: text/html; charset=UTF-8#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [content-type], Value: [text/html; charset=UTF-8]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Content-Length: 0#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [content-length], Value: [0]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Date: Mon, 15 Sep 2008 23:24:58 GMT#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [date], Value: [Mon, 15 Sep 2008 23:24:58 GMT]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Server: lighttpd/1.4.19 with Avatron extensions#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [server], Value: [lighttpd/1.4.19 with Avatron extensions]
      Sep 15 19:24:59 thor mount.davfs: [hdr] #015
      Sep 15 19:24:59 thor mount.davfs: End of headers.
      Sep 15 19:24:59 thor mount.davfs: Running post_headers hooks
      Sep 15 19:24:59 thor mount.davfs: Running post_send hooks
      Sep 15 19:24:59 thor mount.davfs: Request ends, status 200 class 2xx, error line:#012200 OK
      Sep 15 19:24:59 thor mount.davfs: Running destroy hooks.
      Sep 15 19:24:59 thor mount.davfs: Request ends.
      Sep 15 19:24:59 thor mount.davfs: Running pre_send hooks
      Sep 15 19:24:59 thor mount.davfs: Sending request headers:#012PROPFIND /tmp/ HTTP/1.1#015#012Host: 10.222.222.130:8080#015#012User-Agent: davfs2/1.3.3 neon/0.27.2#015#012Connection: TE#015#012TE: trailers#015#012Depth: 1#015#012Content-Length: 314#015#012Content-Type: application/xml#015#012#015
      Sep 15 19:24:59 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:24:59 thor mount.davfs: Sending request body:
      Sep 15 19:24:59 thor mount.davfs: Request sent; retry is 1.
      Sep 15 19:24:59 thor mount.davfs: [status-line] < HTTP/1.1 401 Unauthorized#015
      Sep 15 19:24:59 thor mount.davfs: [hdr] WWW-Authenticate: Digest realm="AppHTTPServer", nonce="d86295ca2a9ec75b95d5a01a060aad01", qop="auth"#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [www-authenticate], Value: [Digest realm="AppHTTPServer", nonce="d86295ca2a9ec75b95d5a01a060aad01", qop="auth"]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Content-Type: text/html#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [content-type], Value: [text/html]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Content-Length: 351#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [content-length], Value: [351]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Date: Mon, 15 Sep 2008 23:24:58 GMT#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [date], Value: [Mon, 15 Sep 2008 23:24:58 GMT]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Server: lighttpd/1.4.19 with Avatron extensions#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [server], Value: [lighttpd/1.4.19 with Avatron extensions]
      Sep 15 19:24:59 thor mount.davfs: [hdr] #015
      Sep 15 19:24:59 thor mount.davfs: End of headers.
      Sep 15 19:24:59 thor mount.davfs: Running post_headers hooks
      Sep 15 19:24:59 thor mount.davfs: Reading 351 bytes of response body.
      Sep 15 19:24:59 thor mount.davfs: Got 351 bytes.
      Sep 15 19:24:59 thor mount.davfs: Running post_send hooks
      Sep 15 19:24:59 thor mount.davfs: Running pre_send hooks
      Sep 15 19:24:59 thor mount.davfs: Sending request headers:#012PROPFIND /tmp/ HTTP/1.1#015#012Host: 10.222.222.130:8080#015#012User-Agent: davfs2/1.3.3 neon/0.27.2#015#012Connection: TE#015#012TE: trailers#015#012Depth: 1#015#012Content-Length: 314#015#012Content-Type: application/xml#015#012Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx#015#012#015
      Sep 15 19:24:59 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:24:59 thor mount.davfs: Sending request body:
      Sep 15 19:24:59 thor mount.davfs: Request sent; retry is 1.
      Sep 15 19:24:59 thor mount.davfs: [status-line] < HTTP/1.1 207 Multi-status#015
      Sep 15 19:24:59 thor mount.davfs: [hdr] Content-Type: text/xml; charset="utf-8"#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [content-type], Value: [text/xml; charset="utf-8"]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Content-Length: 775#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [content-length], Value: [775]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Date: Mon, 15 Sep 2008 23:24:58 GMT#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [date], Value: [Mon, 15 Sep 2008 23:24:58 GMT]
      Sep 15 19:24:59 thor mount.davfs: [hdr] Server: lighttpd/1.4.19 with Avatron extensions#015
      Sep 15 19:24:59 thor mount.davfs: Header Name: [server], Value: [lighttpd/1.4.19 with Avatron extensions]
      Sep 15 19:24:59 thor mount.davfs: [hdr] #015
      Sep 15 19:24:59 thor mount.davfs: End of headers.
      Sep 15 19:24:59 thor mount.davfs: Running post_headers hooks
      Sep 15 19:24:59 thor mount.davfs: Reading 775 bytes of response body.
      Sep 15 19:24:59 thor mount.davfs: Got 775 bytes.
      Sep 15 19:24:59 thor mount.davfs: Running post_send hooks
      Sep 15 19:24:59 thor mount.davfs: Request ends, status 207 class 2xx, error line:#012207 Multi-status
      Sep 15 19:24:59 thor mount.davfs: Running destroy hooks.
      Sep 15 19:24:59 thor mount.davfs: Request ends.
      Sep 15 19:24:59 thor mount.davfs: directory updated: (nil)->0x8ae26e8
      Sep 15 19:24:59 thor mount.davfs:   /tmp/
      Sep 15 19:24:59 thor kernel: SELinux: initialized (dev fuse, type fuse), uses genfs_contexts
      Sep 15 19:24:59 thor mount.davfs: kernel_fs: fuse
      Sep 15 19:24:59 thor mount.davfs: Fork into daemon mode
      Sep 15 19:24:59 thor mount.davfs: Set signal handler
      Sep 15 19:24:59 thor mount.davfs: Releasing root privileges
      Sep 15 19:24:59 thor mount.davfs: Releasing terminal
      Sep 15 19:24:59 thor mount.davfs: Writing pid file
      Sep 15 19:24:59 thor mount.davfs: Starting message loop
      Sep 15 19:24:59 thor mount.davfs: fuse kernel version 7
      Sep 15 19:24:59 thor mount.davfs: SELECT: 1
      Sep 15 19:24:59 thor mount.davfs: FUSE_INIT:
      Sep 15 19:24:59 thor mount.davfs:   version 7.9
      Sep 15 19:24:59 thor mount.davfs: RET: Success
      Sep 15 19:24:59 thor mount.davfs: Parent: parent pid: 5059, child pid: 5062
      Sep 15 19:24:59 thor mount.davfs: Parent: writing mtab entry
      Sep 15 19:24:59 thor mount.davfs: Parent: leaving now
      Sep 15 19:25:00 thor mount.davfs: SELECT: 0
      Sep 15 19:25:00 thor mount.davfs: tidy: 0 of 2 nodes changed
      Sep 15 19:25:00 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:00 thor mount.davfs: SELECT: 1
      Sep 15 19:25:00 thor mount.davfs: FUSE_GETATTR:
      Sep 15 19:25:00 thor mount.davfs:   n 0x8ae26e8
      Sep 15 19:25:00 thor mount.davfs: getattr /tmp/
      Sep 15 19:25:00 thor mount.davfs: RET: Success
      Sep 15 19:25:02 thor mount.davfs: SELECT: 0
      Sep 15 19:25:02 thor mount.davfs: tidy: 0 of 2 nodes changed
      Sep 15 19:25:02 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:03 thor mount.davfs: SELECT: 0
      Sep 15 19:25:03 thor mount.davfs: tidy: 0 of 2 nodes changed
      Sep 15 19:25:03 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:04 thor mount.davfs: SELECT: 0
      Sep 15 19:25:02 thor mount.davfs: tidy: 0 of 2 nodes changed
      Sep 15 19:25:02 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:03 thor mount.davfs: SELECT: 0
      Sep 15 19:25:03 thor mount.davfs: tidy: 0 of 2 nodes changed
      Sep 15 19:25:03 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:04 thor mount.davfs: SELECT: 0
      Sep 15 19:25:04 thor mount.davfs: tidy: 0 of 2 nodes changed
      Sep 15 19:25:04 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:05 thor mount.davfs: SELECT: 0
      Sep 15 19:25:05 thor mount.davfs: tidy: 0 of 2 nodes changed
      Sep 15 19:25:05 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:05 thor mount.davfs: SELECT: 1
      Sep 15 19:25:05 thor mount.davfs: FUSE_GETATTR:
      Sep 15 19:25:05 thor mount.davfs:   n 0x8ae26e8
      Sep 15 19:25:05 thor mount.davfs: getattr /tmp/
      Sep 15 19:25:05 thor mount.davfs: RET: Success
      Sep 15 19:25:05 thor mount.davfs: SELECT: 1
      Sep 15 19:25:05 thor mount.davfs: FUSE_LOOKUP:
      Sep 15 19:25:05 thor mount.davfs:   p 0x8ae26e8, aaaa.txt
      Sep 15 19:25:05 thor mount.davfs: lookup /tmp/aaaa.txt
      Sep 15 19:25:05 thor mount.davfs: RET: No such file or directory
      Sep 15 19:25:05 thor mount.davfs: SELECT: 1
      Sep 15 19:25:05 thor mount.davfs: FUSE_LOOKUP:
      Sep 15 19:25:05 thor mount.davfs:   p 0x8ae26e8, aaaa.txt
      Sep 15 19:25:05 thor mount.davfs: lookup /tmp/aaaa.txt
      Sep 15 19:25:05 thor mount.davfs: RET: No such file or directory
      Sep 15 19:25:05 thor mount.davfs: SELECT: 1
      Sep 15 19:25:05 thor mount.davfs: FUSE_CREATE:
      Sep 15 19:25:05 thor mount.davfs:   n 0x8ae26e8, f 0100301
      Sep 15 19:25:05 thor mount.davfs:   pid 5066, mode 0100644
      Sep 15 19:25:05 thor mount.davfs: lookup /tmp/aaaa.txt
      Sep 15 19:25:05 thor mount.davfs: create /tmp/aaaa.txt
      Sep 15 19:25:05 thor mount.davfs: new node: 0x8ae26e8->0x8ac3140
      Sep 15 19:25:05 thor mount.davfs: open /tmp/aaaa.txt
      Sep 15 19:25:05 thor mount.davfs:   no update
      Sep 15 19:25:05 thor mount.davfs:   fd 6
      Sep 15 19:25:05 thor mount.davfs: RET: Success
      Sep 15 19:25:05 thor mount.davfs: SELECT: 1
      Sep 15 19:25:05 thor mount.davfs: FUSE_WRITE:
      Sep 15 19:25:05 thor mount.davfs:   n 0x8ac3140, fd 6
      Sep 15 19:25:05 thor mount.davfs:   pid 5066, flags 02
      Sep 15 19:25:05 thor mount.davfs:   size 12, off 0
      Sep 15 19:25:05 thor mount.davfs:   written 12
      Sep 15 19:25:05 thor mount.davfs: RET: Success
      Sep 15 19:25:05 thor mount.davfs: SELECT: 1
      Sep 15 19:25:05 thor mount.davfs: FUSE_FLUSH: ignored
      Sep 15 19:25:05 thor mount.davfs: RET: Success
      Sep 15 19:25:05 thor mount.davfs: SELECT: 1
      Sep 15 19:25:05 thor mount.davfs: FUSE_RELEASE:
      Sep 15 19:25:05 thor mount.davfs:   n 0x8ac3140, f 0100001
      Sep 15 19:25:05 thor mount.davfs:   pid 0, fd 6
      Sep 15 19:25:05 thor mount.davfs:  close /tmp/aaaa.txt
      Sep 15 19:25:05 thor mount.davfs: RET: Success
      Sep 15 19:25:05 thor mount.davfs: tidy: 1 of 3 nodes changed
      Sep 15 19:25:05 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:07 thor mount.davfs: SELECT: 0
      Sep 15 19:25:07 thor mount.davfs: tidy: 1 of 3 nodes changed
      Sep 15 19:25:07 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:07 thor mount.davfs: tidy: /tmp/aaaa.txt
      Sep 15 19:25:07 thor mount.davfs: Running pre_send hooks
      Sep 15 19:25:07 thor mount.davfs: Sending request headers:#012PUT /tmp/aaaa.txt HTTP/1.1#015#012Host: 10.222.222.130:8080#015#012User-Agent: davfs2/1.3.3 neon/0.27.2#015#012Connection: TE#015#012TE: trailers#015#012If-None-Match: *#015#012Content-Length: 12#015#012Expect: 100-continue#015#012Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx#015#012#015
      Sep 15 19:25:07 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:25:07 thor mount.davfs: Request sent; retry is 1.
      Sep 15 19:25:07 thor mount.davfs: Aborted request (-3): Could not read status line
      Sep 15 19:25:07 thor mount.davfs: Closing connection.
      Sep 15 19:25:07 thor mount.davfs: Connection closed.
      Sep 15 19:25:07 thor mount.davfs: Persistent connection timed out, retrying.
      Sep 15 19:25:07 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:25:07 thor mount.davfs: Connecting to 10.222.222.130
      Sep 15 19:25:07 thor mount.davfs: Request sent; retry is 0.
      Sep 15 19:25:07 thor mount.davfs: [status-line] < HTTP/1.1 417 Expectation Failed#015
      Sep 15 19:25:07 thor mount.davfs: [hdr] Connection: close#015
      Sep 15 19:25:07 thor mount.davfs: Header Name: [connection], Value: [close]
      Sep 15 19:25:07 thor mount.davfs: [hdr] Content-Length: 0#015
      Sep 15 19:25:07 thor mount.davfs: Header Name: [content-length], Value: [0]
      Sep 15 19:25:07 thor mount.davfs: [hdr] Date: Mon, 15 Sep 2008 23:25:06 GMT#015
      Sep 15 19:25:07 thor mount.davfs: Header Name: [date], Value: [Mon, 15 Sep 2008 23:25:06 GMT]
      Sep 15 19:25:07 thor mount.davfs: [hdr] Server: lighttpd/1.4.19 with Avatron extensions#015
      Sep 15 19:25:07 thor mount.davfs: Header Name: [server], Value: [lighttpd/1.4.19 with Avatron extensions]
      Sep 15 19:25:07 thor mount.davfs: [hdr] #015
      Sep 15 19:25:07 thor mount.davfs: End of headers.
      Sep 15 19:25:07 thor mount.davfs: Running post_headers hooks
      Sep 15 19:25:07 thor mount.davfs: Running post_send hooks
      Sep 15 19:25:07 thor mount.davfs: Closing connection.
      Sep 15 19:25:07 thor mount.davfs: Connection closed.
      Sep 15 19:25:07 thor mount.davfs: Request ends, status 417 class 4xx, error line:#012417 Expectation Failed
      Sep 15 19:25:07 thor mount.davfs: Running destroy hooks.
      Sep 15 19:25:07 thor mount.davfs: Request ends.
      Sep 15 19:25:07 thor mount.davfs: tidy: neon error
      Sep 15 19:25:07 thor mount.davfs:       417 Expectation Failed
      Sep 15 19:25:08 thor mount.davfs: SELECT: 0
      Sep 15 19:25:08 thor mount.davfs: tidy: 1 of 3 nodes changed
      Sep 15 19:25:08 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:08 thor mount.davfs: tidy: /tmp/aaaa.txt
      Sep 15 19:25:08 thor mount.davfs: Running pre_send hooks
      Sep 15 19:25:08 thor mount.davfs: Sending request headers:#012PUT /tmp/aaaa.txt HTTP/1.1#015#012Host: 10.222.222.130:8080#015#012User-Agent: davfs2/1.3.3 neon/0.27.2#015#012Connection: TE#015#012TE: trailers#015#012If-None-Match: *#015#012Content-Length: 12#015#012Expect: 100-continue#015#012Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx#015#012#015
      Sep 15 19:25:08 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:25:08 thor mount.davfs: Connecting to 10.222.222.130
      Sep 15 19:25:08 thor mount.davfs: Request sent; retry is 0.
      Sep 15 19:25:08 thor mount.davfs: [status-line] < HTTP/1.1 417 Expectation Failed#015
      Sep 15 19:25:08 thor mount.davfs: [hdr] Connection: close#015
      Sep 15 19:25:08 thor mount.davfs: Header Name: [connection], Value: [close]
      Sep 15 19:25:08 thor mount.davfs: [hdr] Content-Length: 0#015
      Sep 15 19:25:08 thor mount.davfs: Header Name: [content-length], Value: [0]
      Sep 15 19:25:08 thor mount.davfs: [hdr] Date: Mon, 15 Sep 2008 23:25:08 GMT#015
      Sep 15 19:25:08 thor mount.davfs: Header Name: [date], Value: [Mon, 15 Sep 2008 23:25:08 GMT]
      Sep 15 19:25:08 thor mount.davfs: [hdr] Server: lighttpd/1.4.19 with Avatron extensions#015
      Sep 15 19:25:08 thor mount.davfs: Header Name: [server], Value: [lighttpd/1.4.19 with Avatron extensions]
      Sep 15 19:25:08 thor mount.davfs: [hdr] #015
      Sep 15 19:25:08 thor mount.davfs: End of headers.
      Sep 15 19:25:08 thor mount.davfs: Running post_headers hooks
      Sep 15 19:25:08 thor mount.davfs: Running post_send hooks
      Sep 15 19:25:08 thor mount.davfs: Closing connection.
      Sep 15 19:25:08 thor mount.davfs: Connection closed.
      Sep 15 19:25:08 thor mount.davfs: Request ends, status 417 class 4xx, error line:#012417 Expectation Failed
      Sep 15 19:25:08 thor mount.davfs: Running destroy hooks.
      Sep 15 19:25:08 thor mount.davfs: Request ends.
      Sep 15 19:25:08 thor mount.davfs: tidy: neon error
      Sep 15 19:25:08 thor mount.davfs:       417 Expectation Failed
      Sep 15 19:25:09 thor mount.davfs: SELECT: 0
      Sep 15 19:25:09 thor mount.davfs: tidy: 1 of 3 nodes changed
      Sep 15 19:25:09 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:09 thor mount.davfs: tidy: /tmp/aaaa.txt
      Sep 15 19:25:09 thor mount.davfs: Running pre_send hooks
      Sep 15 19:25:09 thor mount.davfs: Sending request headers:#012PUT /tmp/aaaa.txt HTTP/1.1#015#012Host: 10.222.222.130:8080#015#012User-Agent: davfs2/1.3.3 neon/0.27.2#015#012Connection: TE#015#012TE: trailers#015#012If-None-Match: *#015#012Content-Length: 12#015#012Expect: 100-continue#015#012Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx#015#012#015
      Sep 15 19:25:09 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:25:09 thor mount.davfs: Connecting to 10.222.222.130
      Sep 15 19:25:09 thor mount.davfs: Request sent; retry is 0.
      Sep 15 19:25:09 thor mount.davfs: [status-line] < HTTP/1.1 417 Expectation Failed#015
      Sep 15 19:25:09 thor mount.davfs: [hdr] Connection: close#015
      Sep 15 19:25:09 thor mount.davfs: Header Name: [connection], Value: [close]
      Sep 15 19:25:09 thor mount.davfs: [hdr] Content-Length: 0#015
      Sep 15 19:25:09 thor mount.davfs: Header Name: [content-length], Value: [0]
      Sep 15 19:25:09 thor mount.davfs: [hdr] Date: Mon, 15 Sep 2008 23:25:09 GMT#015
      Sep 15 19:25:09 thor mount.davfs: Header Name: [date], Value: [Mon, 15 Sep 2008 23:25:09 GMT]
      Sep 15 19:25:09 thor mount.davfs: [hdr] Server: lighttpd/1.4.19 with Avatron extensions#015
      Sep 15 19:25:09 thor mount.davfs: Header Name: [server], Value: [lighttpd/1.4.19 with Avatron extensions]
      Sep 15 19:25:09 thor mount.davfs: [hdr] #015
      Sep 15 19:25:09 thor mount.davfs: End of headers.
      Sep 15 19:25:09 thor mount.davfs: Running post_headers hooks
      Sep 15 19:25:09 thor mount.davfs: Running post_send hooks
      Sep 15 19:25:09 thor mount.davfs: Closing connection.
      Sep 15 19:25:09 thor mount.davfs: Connection closed.
      Sep 15 19:25:09 thor mount.davfs: Request ends, status 417 class 4xx, error line:#012417 Expectation Failed
      Sep 15 19:25:09 thor mount.davfs: Running destroy hooks.
      Sep 15 19:25:09 thor mount.davfs: Request ends.
      Sep 15 19:25:09 thor mount.davfs: tidy: neon error
      Sep 15 19:25:09 thor mount.davfs:       417 Expectation Failed
      Sep 15 19:25:10 thor mount.davfs: SELECT: 0
      Sep 15 19:25:10 thor mount.davfs: tidy: 1 of 3 nodes changed
      Sep 15 19:25:10 thor mount.davfs: cache-size: 0 MiBytes.
      Sep 15 19:25:10 thor mount.davfs: tidy: /tmp/aaaa.txt
      Sep 15 19:25:10 thor mount.davfs: Running pre_send hooks
      Sep 15 19:25:10 thor mount.davfs: Sending request headers:#012PUT /tmp/aaaa.txt HTTP/1.1#015#012Host: 10.222.222.130:8080#015#012User-Agent: davfs2/1.3.3 neon/0.27.2#015#012Connection: TE#015#012TE: trailers#015#012If-None-Match: *#015#012Content-Length: 12#015#012Expect: 100-continue#015#012Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx#015#012#015
      Sep 15 19:25:10 thor mount.davfs: Sending request-line and headers:
      Sep 15 19:25:10 thor mount.davfs: Connecting to 10.222.222.130
      Sep 15 19:25:10 thor mount.davfs: Request sent; retry is 0.
      Sep 15 19:25:11 thor mount.davfs: [status-line] < HTTP/1.1 417 Expectation Failed#015
      Sep 15 19:25:11 thor mount.davfs: [hdr] Connection: close#015
      Sep 15 19:25:11 thor mount.davfs: Header Name: [connection], Value: [close]
      Sep 15 19:25:11 thor mount.davfs: [hdr] Content-Length: 0#015
      Sep 15 19:25:11 thor mount.davfs: Header Name: [content-length], Value: [0]
      Sep 15 19:25:11 thor mount.davfs: [hdr] Date: Mon, 15 Sep 2008 23:25:10 GMT#015
      Sep 15 19:25:11 thor mount.davfs: Header Name: [date], Value: [Mon, 15 Sep 2008 23:25:10 GMT]
      Sep 15 19:25:11 thor mount.davfs: [hdr] Server: lighttpd/1.4.19 with Avatron extensions#015
      Sep 15 19:25:11 thor mount.davfs: Header Name: [server], Value: [lighttpd/1.4.19 with Avatron extensions]
      Sep 15 19:25:11 thor mount.davfs: [hdr] #015
      Sep 15 19:25:11 thor mount.davfs: End of headers.
      Sep 15 19:25:11 thor mount.davfs: Running post_headers hooks
      Sep 15 19:25:11 thor mount.davfs: Running post_send hooks
      Sep 15 19:25:11 thor mount.davfs: Closing connection.
      Sep 15 19:25:11 thor mount.davfs: Connection closed.
      Sep 15 19:25:11 thor mount.davfs: Request ends, status 417 class 4xx, error line:#012417 Expectation Failed
      Sep 15 19:25:11 thor mount.davfs: Running destroy hooks.
      Sep 15 19:25:11 thor mount.davfs: Request ends.
      Sep 15 19:25:11 thor mount.davfs: tidy: neon error
      Sep 15 19:25:11 thor mount.davfs:       417 Expectation Failed

      Hopefully this should help...

       
    • Werner Baumann
      Werner Baumann
      2008-09-16

      Hello Ron,

      the server "lighttpd/1.4.19 with Avatron" does not support the "Expect: 100-continue"-Header. davfs2 uses this header by default. You can turn off this header with option "use_expect100 0" in the davfs2.conf-file. It should work now.

      I am not clear, whether it works with option "if_match_bug 0". But if it does, you should use it.

      Cheers
      Werner

       
    • Werner:

      Thanks for your support and fine work. The option "use_expect100 0" did the trick. I did not need options: "if_match_bug 0" or "precheck 0".

      The "davfs2" file system running on fedora 8 works as expected with the Apple itouch "Air Sharing" application! This is quite amazing - a wireless storage device with the ability to read many different file formats that is essentially OS independent.

      BTW: I am the co-author of the "Network Security Toolkit" (NST) http://www.networksecuritytoolkit.org and will be integrating the dav-linuxfs into the distribution for the next release. I will be building out a NST WUI mount interface for this file system type.

      Thank you again!

      Ron Henderson

       
    • Werner Baumann
      Werner Baumann
      2008-09-17

      Hello Ron,

      glad it works now.
      I should probably make "use_expect100 0" the default behaviour of davfs2. Using this header seems to cause more trouble than the possible benifits (save traffic in some rare cases) are worth.

      Concerning "if_match_bug":
      "If match_bug 0" should really be the default behaviour. It is more effective and more reliable than what davfs2 does by default. The only reason why davfs2 uses the bad choice as default is that the bug is in Apache (except the latest release). If your server is without this bug (it probably is), you should use option "if_match_bug 0".

      One suggestion concering NST: When it comes to analyzing WebDAV-servers, in many cases Cadaver might be more usefull than davfs2. You should include it (probably you already did).

      Cheers
      Werner

       
  • Momo
    Momo
    2011-05-25

    Hello Werner,

    I seem to have a similar problem.
    Server: 1und1.de, https:sd2dav.1und1.de
    davfs 1.4.6 on debian squeeze, kernel 2.6.32-5-686

    Like Ron above, I can mount, display directories and file contents. I cannot copy files onto the remote server or edit remote files, though. More precisely, it first seems I can; files do appear after copying, only to disappear some seconds later. Same with edits; I can edit, save (like, with pico), check the change with cat, but after a while it reverts back to the original remote version of the file. if I unmount and re-mount, things are as if I had never modified anything.

    As for Ron, no problems with cadaver.

    I've tried

    use_locks       0
    use_expect100   0
    if_match_bug    0
    precheck        0
    (is there some possible conflict among the above?)

    but it still doesn't work. User or root, no difference.

    Another very odd fact: everything works fine with the webdav service provided by box.net instead of 1und1.de

    My error log looks similar to Ron's …. the only "error" lines that I get and Ron didn't is
    May 25 23:15:54 moritz-laptop mount.davfs: Request ends, status 412 class 4xx, error line:#012412 Precondition Failed

    the complete log is more than 2000 lines using "debug most". Should I post it?

    I would hugely appreciate some hint or help.

    thanks

    Moritz