tiptronic Posted February 16, 2018 Report Share Posted February 16, 2018 Just before I wrote the comment, I updated everything to 2.5.12 (1911) When I add a file on Syno1 (using FileStation) it syncs fine. Everything syncs fine on my Macs. When I add files through Finder (onto the Syno Mount) it doesn't sync. I just saw, that most of the files have the correct rights and some has a user rslsync... Do I explicitely add a user rslsync to the Synologies? Quote Link to comment Share on other sites More sharing options...
Gane O'dwyer Posted February 16, 2018 Report Share Posted February 16, 2018 Since 2.4.5 app version Sync runs under rslsync user on Synology NASes and admin user is no longer mandatory. Quote Link to comment Share on other sites More sharing options...
tiptronic Posted February 16, 2018 Report Share Posted February 16, 2018 OK - here's how I got it working: - I just mount my shared volumes on the Mac as AFP (not SMB) Now everything works fine on all machines... (it might have something to do with Synology's SMB 'opportunistic locking' ... dunno) Quote Link to comment Share on other sites More sharing options...
tiptronic Posted March 1, 2018 Report Share Posted March 1, 2018 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 Quote Link to comment Share on other sites More sharing options...
Gane O'dwyer Posted March 1, 2018 Report Share Posted March 1, 2018 @tiptronic No good idea to insert here a good piece of log but anyway you have a access denied error and error '-13' prove it. Quote Link to comment Share on other sites More sharing options...
tiptronic Posted March 5, 2018 Report Share Posted March 5, 2018 @Gane O'dwyerInterestingly this is only for *some* files and not for others. Sometimes it helps if I copy the file to Synology2 and then it syncs over to Synology1 - sometimes not. The mileage varies... So - why do I get a -13 error from 1 of 10 files I copy from my Mac to one of the Synologies? After some days of working fine, today I had the problem again: I can't upload a file without getting the error. - If I copy the file to my Mac's Sync folder, it sometimes works, but sometimes it happens, that one of the Synologies gets the file and the other throws the 'file locked' message and doesn't sync the file. - If I copy the file to one of the Synologies using their own File-Manager, it mostly works, but sometimes the other Synology throws the 'file locked' message. - The behaviour is random - I couldn't find a pattern -> In all cases, my Macs get the file without any problems Could it be that the file gets locked when the Synology is indexing (or whatever)? Quote Link to comment Share on other sites More sharing options...
Gane O'dwyer Posted March 6, 2018 Report Share Posted March 6, 2018 @tiptronic You need to check folders permissions on both sides and especially in the NAS. If you are 100% sure that everything works as it have to be please send logs to the customer support team for the further issue investigation. Also please make sure that file are not in use while do you observe such app behaviour. Quote Link to comment Share on other sites More sharing options...
tiptronic Posted March 6, 2018 Report Share Posted March 6, 2018 @Gane O'dwyer What are the proper folder permissions? The current situation is like this: If I copy to the NAS using AFP or SMB, the files/folders are copied using my user/group. If I copy to a sync-folder on my Mac, the files are saved to the NAS as rslsync/rslsync. Both users have full access to the synced folder(s) and the hierarchy up. Generally this work fine - except when the 'locked' message appears. Unfortunately I can't find out, what makes Sync to see the file as 'locked', because it is somwhat random. Any other idea what this could be? (Btw. the two NASes have different users) In the logs all I see is what I sent in my previous question. Quote Link to comment Share on other sites More sharing options...
Gane O'dwyer Posted March 7, 2018 Report Share Posted March 7, 2018 @tiptronic Ok, but do you have network shared added in Sync and are you sure that Sync rslsyn cuser has access to such directories. Quote Link to comment Share on other sites More sharing options...
David Galler Posted May 8, 2018 Report Share Posted May 8, 2018 You can try New Long Path Tool for deleting files or locked files with downloading for free. Quote Link to comment Share on other sites More sharing options...
seanfl Posted December 23, 2018 Report Share Posted December 23, 2018 I'm having a similar issue with locked files. There have been some data files that have come up locked, but the ones that I can't seem to get to unlock are from Word and Excel. I've tried restarting Resilio, rebooting the computer where the file resides, rebooting all computers, and still can not get them to transfer over to a computer that didn't have them. The only solution I found was to rename the problem files and then they would sync over fine. Any other ideas? Using Sync 2.6.2 (1330) on all machines. Windows 10 64 bit everywhere. Quote Link to comment Share on other sites More sharing options...
kruuger Posted April 8, 2020 Report Share Posted April 8, 2020 hi all Are there any solutions to sync DWG Autocad files after save? All works great when i close the dwg but when it is still open Sync can't handle this? Cloud like dropbox, onedrive pass saved files without any problem. Why Sync can't do this ? Thanks kruuger. Quote Link to comment Share on other sites More sharing options...
Recommended Posts
Join the conversation
You can post now and register later. If you have an account, sign in now to post with your account.
Note: Your post will require moderator approval before it will be visible.