So the locking problem is back
I try to sync on 2 x Macs and 2 x Synology, where all have the latest Resilio Sync version and identical Date/Time-settings and NTP-connections.
There are a couple of files which won't sync. If I delete these files and re-upload (even under a different name), the problem appears again. But it seems to be dependent on: from WHERE I upload the file.
Both Macs are synching fine!
The log on Synology 1 sais:
[20180301 08:13:43.528] Entry "/volume2/OFFICE 2016/Testfile.csv" was updated, current state: pvi[type:1 s:120203 mt:1519831315 h:], expected: pvi[NULL]
[20180301 08:13:43.528] FC[AD41]: fs event for entry "/volume2/OFFICE 2016/Testfile.csv" t:1519831315 s:120203 id:2307:108437560 type:1
[20180301 08:13:43.529] TorrentController: (reading) failed to open file "/volume2/OFFICE 2016/Testfile.csv" - 13
[20180301 08:13:43.529] JOURNAL[AD41]: cancelling new entry job for "/volume2/OFFICE 2016/Testfile.csv" - failed to init tf
[20180301 08:13:43.529] JOURNAL[AD41]: repost fs event for path "/volume2/OFFICE 2016/Testfile.csv" - torrent creation aborted
[20180301 08:13:53.093] Entry "/volume2/OFFICE 2016/Testfile.csv" was updated, current state: pvi[type:1 s:120203 mt:1519831315 h:], expected: pvi[NULL]
[20180301 08:13:53.093] FC[AD41]: fs event for entry "/volume2/OFFICE 2016/Testfile.csv" t:1519831315 s:120203 id:2307:108437560 type:1
[20180301 08:13:53.093] TorrentController: (reading) failed to open file "/volume2/OFFICE 2016/Testfile.csv" - 13
[20180301 08:13:53.093] JOURNAL[AD41]: cancelling new entry job for "/volume2/OFFICE 2016/Testfile.csv" - failed to init tf
[20180301 08:13:53.113] JOURNAL[AD41]: repost fs event for path "/volume2/OFFICE 2016/Testfile.csv" - torrent creation aborted
The log on Synology 2 (btrf):
SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:49.594] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:49.594] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:49.594] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:49.594] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:49.594] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:49.594] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:49.594] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:49.594] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:49.594] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:49.594] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:49.595] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:49.595] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:49.595] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:49.595] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:49.595] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:49.595] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:49.595] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:49.595] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:49.595] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:49.595] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:49.595] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:49.595] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:49.595] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:49.595] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:49.595] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:49.595] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:49.596] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:49.596] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:49.596] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:50.586] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:50.586] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:50.586] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:50.586] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:50.586] SF[AD41] [B898]: Going to connect to peer 192.168.61.12:59012 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:50.586] AddToDownloading SP:[B898] index = 5083
[20180301 13:38:50.586] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:1/30050 limit:13 speed:9641
[20180301 13:38:50.586] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:0/0 limit:15 speed:9052
[20180301 13:38:50.587] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:50.587] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:RECHECK error: meta:1 conns:1 io:1
[20180301 13:38:50.587] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:50.587] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:50.587] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:50.588] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] got login - peer:1032389757D1C1C142BCD8E01E9248FDCEE4B898 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:50.589] AddToDownloading SP:[B898] index = 5083
[20180301 13:38:50.589] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] 192.168.61.12:59012: did not pick any blocks. blocking peer temporarily
[20180301 13:38:50.591] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:50.592] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:50.592] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:50.592] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:50.592] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:50.592] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:50.592] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:50.592] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:50.593] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:50.593] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:50.593] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:50.593] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:50.593] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:50.593] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:50.593] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:50.593] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:50.593] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:50.593] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:50.594] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:50.594] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:50.594] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 1
[20180301 13:38:50.594] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:50.594] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:50.594] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:50.594] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 0
[20180301 13:38:51.591] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:15 speed:9641
[20180301 13:38:51.591] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:0/0 limit:15 speed:9052
[20180301 13:38:51.591] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnPeerConnectionChanged
[20180301 13:38:51.591] SF[AD41]: UpdatePeersStat
[20180301 13:38:51.592] SF[AD41] [B898]: up:8396603 down:30050
[20180301 13:38:51.593] SF[AD41] [BA29]: up:0 down:30050
[20180301 13:38:51.593] ScheduledTask: "UpdatePeersStat" invoked: timer, reason:UpdatePeerFileInfo
[20180301 13:38:51.593] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:51.593] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:52.618] SyncFolderNotify: SyncFolderNotify: "ID", event = "IN_CLOSE_WRITE"
[20180301 13:38:52.618] [OnNotifyFileChange] "/volume1/OFFICE/.sync/ID"
[20180301 13:38:52.618] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:52.618] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:52.618] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:52.618] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:52.618] SF[AD41] [BA29]: Going to connect to peer 192.168.61.150:51998 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:52.618] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:52.618] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:1/30050 limit:15 speed:9052
[20180301 13:38:52.618] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:15 speed:9641
[20180301 13:38:52.618] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:52.618] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:RECHECK error: meta:1 conns:1 io:1
[20180301 13:38:52.618] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:52.619] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:52.619] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:52.621] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] got login - peer:10CD27075657E2E12B4A121C7E1C0671E698BA29 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:52.621] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:52.622] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] 192.168.61.150:51998: did not pick any blocks. blocking peer temporarily
[20180301 13:38:52.622] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:52.622] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:52.622] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:52.622] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:52.623] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:52.623] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:52.623] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:52.623] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:52.623] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:52.623] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:52.623] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:52.623] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:52.623] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:52.623] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:52.623] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:52.623] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:52.623] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:52.623] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:52.623] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:52.624] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:52.624] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:52.624] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:52.624] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:52.624] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:52.624] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:52.624] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:52.624] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:52.624] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:52.624] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:52.624] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:53.621] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:53.622] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:53.622] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:53.622] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:53.622] SF[AD41] [B898]: Going to connect to peer 192.168.61.12:59012 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:53.622] AddToDownloading SP:[B898] index = 5083
[20180301 13:38:53.622] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:1/30050 limit:15 speed:9641
[20180301 13:38:53.622] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:53.622] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:0/0 limit:9 speed:9052
[20180301 13:38:53.622] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:53.622] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:53.622] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:DOWNLOAD error: meta:1 conns:1 io:0
[20180301 13:38:53.623] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:53.627] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] got login - peer:1032389757D1C1C142BCD8E01E9248FDCEE4B898 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:53.628] AddToDownloading SP:[B898] index = 5083
[20180301 13:38:53.628] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] 192.168.61.12:59012: did not pick any blocks. blocking peer temporarily
[20180301 13:38:53.632] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:53.632] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:53.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:53.632] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:53.632] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:53.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:53.632] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:53.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:53.632] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:53.632] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:53.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:53.633] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:53.633] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:53.633] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:53.633] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:53.633] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:53.634] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:53.634] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:53.634] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:53.634] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:53.634] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:53.634] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:53.634] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:53.634] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:53.634] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:53.634] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:53.634] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:53.635] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:53.635] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:53.635] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:54.625] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:54.625] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:54.626] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:54.626] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:54.626] SF[AD41] [BA29]: Going to connect to peer 192.168.61.150:51998 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:54.626] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:54.626] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:1/30050 limit:9 speed:9052
[20180301 13:38:54.626] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:54.626] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:11 speed:9641
[20180301 13:38:54.626] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:54.626] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:54.626] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:DOWNLOAD error: meta:1 conns:1 io:0
[20180301 13:38:54.627] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:54.628] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] got login - peer:10CD27075657E2E12B4A121C7E1C0671E698BA29 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:54.629] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:54.629] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] 192.168.61.150:51998: did not pick any blocks. blocking peer temporarily
[20180301 13:38:54.630] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:54.630] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:54.630] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:54.630] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:54.630] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:54.630] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:54.630] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:54.630] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:54.630] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:54.630] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:54.631] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:54.631] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:54.631] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:54.631] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:54.631] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:54.631] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:54.631] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:54.632] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:54.632] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:54.632] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:54.632] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:54.632] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:54.632] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:54.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:54.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:54.632] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:54.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:54.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:54.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:54.632] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:55.630] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:55.630] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:55.630] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:55.630] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:55.631] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:55.631] SF[AD41] [BA29]: Going to connect to peer 192.168.61.150:51998 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:55.631] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:55.631] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:1/30050 limit:15 speed:9052
[20180301 13:38:55.631] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:11 speed:9641
[20180301 13:38:55.631] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:55.631] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:55.631] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:DOWNLOAD error: meta:1 conns:1 io:0
[20180301 13:38:55.632] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:55.633] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] got login - peer:10CD27075657E2E12B4A121C7E1C0671E698BA29 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:55.633] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:55.634] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:55.634] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:55.634] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:55.634] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:55.634] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:55.634] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:55.634] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:55.635] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:55.635] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:55.635] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:55.635] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:55.635] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:55.635] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:55.635] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:55.635] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:55.635] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:55.635] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:55.635] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:55.635] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:55.635] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:55.635] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:55.635] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:55.635] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:55.635] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:55.636] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:55.636] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:55.636] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:55.636] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:55.636] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:55.636] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:56.637] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:56.637] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:56.637] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:56.637] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:56.637] SF[AD41] [B898]: Going to connect to peer 192.168.61.12:59012 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:56.637] AddToDownloading SP:[B898] index = 5083
[20180301 13:38:56.637] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:56.637] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:1/30050 limit:11 speed:9641
[20180301 13:38:56.637] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:0/0 limit:15 speed:9052
[20180301 13:38:56.637] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:56.637] SF[AD41]: UpdatePeersStat
[20180301 13:38:56.637] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:56.638] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:56.638] SF[AD41] [B898]: up:8396603 down:30050
[20180301 13:38:56.639] SF[AD41] [BA29]: up:0 down:30050
[20180301 13:38:56.639] ScheduledTask: "UpdatePeersStat" invoked: timer, reason:OnLocalTreeChanged
[20180301 13:38:56.639] SF[AD41] [BA29]: Going to send state notify to peer 192.168.61.150:51998 - root:1F58D5E97704D420F0699BB04FE203508AFF9966 pieces:6CB233FDC3B019D73A236741B88E372BDD490391
[20180301 13:38:56.639] D! 10SyncTcpReq[0x00007f1d94412fc0][TCP] [BA29]: debug 94412FC0 - SendStateNotifyToPeer
[20180301 13:38:56.639] D! 10SyncTcpReq[0x00007f1d94412fc0][TCP] [BA29]: Connect to 192.168.61.150:51998 via TUNNELL(1)
[20180301 13:38:56.639] D! 10SyncTcpReq[0x00007f1d94412fc0][TCP-TUNNELL] [BA29]: shutdown 94412FC0 - SendStateNotifyToPeer
[20180301 13:38:56.639] ScheduledTask: "StateNotify" invoked: timer, reason:OnLocalTreeChanged
[20180301 13:38:56.639] SF[AD41] [B898]: Going to sync state with peer 192.168.61.12:59012
[20180301 13:38:56.639] D! 10SyncTcpReq[0x00007f1d94413680][TCP] [B898]: debug 94413680 - outgoing merge
[20180301 13:38:56.639] D! 10SyncTcpReq[0x00007f1d94413680][TCP] [B898]: Connect to 192.168.61.12:59012 via TUNNELL(7)
[20180301 13:38:56.639] SF[AD41] [B898]: ConecurrentMergeController has started request[0x00007f1d94413680] processing
[20180301 13:38:56.639] ScheduledTask: "SyncState" invoked: timer, reason:OnLocalTreeChanged
[20180301 13:38:56.639] SF[AD41] [99FB]: SyncStateWithPeer - no valid addr
[20180301 13:38:56.639] ScheduledTask: "SyncState" invoked: timer, reason:OnLocalTreeChanged
[20180301 13:38:56.639] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:DOWNLOAD error: meta:1 conns:1 io:0
[20180301 13:38:56.640] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] got login - peer:1032389757D1C1C142BCD8E01E9248FDCEE4B898 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:56.640] SF[AD41] [B898]: connected, endpoint: 192.168.61.12:59012
[20180301 13:38:56.640] MC[AD41] [B898]: generating intial request with root 1F58D5E97704D420F0699BB04FE203508AFF9966
[20180301 13:38:56.641] AddToDownloading SP:[B898] index = 5083
[20180301 13:38:56.641] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] 192.168.61.12:59012: did not pick any blocks. blocking peer temporarily
[20180301 13:38:56.642] SF[AD41] [B898]: Received request "id"
[20180301 13:38:56.642] SF[AD41] [B898]: Got id message from peer Syno (1032389757D1C1C142BCD8E01E9248FDCEE4B898) 2.5.12
[20180301 13:38:56.645] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:56.645] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:56.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:56.645] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.12:59012/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:56.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:56.645] RemoveFromDownloading SP:[B898] index = 5083
[20180301 13:38:56.645] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:56.645] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:56.645] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:56.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:56.646] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:56.646] SF[AD41] [B898]: Received request "root"
[20180301 13:38:56.646] MC[AD41] [B898]: processing root message, my hash: 1F58D5E97704D420F0699BB04FE203508AFF9966, remote hash 1F58D5E97704D420F0699BB04FE203508AFF9966, timediff: 0
[20180301 13:38:56.646] MC[AD41] [B898]: sending get_have_pieces, prevhash: CFFA89BE998635095C3781277DF02E5FC6687DD4
[20180301 13:38:56.647] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:56.647] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:56.647] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:56.647] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:56.647] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:56.647] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:56.647] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:56.647] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:56.647] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:56.647] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:56.647] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:56.647] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:56.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:56.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:56.647] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:56.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:56.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:56.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:56.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:56.701] D! 10SyncTcpReq[0x00007f1d94412fc0]: EOF - error: 0 (Success)
[20180301 13:38:56.701] D! 10SyncTcpReq[0x00007f1d94412fc0][TCP-TUNNELL] [BA29]: eof - SendStateNotifyToPeer
[20180301 13:38:56.701] D! 10SyncTcpReq[0x00007f1d94412fc0][TCP-TUNNELL] [BA29]: destroing 94412FC0 cbcnt:0, error: 0, wb: 0 - SendStateNotifyToPeer
[20180301 13:38:56.726] SF[AD41] [B898]: Received request "have_pieces"
[20180301 13:38:56.726] MC[AD41] [B898]: processing have_pieces message
[20180301 13:38:56.726] SF[AD41] [B898]: State sync finished
[20180301 13:38:56.726] D! 10SyncTcpReq[0x00007f1d94413680][TCP-TUNNELL] [B898]: cancel 94413680 - outgoing merge, refcount - 2
[20180301 13:38:56.751] D! 10SyncTcpReq[0x00007f1d94413680][TCP-TUNNELL] [B898]: destroing 94413680 cbcnt:4, error: 0, wb: 0 - outgoing merge
[20180301 13:38:57.640] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:57.640] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:57.640] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:57.640] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:57.640] SF[AD41] [BA29]: Going to connect to peer 192.168.61.150:51998 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:57.640] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:57.640] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:57.640] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:1/30050 limit:15 speed:9052
[20180301 13:38:57.640] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:8 speed:9641
[20180301 13:38:57.640] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:57.640] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:57.641] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:DOWNLOAD error: meta:1 conns:1 io:0
[20180301 13:38:57.641] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:57.642] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] got login - peer:10CD27075657E2E12B4A121C7E1C0671E698BA29 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:57.643] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:57.643] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] 192.168.61.150:51998: did not pick any blocks. blocking peer temporarily
[20180301 13:38:57.644] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:57.644] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:57.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:57.644] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:57.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:57.644] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:57.644] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:57.644] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:57.644] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:57.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:57.644] PC[0x00007f1dac0b44d0][0x0000000000000000][192.168.61.150:51998/TCP] incoming connection from 192.168.61.150:51998
[20180301 13:38:57.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:57.645] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:57.645] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:57.645] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:57.645] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:57.645] PC[0x00007f1dac0b44d0][0x0000000000000000][192.168.61.150:51998/TUNNELL] recognized incomming SyncTcpReq[0x00007f1dac0b1770]
[20180301 13:38:57.645] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:57.645] PC[0x00007f1dac0b44d0][0x0000000000000000][192.168.61.150:51998/TUNNELL] disconnect - reason: "~PeerConnection", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:57.645] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:57.645] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:57.645] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:57.645] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:57.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:57.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:57.645] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:57.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:57.645] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:57.646] SF[AD41] [BA29]: Received request "id"
[20180301 13:38:57.646] SF[AD41] [BA29]: Got id message from peer Andys iMac (10CD27075657E2E12B4A121C7E1C0671E698BA29) 2.5.12
[20180301 13:38:57.646] D! 10SyncTcpReq[0x00007f1dac0b1770][TCP-TUNNELL] [0000]: debug AC0B1770 - incoming merge
[20180301 13:38:57.646] SF[AD41] [BA29]: Got state sync request
[20180301 13:38:57.646] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:57.646] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:57.646] SF[AD41] [BA29]: Received request "get_root"
[20180301 13:38:57.646] SF[AD41] [BA29]: ConecurrentMergeController has started merge request[0x00007f1dac0b1770] processing
[20180301 13:38:57.646] MC[AD41] [BA29]: processing get_root message, my hash: 1F58D5E97704D420F0699BB04FE203508AFF9966, remote hash 1F58D5E97704D420F0699BB04FE203508AFF9966
[20180301 13:38:57.647] SF[AD41] [BA29]: Received request "get_have_pieces"
[20180301 13:38:57.647] MC[AD41] [BA29]: processing get_have_pieces message, my hash: 6CB233FDC3B019D73A236741B88E372BDD490391, prev hash: 6CB233FDC3B019D73A236741B88E372BDD490391
[20180301 13:38:57.702] D! 10SyncTcpReq[0x00007f1dac0b1770]: EOF - error: 0 (Success)
[20180301 13:38:57.702] D! 10SyncTcpReq[0x00007f1dac0b1770][TCP-TUNNELL] [0000]: eof - incoming merge
[20180301 13:38:57.702] SF[AD41] [BA29]: State sync finished
[20180301 13:38:57.702] D! 10SyncTcpReq[0x00007f1dac0b1770][TCP-TUNNELL] [0000]: cancel AC0B1770 - incoming merge, refcount - 2
[20180301 13:38:57.727] D! 10SyncTcpReq[0x00007f1dac0b1770][TCP-TUNNELL] [0000]: destroing AC0B1770 cbcnt:4, error: 0, wb: 0 - incoming merge
[20180301 13:38:58.643] SyncFolderNotify: SyncFolderNotify: "ID", event = "IN_CLOSE_WRITE"
[20180301 13:38:58.643] [OnNotifyFileChange] "/volume1/OFFICE/.sync/ID"
[20180301 13:38:58.644] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:58.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:58.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:58.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:58.644] SF[AD41] [BA29]: Going to connect to peer 192.168.61.150:51998 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:58.644] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:58.644] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:58.644] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:1/30050 limit:15 speed:9052
[20180301 13:38:58.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:58.644] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:8 speed:9641
[20180301 13:38:58.644] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:58.644] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:DOWNLOAD error: meta:1 conns:1 io:0
[20180301 13:38:58.645] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:58.645] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] got login - peer:10CD27075657E2E12B4A121C7E1C0671E698BA29 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:58.646] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:58.646] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] 192.168.61.150:51998: did not pick any blocks. blocking peer temporarily
[20180301 13:38:58.647] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:58.647] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:58.647] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:58.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:58.647] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:58.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:58.647] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:58.647] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:58.647] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:58.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:58.647] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:58.647] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:58.647] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:58.648] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:58.648] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:58.648] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:58.648] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:58.648] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:58.648] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:58.648] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:58.648] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:58.648] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:58.648] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:58.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:58.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:58.648] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:58.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:58.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:58.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:58.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:38:58.917] PC[0x00007f1dac0b44d0][0x0000000000000000][192.168.61.12:59012/TCP] incoming connection from 192.168.61.12:59012
[20180301 13:38:58.922] PC[0x00007f1dac0b44d0][0x0000000000000000][192.168.61.12:59012/TUNNELL] recognized incomming SyncTcpReq[0x00007f1dac0b1770]
[20180301 13:38:58.922] PC[0x00007f1dac0b44d0][0x0000000000000000][192.168.61.12:59012/TUNNELL] disconnect - reason: "~PeerConnection", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:58.923] SF[AD41] [B898]: Received request "state_notify"
[20180301 13:38:58.923] SF[AD41] [B898]: Got state notify - tree:1F58D5E97704D420F0699BB04FE203508AFF9966 pieces:CFFA89BE998635095C3781277DF02E5FC6687DD4
[20180301 13:38:59.647] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:38:59.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:38:59.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:38:59.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:38:59.648] SF[AD41] [BA29]: Going to connect to peer 192.168.61.150:51998 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:59.648] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:38:59.648] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:59.648] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:1/30050 limit:15 speed:9052
[20180301 13:38:59.648] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:8 speed:9641
[20180301 13:38:59.648] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:38:59.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:38:59.648] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:DOWNLOAD error: meta:1 conns:1 io:0
[20180301 13:38:59.649] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:59.649] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] got login - peer:10CD27075657E2E12B4A121C7E1C0671E698BA29 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:38:59.650] AddToDownloading SP:[BA29] index = 5083
[20180301 13:38:59.651] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:38:59.651] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:59.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:38:59.651] PC[0x00007f1d9441fb30][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:38:59.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:38:59.651] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:38:59.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:38:59.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:59.651] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:59.651] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:38:59.652] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:59.652] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:38:59.652] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:38:59.652] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:38:59.652] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:38:59.652] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:38:59.652] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:59.652] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:59.652] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:38:59.652] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:59.652] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:38:59.652] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:59.652] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:38:59.652] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:38:59.652] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:38:59.652] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:38:59.652] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:38:59.652] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:59.652] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:38:59.652] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
[20180301 13:39:00.651] TF[AD41] [0x00007f1d9440e530][-no-caption-]: created
[20180301 13:39:00.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: parse meta - piece hash size = 20
[20180301 13:39:00.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "INVALID" to "LOADED"
[20180301 13:39:00.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "LOADED" to "RECHECK"
[20180301 13:39:00.651] SF[AD41] [BA29]: Going to connect to peer 192.168.61.150:51998 for file "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:39:00.651] AddToDownloading SP:[BA29] index = 5083
[20180301 13:39:00.651] SF[AD41] [BA29]: connect files to peer, queue:0 dowloading:1/30050 limit:15 speed:9052
[20180301 13:39:00.651] SF[AD41] [B898]: connect files to peer, queue:0 dowloading:0/0 limit:8 speed:9641
[20180301 13:39:00.651] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:OnFileWantsDownload
[20180301 13:39:00.651] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: state:RECHECK error: meta:1 conns:1 io:1
[20180301 13:39:00.651] suffix "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" exists on disk, won't perform recheck
[20180301 13:39:00.652] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "RECHECK" to "DOWNLOAD"
[20180301 13:39:00.652] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] send login - peer:10A9100C5F8FFA6AADAA4DACE53657258E98506D share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:39:00.653] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] got login - peer:10CD27075657E2E12B4A121C7E1C0671E698BA29 share:09D1A306E9673E221DB423BFA8E1E36F0C215F5C5894FBF466C536F2E552AD41 info:D3805727BA86738DF678DDDB561AEDF89881544D file:"Steuer/Steuer 2016/Testfile.csv"
[20180301 13:39:00.653] AddToDownloading SP:[BA29] index = 5083
[20180301 13:39:00.654] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] 192.168.61.150:51998: did not pick any blocks. blocking peer temporarily
[20180301 13:39:00.655] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:39:00.655] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_MODIFY"
[20180301 13:39:00.655] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:39:00.655] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: piece 3 complete
[20180301 13:39:00.655] PC[0x00007f1d942fd610][0x00007f1d9440e530][192.168.61.150:51998/TUNNELL] disconnect - reason: "Is seed", rb: 0, wb: 0, error: 0 (Success) ref: 0
[20180301 13:39:00.655] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: all pieces completed, making close handles job
[20180301 13:39:00.655] RemoveFromDownloading SP:[BA29] index = 5083
[20180301 13:39:00.656] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: change state from "DOWNLOAD" to "POST_DOWNLOAD_WORK"
[20180301 13:39:00.656] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_CLOSE_WRITE"
[20180301 13:39:00.656] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:39:00.657] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:39:00.657] JOURNAL[AD41]: torrent finished "/volume1/OFFICE 2016/Testfile.csv"
[20180301 13:39:00.657] JOURNAL[AD41]: Setup entry job "SyncTorrentFinishThreadMessage" for path "Steuer/Steuer 2016/Testfile.csv", next state is "SKIP", queue size 1 0 0 0
[20180301 13:39:00.657] JOURNAL[AD41]: Setup entry job "FixSuffixOnFileJob" for path "Steuer/Steuer 2016/Testfile.csv", next state is "PERFORM", queue size 1 0 0 0
[20180301 13:39:00.657] JOURNAL[AD41]: Check fs duplicates, fp: /volume1/OFFICE 2016/Testfile.csv (0 0), suffix: /volume1/OFFICE 2016/Testfile.csv.rsls (0 0)
[20180301 13:39:00.657] SyncFileEntry: writing file attributes to file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", mt:1519831315
[20180301 13:39:00.657] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:39:00.657] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:39:00.657] JOURNAL[AD41]: TorrentFile: failed to fix suffix on file "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync" ("/volume1/OFFICE 2016/Testfile.csv") - 13
[20180301 13:39:00.657] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: removing torrent for file
[20180301 13:39:00.657] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:39:00.657] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 2
[20180301 13:39:00.657] SyncFolderNotify: SyncFolderNotify: "BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync", event = "IN_ATTRIB"
[20180301 13:39:00.657] JOURNAL[AD41]: Finished post-download-work for entry "/volume1/OFFICE 2016/Testfile.csv", result: 0
[20180301 13:39:00.657] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: closed all handles, fs_refs = 1
[20180301 13:39:00.657] [OnNotifyAttrChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:39:00.658] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:39:00.658] [OnNotifyFileChange] "/volume1/OFFICE/.sync/BA2AFCBB1B59B6EB728133B6E96C4D9042629CD4.!sync"
[20180301 13:39:00.658] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: check can delete: fs_refs = 0
[20180301 13:39:00.658] TF[AD41] [0x00007f1d9440e530][/volume1/OFFICE 2016/Testfile.csv]: deleted
Show/hide
I already re-verified users/groups/file- and folder-permissions... Now I'm running out of ideas. Anyone can help?
And I think, this one could be related:
[20180301 08:13:43.523] JOURNAL[AD41]: won't perform job for entry "andy/2018/18-D-156.pdf" - parent has fs error
[20180301 08:13:43.524] JOURNAL[AD41]: won't perform job for entry "andy/2018/18-D-159.pdf" - parent has fs error
Re-uploading the files doesn't help - after a minute the problem appears again. But there are more files in the folder, which sync fine. It seems to be dependent on from where the file was added (or to which device).
Any ideas with that??
Any info appreciated!
andy