search

ovh / svfs

The Swift Virtual File System
Other
374 stars 54 forks source link

Input/output error while initializing a borgbackup repo #105

Closed bosr closed 7 years ago

bosr commented 7 years ago

Hi, thanks for this project!

I'm running into the following errors when trying to use svfs for regular backups (using borgbackup to an OVH PCS container). FYI, I could successfully cp and rsync several small files between my server and the container. I actually had some 408 issues which I'll post later, but the basic configuration should be OK.

Thanks in advance for your help, Cheers

Context

Steps to reproduce this issue :

  1. source your openrc.sh script for the credential envvars to be exported

  2. mount the container with the standard options

    mount -t svfs -o debug,extra_attr,blocksize=4k,container=archives ovh-pcs /mnt/ovh-pcs

    I subsequently tried to remove extra_attr and blocksize=4k options: same results as below.

  3. attempt to initialize a borgbackup repo with the following command

    borg init --encryption=none /mnt/ovh-pcs/archives

Results you expected :

I expected the operation to proceed normally without a nasty Input/output error raising an exception :)

Results you observed :

The borg command fails quickly after launch

borg init --encryption=keyfile /mnt/ovh-pcs/archives
Enter new passphrase:
Enter same passphrase again:
Do you want your passphrase to be displayed for verification? [yN]:
Local Exception.
Traceback (most recent call last):
  File "/usr/lib/python3/dist-packages/borg/archiver.py", line 1684, in main
    exit_code = archiver.run(args)
  File "/usr/lib/python3/dist-packages/borg/archiver.py", line 1621, in run
    return args.func(args)
  File "/usr/lib/python3/dist-packages/borg/archiver.py", line 85, in wrapper
    return method(self, args, repository=repository, **kwargs)
  File "/usr/lib/python3/dist-packages/borg/archiver.py", line 131, in do_init
    manifest.write()
  File "/usr/lib/python3/dist-packages/borg/helpers.py", line 139, in write
    self.repository.put(self.MANIFEST_ID, self.key.encrypt(data))
  File "/usr/lib/python3/dist-packages/borg/repository.py", line 535, in put
    segment, offset = self.io.write_put(id, data)
  File "/usr/lib/python3/dist-packages/borg/repository.py", line 784, in write_put
    fd = self.get_write_fd(raise_full=raise_full)
  File "/usr/lib/python3/dist-packages/borg/repository.py", line 668, in get_write_fd
    sync_dir(os.path.join(self.path, 'data'))
  File "/usr/lib/python3/dist-packages/borg/platform.py", line 10, in sync_dir
    os.fsync(fd)
OSError: [Errno 5] Input/output error

Platform: Linux freenas 3.16.0-4-amd64 #1 SMP Debian 3.16.36-1+deb8u2 (2016-10-19) x86_64
Linux: debian 8.6
Borg: 1.0.7  Python: CPython 3.4.2
PID: 2618  CWD: /root
sys.argv: ['/usr/bin/borg', 'init', '--encryption=keyfile', '/mnt/ovh-pcs/archives']
SSH_ORIGINAL_COMMAND: None

Debug log :

root@nas:~# mount -t svfs -o debug,extra_attr,blocksize=4k,container=archives ovh-pcs /mnt/ovh-pcs
root@nas:~# DEBU[2016-11-07T07:32:36+01:00] Skipping configuration : open : no such file or directory  source=svfs
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x2 Node=0x1 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x2] Getattr valid=1m0s ino=1 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x3 Node=0x1 Uid=0 Gid=0 Pid=2618] "archives"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x3] Lookup 0x2 gen=0 valid=1m0s attr={valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------}  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Open [ID=0x4 Node=0x2 Uid=0 Gid=0 Pid=2618] dir=true fl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x4] Open 0x1 fl=0            source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Read [ID=0x5 Node=0x2 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x5] Read 0                   source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x7 Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Release [ID=0x6 Node=0x2 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly+OpenDirectory+OpenNonblock rfl=0 owner=0x0 source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x6] Release                  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x7] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x8 Node=0x2 Uid=0 Gid=0 Pid=2618] "README"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x8] Lookup error=ENOENT      source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Create [ID=0x9 Node=0x2 Uid=0 Gid=0 Pid=2618] "README" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x9] Create {0x3 gen=0 valid=1m0s attr={valid=1m0s ino=13186341086054391566 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0xa Node=0x3 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0xa] Header error=ENOSYS      source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0xb Node=0x3 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0xb] Header error=ENOSYS      source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Write [ID=0xc Node=0x3 Uid=0 Gid=0 Pid=2618] 0x1 26 @0 fl=WriteLockOwner lock=15002273224907307667 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0xc] Write 26                 source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Flush [ID=0xd Node=0x3 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0xd] Flush                    source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Release [ID=0xe Node=0x3 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0xf Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0xf] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x10 Node=0x2 Uid=0 Gid=0 Pid=2618] "data"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x10] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Mkdir [ID=0x11 Node=0x2 Uid=0 Gid=0 Pid=2618] "data" mode=drwx------ umask=----rwxrwx  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x11] Mkdir 0x4 gen=0 valid=1m0s attr={valid=1m0s ino=12077135400190652796 size=4096 mode=drwx------}source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x12 Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x12] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x13 Node=0x2 Uid=0 Gid=0 Pid=2618] "config"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x13] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Create [ID=0x14 Node=0x2 Uid=0 Gid=0 Pid=2618] "config" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0xe] Release                  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x14] Create {0x5 gen=0 valid=1m0s attr={valid=1m0s ino=6011966394027229074 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0x15 Node=0x5 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x15] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0x16 Node=0x5 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x16] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Write [ID=0x17 Node=0x5 Uid=0 Gid=0 Pid=2618] 0x1 164 @0 fl=WriteLockOwner lock=15002273224907307667 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x17] Write 164               source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Flush [ID=0x18 Node=0x5 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x18] Flush                   source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Release [ID=0x19 Node=0x5 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x1a Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x1a] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x1b Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.exclusive"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x1b] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Mkdir [ID=0x1c Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.exclusive" mode=drwx------ umask=----rwxrwx  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x1c] Mkdir 0x6 gen=0 valid=1m0s attr={valid=1m0s ino=3025830946549282413 size=4096 mode=drwx------}  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x1d Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x1d] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x1e Node=0x6 Uid=0 Gid=0 Pid=2618] "freenas.2618-0"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x19] Release                 source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x1e] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Create [ID=0x1f Node=0x6 Uid=0 Gid=0 Pid=2618] "freenas.2618-0" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x1f] Create {0x7 gen=0 valid=1m0s attr={valid=1m0s ino=9561885602189467838 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0x20 Node=0x7 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x20] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Flush [ID=0x21 Node=0x7 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x21] Flush                   source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Release [ID=0x22 Node=0x7 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x22] Release                 source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x23 Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.roster"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x23] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x24 Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.roster"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x24] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Lookup [ID=0x25 Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.roster"  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x25] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Create [ID=0x26 Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.roster" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x26] Create {0x8 gen=0 valid=1m0s attr={valid=1m0s ino=2329685365225963220 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0x27 Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x27] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0x28 Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x28] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Write [ID=0x29 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 37 @0 fl=WriteLockOwner lock=15002273224907307667 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x29] Write 37                source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Flush [ID=0x2a Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x2a] Flush                   source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Release [ID=0x2b Node=0x8 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x2c Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x2c] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x2d Node=0x5 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x2d] Getattr valid=1m0s ino=6011966394027229074 size=164 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Open [ID=0x2e Node=0x5 Uid=0 Gid=0 Pid=2618] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x2e] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0x2f Node=0x5 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x2f] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:20+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- ID=0x30 Node=0x5 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x30] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Read [ID=0x31 Node=0x5 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x31] Read 4096               source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Getattr [ID=0x32 Node=0x5 Uid=0 Gid=0 Pid=2618] 0x1 fl=GetattrFh  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x32] Getattr valid=1m0s ino=6011966394027229074 size=164 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Flush [ID=0x33 Node=0x5 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x33] Flush                   source=fuse
DEBU[2016-11-07T07:33:20+01:00] <- Release [ID=0x34 Node=0x5 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:20+01:00] -> [ID=0x34] Release                 source=fuse
DEBU[2016-11-07T07:33:21+01:00] -> [ID=0x2b] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x35 Node=0x2 Uid=0 Gid=0 Pid=2618] dir=true fl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x35] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x36 Node=0x2 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x36] Read 176                source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x37 Node=0x2 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0xb0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x37] Read 0                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x38 Node=0x2 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly+OpenDirectory+OpenNonblock rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x38] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x39 Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x39] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x3a Node=0x4 Uid=0 Gid=0 Pid=2618] dir=true fl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x3a] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x3b Node=0x4 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x3b] Read 0                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x3c Node=0x4 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly+OpenDirectory+OpenNonblock rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x3c] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x3d Node=0x2 Uid=0 Gid=0 Pid=2618] dir=true fl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x3d] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x3e Node=0x2 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x3e] Read 176                source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x3f Node=0x2 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0xb0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x3f] Read 0                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x40 Node=0x2 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly+OpenDirectory+OpenNonblock rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x40] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x41 Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x41] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x42 Node=0x6 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x42] Getattr valid=1m0s ino=3025830946549282413 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x43 Node=0x4 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x43] Getattr valid=1m0s ino=12077135400190652796 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Lookup [ID=0x44 Node=0x4 Uid=0 Gid=0 Pid=2618] "0"  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x44] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Lookup [ID=0x45 Node=0x4 Uid=0 Gid=0 Pid=2618] "0"  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x45] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Mkdir [ID=0x46 Node=0x4 Uid=0 Gid=0 Pid=2618] "0" mode=drwx------ umask=----rwxrwx  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x46] Mkdir 0x9 gen=0 valid=1m0s attr={valid=1m0s ino=1571364423535387257 size=4096 mode=drwx------}  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x47 Node=0x4 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x47] Getattr valid=1m0s ino=12077135400190652796 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x48 Node=0x4 Uid=0 Gid=0 Pid=2618] dir=true fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x48] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Fsync [ID=0x49 Node=0x4 Uid=0 Gid=0 Pid=2618] Handle 0x1 Flags 0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x49] Fsync error=EIO         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x4a Node=0x4 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x4a] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x4b Node=0x8 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x4b] Getattr valid=1m0s ino=2329685365225963220 size=37 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x4c Node=0x8 Uid=0 Gid=0 Pid=2618] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x4c] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x4d Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x4d] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x4e Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x4e] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x4f Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x4f] Read 4096               source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x50 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=GetattrFh  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x50] Getattr valid=1m0s ino=2329685365225963220 size=37 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Flush [ID=0x51 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x51] Flush                   source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x52 Node=0x8 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x52] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x53 Node=0x8 Uid=0 Gid=0 Pid=2618] dir=false fl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x53] Open 0x1 fl=OpenDirectIO+OpenNonSeekable  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Setattr [ID=0x54 Node=0x8 Uid=0 Gid=0 Pid=2618] size=0 handle=INVALID-0x0 lockowner  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x54] Setattr valid=1m0s ino=2329685365225963220 size=0 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x55 Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x55] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x56 Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x56] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Write [ID=0x57 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 17 @0 fl=WriteLockOwner lock=15002273224907307667 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x57] Write 17                source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Flush [ID=0x58 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x58] Flush                   source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x59 Node=0x8 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x5a Node=0x8 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x5a] Getattr valid=1m0s ino=2329685365225963220 size=17 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x5b Node=0x8 Uid=0 Gid=0 Pid=2618] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x5b] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x5c Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x5c] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x5d Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x5d] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x5e Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x5e] Read 4096               source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x5f Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=GetattrFh  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x5f] Getattr valid=1m0s ino=2329685365225963220 size=17 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Flush [ID=0x60 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x60] Flush                   source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x61 Node=0x8 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x61] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Open [ID=0x62 Node=0x8 Uid=0 Gid=0 Pid=2618] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x62] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x63 Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x63] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] No opcode 39                         source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- ID=0x64 Node=0x8 Uid=0 Gid=0 Pid=2618  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x64] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Read [ID=0x65 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x65] Read 4096               source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x66 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=GetattrFh  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x66] Getattr valid=1m0s ino=2329685365225963220 size=17 mode=-rwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Flush [ID=0x67 Node=0x8 Uid=0 Gid=0 Pid=2618] 0x1 fl=0x0 lk=0xd032c80339f43a93  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x67] Flush                   source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Release [ID=0x68 Node=0x8 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x68] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Remove [ID=0x69 Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.roster" dir=false  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x69] Remove                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Getattr [ID=0x6a Node=0x2 Uid=0 Gid=0 Pid=2618] 0x0 fl=0  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x6a] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Remove [ID=0x6b Node=0x6 Uid=0 Gid=0 Pid=2618] "freenas.2618-0" dir=false  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x6b] Remove                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Remove [ID=0x6c Node=0x2 Uid=0 Gid=0 Pid=2618] "lock.exclusive" dir=true  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Forget [ID=0x6d Node=0x7 Uid=0 Gid=0 Pid=0] 1  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x6d] Forget                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x59] Release                 source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Forget [ID=0x6e Node=0x8 Uid=0 Gid=0 Pid=0] 1  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x6e] Forget                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x6c] Remove                  source=fuse
DEBU[2016-11-07T07:33:43+01:00] <- Forget [ID=0x6f Node=0x6 Uid=0 Gid=0 Pid=0] 1  source=fuse
DEBU[2016-11-07T07:33:43+01:00] -> [ID=0x6f] Forget                  source=fuse

Additional information :

xlucas commented 7 years ago

Hello !

There's no real reason to call fsync(2) or something similar after writing data to svfs since the way data or metadata is written is already synchronous.

For this reason, this system call is not handled.

bosr commented 7 years ago

Thanks Xavier,

I'll report it upstream.

bosr commented 7 years ago

Hmm, would you consider to handle the call to fsync(2) as a no-op (maybe through a mount option)? I'm afraid to have to report to each and every upstream that rightfully calls fsync :)

xlucas commented 7 years ago

@bosr That was actually my plan already :)

It will be available in the next release.

bosr commented 7 years ago

perfect, thanks!

bosr commented 7 years ago

Xavier,

I found a way to build a local release of master (installing the dependencies then rake release[0.8.3]), then I retried to initialize my borg repo. I have the same fsync error as before. Maybe I did not build it correctly, or maybe something is still missing in svfs?

Here is the debug output:

root@jessie:~# DEBU[2016-11-07T13:04:45Z] Skipping configuration : open : no such file or directory  source=svfs
DEBU[2016-11-07T13:05:12Z] <- Getattr [ID=0x2 Node=0x1 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x2] Getattr valid=1m0s ino=1 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Lookup [ID=0x3 Node=0x1 Uid=0 Gid=0 Pid=9719] "archives"  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x3] Lookup error=ENOENT      source=fuse
DEBU[2016-11-07T13:05:12Z] <- Lookup [ID=0x4 Node=0x1 Uid=0 Gid=0 Pid=9719] "archives"  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x4] Lookup error=ENOENT      source=fuse
DEBU[2016-11-07T13:05:12Z] <- Lookup [ID=0x5 Node=0x1 Uid=0 Gid=0 Pid=9719] "archives"  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x5] Lookup error=ENOENT      source=fuse
DEBU[2016-11-07T13:05:12Z] <- Mkdir [ID=0x6 Node=0x1 Uid=0 Gid=0 Pid=9719] "archives" mode=drwx------ umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x6] Mkdir 0x2 gen=0 valid=1m0s attr={valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------}  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Getattr [ID=0x7 Node=0x1 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x7] Getattr valid=1m0s ino=1 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Lookup [ID=0x8 Node=0x2 Uid=0 Gid=0 Pid=9719] "README"  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x8] Lookup error=ENOENT      source=fuse
DEBU[2016-11-07T13:05:12Z] <- Create [ID=0x9 Node=0x2 Uid=0 Gid=0 Pid=9719] "README" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x9] Create {0x3 gen=0 valid=1m0s attr={valid=1m0s ino=13186341086054391566 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T13:05:12Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:12Z] <- ID=0xa Node=0x3 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0xa] Header error=ENOSYS      source=fuse
DEBU[2016-11-07T13:05:12Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:12Z] <- ID=0xb Node=0x3 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0xb] Header error=ENOSYS      source=fuse
DEBU[2016-11-07T13:05:12Z] <- Write [ID=0xc Node=0x3 Uid=0 Gid=0 Pid=9719] 0x1 26 @0 fl=WriteLockOwner lock=604325201252513156 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0xc] Write 26                 source=fuse
DEBU[2016-11-07T13:05:12Z] <- Flush [ID=0xd Node=0x3 Uid=0 Gid=0 Pid=9719] 0x1 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0xd] Flush                    source=fuse
DEBU[2016-11-07T13:05:12Z] <- Release [ID=0xe Node=0x3 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Getattr [ID=0xf Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0xf] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Lookup [ID=0x10 Node=0x2 Uid=0 Gid=0 Pid=9719] "data"  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x10] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:12Z] <- Mkdir [ID=0x11 Node=0x2 Uid=0 Gid=0 Pid=9719] "data" mode=drwx------ umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x11] Mkdir 0x4 gen=0 valid=1m0s attr={valid=1m0s ino=12077135400190652796 size=4096 mode=drwx------}  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Getattr [ID=0x12 Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x12] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Lookup [ID=0x13 Node=0x2 Uid=0 Gid=0 Pid=9719] "config"  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x13] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:12Z] <- Create [ID=0x14 Node=0x2 Uid=0 Gid=0 Pid=9719] "config" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0xe] Release                  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x14] Create {0x5 gen=0 valid=1m0s attr={valid=1m0s ino=6011966394027229074 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T13:05:12Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:12Z] <- ID=0x15 Node=0x5 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x15] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:12Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:12Z] <- ID=0x16 Node=0x5 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x16] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:12Z] <- Write [ID=0x17 Node=0x5 Uid=0 Gid=0 Pid=9719] 0x1 164 @0 fl=WriteLockOwner lock=604325201252513156 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x17] Write 164               source=fuse
DEBU[2016-11-07T13:05:12Z] <- Flush [ID=0x18 Node=0x5 Uid=0 Gid=0 Pid=9719] 0x1 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x18] Flush                   source=fuse
DEBU[2016-11-07T13:05:12Z] <- Getattr [ID=0x1a Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x1a] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Lookup [ID=0x1b Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.exclusive"  source=fuse
DEBU[2016-11-07T13:05:12Z] -> [ID=0x1b] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:12Z] <- Mkdir [ID=0x1c Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.exclusive" mode=drwx------ umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:12Z] <- Release [ID=0x19 Node=0x5 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x1c] Mkdir 0x6 gen=0 valid=1m0s attr={valid=1m0s ino=3025830946549282413 size=4096 mode=drwx------}  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x1d Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x1d] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Lookup [ID=0x1e Node=0x6 Uid=0 Gid=0 Pid=9719] "jessie.9719-0"  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x1e] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Create [ID=0x1f Node=0x6 Uid=0 Gid=0 Pid=9719] "jessie.9719-0" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x1f] Create {0x7 gen=0 valid=1m0s attr={valid=1m0s ino=6655585270577227538 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x20 Node=0x7 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x20] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Flush [ID=0x21 Node=0x7 Uid=0 Gid=0 Pid=9719] 0x1 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x21] Flush                   source=fuse
DEBU[2016-11-07T13:05:13Z] <- Lookup [ID=0x23 Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.roster"  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x23] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Lookup [ID=0x24 Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.roster"  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x24] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Lookup [ID=0x25 Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.roster"  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x25] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Create [ID=0x26 Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.roster" fl=OpenWriteOnly+OpenCreate+OpenTruncate mode=-rw------- umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x22 Node=0x7 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x22] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x19] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x26] Create {0x8 gen=0 valid=1m0s attr={valid=1m0s ino=2329685365225963220 size=0 mode=-rwx------}} {0x1 fl=OpenDirectIO+OpenNonSeekable}  source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x27 Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x27] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x28 Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x28] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Write [ID=0x29 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x1 36 @0 fl=WriteLockOwner lock=604325201252513156 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x29] Write 36                source=fuse
DEBU[2016-11-07T13:05:13Z] <- Flush [ID=0x2a Node=0x8 Uid=0 Gid=0 Pid=9719] 0x1 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x2a] Flush                   source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x2c Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x2c] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x2d Node=0x5 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x2d] Getattr valid=1m0s ino=6011966394027229074 size=164 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x2e Node=0x5 Uid=0 Gid=0 Pid=9719] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x2b Node=0x8 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x2e] Open 0x1 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x2f Node=0x5 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x2f] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x30 Node=0x5 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x30] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x31 Node=0x5 Uid=0 Gid=0 Pid=9719] 0x1 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x31] Read 4096               source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x32 Node=0x5 Uid=0 Gid=0 Pid=9719] 0x1 fl=GetattrFh  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x32] Getattr valid=1m0s ino=6011966394027229074 size=164 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Flush [ID=0x33 Node=0x5 Uid=0 Gid=0 Pid=9719] 0x1 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x33] Flush                   source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x35 Node=0x2 Uid=0 Gid=0 Pid=9719] dir=true fl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x35] Open 0x2 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x36 Node=0x2 Uid=0 Gid=0 Pid=9719] 0x2 4096 @0x0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x36] Read 176                source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x37 Node=0x2 Uid=0 Gid=0 Pid=9719] 0x2 4096 @0xb0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x37] Read 0                  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x39 Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x39] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x3a Node=0x4 Uid=0 Gid=0 Pid=9719] dir=true fl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x3a] Open 0x3 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x3b Node=0x4 Uid=0 Gid=0 Pid=9719] 0x3 4096 @0x0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x34 Node=0x5 Uid=0 Gid=0 Pid=0] 0x1 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x34] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x38 Node=0x2 Uid=0 Gid=0 Pid=0] 0x2 fl=OpenReadOnly+OpenDirectory+OpenNonblock rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x38] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x3b] Read 0                  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x3d Node=0x2 Uid=0 Gid=0 Pid=9719] dir=true fl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x3d] Open 0x2 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x3e Node=0x2 Uid=0 Gid=0 Pid=9719] 0x2 4096 @0x0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x3e] Read 176                source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x3f Node=0x2 Uid=0 Gid=0 Pid=9719] 0x2 4096 @0xb0 dir=true fl=0 lock=0 ffl=OpenReadOnly+OpenDirectory+OpenNonblock  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x3f] Read 0                  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x41 Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x41] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x42 Node=0x6 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x42] Getattr valid=1m0s ino=3025830946549282413 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x43 Node=0x4 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x43] Getattr valid=1m0s ino=12077135400190652796 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Lookup [ID=0x44 Node=0x4 Uid=0 Gid=0 Pid=9719] "0"  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x44] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Lookup [ID=0x45 Node=0x4 Uid=0 Gid=0 Pid=9719] "0"  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x45] Lookup error=ENOENT     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Mkdir [ID=0x46 Node=0x4 Uid=0 Gid=0 Pid=9719] "0" mode=drwx------ umask=----rwxrwx  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x3c Node=0x4 Uid=0 Gid=0 Pid=0] 0x3 fl=OpenReadOnly+OpenDirectory+OpenNonblock rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x3c] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x40 Node=0x2 Uid=0 Gid=0 Pid=0] 0x2 fl=OpenReadOnly+OpenDirectory+OpenNonblock rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x40] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x2b] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x46] Mkdir 0x9 gen=0 valid=1m0s attr={valid=1m0s ino=1571364423535387257 size=4096 mode=drwx------}  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x47 Node=0x4 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x47] Getattr valid=1m0s ino=12077135400190652796 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x48 Node=0x4 Uid=0 Gid=0 Pid=9719] dir=true fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x48] Open 0x2 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] <- Fsync [ID=0x49 Node=0x4 Uid=0 Gid=0 Pid=9719] Handle 0x2 Flags 0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x49] Fsync error=EIO         source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x4b Node=0x8 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x4b] Getattr valid=1m0s ino=2329685365225963220 size=36 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x4c Node=0x8 Uid=0 Gid=0 Pid=9719] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x4a Node=0x4 Uid=0 Gid=0 Pid=0] 0x2 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x4a] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x4c] Open 0x2 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x4d Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x4d] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x4e Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x4e] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x4f Node=0x8 Uid=0 Gid=0 Pid=9719] 0x2 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x4f] Read 4096               source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x50 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x2 fl=GetattrFh  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x50] Getattr valid=1m0s ino=2329685365225963220 size=36 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Flush [ID=0x51 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x2 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x51] Flush                   source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x53 Node=0x8 Uid=0 Gid=0 Pid=9719] dir=false fl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x53] Open 0x3 fl=OpenDirectIO+OpenNonSeekable  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Setattr [ID=0x54 Node=0x8 Uid=0 Gid=0 Pid=9719] size=0 handle=INVALID-0x0 lockowner  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x52 Node=0x8 Uid=0 Gid=0 Pid=0] 0x2 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x52] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x54] Setattr valid=1m0s ino=2329685365225963220 size=0 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x55 Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x55] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x56 Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x56] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Write [ID=0x57 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 17 @0 fl=WriteLockOwner lock=604325201252513156 ffl=OpenWriteOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x57] Write 17                source=fuse
DEBU[2016-11-07T13:05:13Z] <- Flush [ID=0x58 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x58] Flush                   source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x5a Node=0x8 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x5a] Getattr valid=1m0s ino=2329685365225963220 size=17 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x5b Node=0x8 Uid=0 Gid=0 Pid=9719] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x59 Node=0x8 Uid=0 Gid=0 Pid=0] 0x3 fl=OpenWriteOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x5b] Open 0x3 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x5c Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x5c] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x5d Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x5d] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x5e Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x5e] Read 4096               source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x5f Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 fl=GetattrFh  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x5f] Getattr valid=1m0s ino=2329685365225963220 size=17 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Flush [ID=0x60 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x60] Flush                   source=fuse
DEBU[2016-11-07T13:05:13Z] <- Open [ID=0x62 Node=0x8 Uid=0 Gid=0 Pid=9719] dir=false fl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x61 Node=0x8 Uid=0 Gid=0 Pid=0] 0x3 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x61] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x62] Open 0x3 fl=0           source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x63 Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x63] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] No opcode 39                         source=fuse
DEBU[2016-11-07T13:05:13Z] <- ID=0x64 Node=0x8 Uid=0 Gid=0 Pid=9719  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x64] Header error=ENOSYS     source=fuse
DEBU[2016-11-07T13:05:13Z] <- Read [ID=0x65 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 4096 @0x0 dir=false fl=0 lock=0 ffl=OpenReadOnly  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x65] Read 4096               source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x66 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 fl=GetattrFh  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x66] Getattr valid=1m0s ino=2329685365225963220 size=17 mode=-rwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Flush [ID=0x67 Node=0x8 Uid=0 Gid=0 Pid=9719] 0x3 fl=0x0 lk=0x862fe919578f584  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x67] Flush                   source=fuse
DEBU[2016-11-07T13:05:13Z] <- Remove [ID=0x69 Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.roster" dir=false  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Release [ID=0x68 Node=0x8 Uid=0 Gid=0 Pid=0] 0x3 fl=OpenReadOnly rfl=0 owner=0x0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x68] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x69] Remove                  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Getattr [ID=0x6a Node=0x2 Uid=0 Gid=0 Pid=9719] 0x0 fl=0  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x6a] Getattr valid=1m0s ino=1613058114198683883 size=4096 mode=drwx------  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Remove [ID=0x6b Node=0x6 Uid=0 Gid=0 Pid=9719] "jessie.9719-0" dir=false  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x59] Release                 source=fuse
DEBU[2016-11-07T13:05:13Z] <- Forget [ID=0x6c Node=0x8 Uid=0 Gid=0 Pid=0] 1  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x6c] Forget                  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x6b] Remove                  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Forget [ID=0x6e Node=0x7 Uid=0 Gid=0 Pid=0] 1  source=fuse
DEBU[2016-11-07T13:05:13Z] -> [ID=0x6e] Forget                  source=fuse
DEBU[2016-11-07T13:05:13Z] <- Remove [ID=0x6d Node=0x2 Uid=0 Gid=0 Pid=9719] "lock.exclusive" dir=true  source=fuse
DEBU[2016-11-07T13:05:14Z] -> [ID=0x6d] Remove                  source=fuse
DEBU[2016-11-07T13:05:14Z] <- Forget [ID=0x6f Node=0x6 Uid=0 Gid=0 Pid=0] 1  source=fuse
DEBU[2016-11-07T13:05:14Z] -> [ID=0x6f] Forget                  source=fuse

Sorry I can't help you reading these logs

xlucas commented 7 years ago

I can confirm fsync(2) works with the master branch.

Our release process cannot be used by someone else than a repo admin since it needs interacting with branches and the gihub API. Also it requires some extra tools for mac packaging.

So rake release will likely not be usable by someone else.

You can go install github.com/ovh/svfs after pulling the latest commits from master and use $GOPATH/bin/svfs as a replacement for your /usr/local/bin/svfs.

bosr commented 7 years ago

Thanks!

danielfett commented 7 years ago

I'm still getting this error, latest svfs version and latest borg version (also tested some older releases). Could the problem be that borg calls fsync on a directory, not on a file?

crequill commented 6 years ago

Same problem here with svfs 0.9.1 on archlinux with hubic.