tiptronic

@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.

@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)?

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

It looks more like a bug... after re-launching Sync the 'invalid time' message is gone (and also didn't appear again)...

I have the same problem since today (after installing Apple's latest software security update - as of 2018-02-21). I re-verified ALL my connected peers and they have all the same time (since they all access the same ntp). Resilio Sync still says 'Invalid time'. Unfortunately there's nothing in the logs (or wherever) which indicates which of the peers it thinks there is a problem... Any hint? thx andy

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)

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?

Is there any news to this problem? I just tried to add a folder from my old Synology 412+ (6.2) to my new Synology 718+ (6.2). And as soon as I add a file from my Mac to one of the Synology folders (using smb), I am getting the exact 'locked' file error... Now I have 3 sync folders and everything has a different state. I restarted the Synologies Sync, re-added the folders, changed/verified permissions, but nothing worked. Any idea - except using 2.3.8?

Hi Helen, thx for the hints. I dunno what happened - here's a summary: - the folders were always read/write (under 1.4) - when I installed 2.2.0 it showed all folders read/write (in the BTSync window), but I noticed, that the Finder-icon showed a strike-through pen (read-only?) - I couldn't relink the folder to make it read-write, so I stopped BTSync 2.2.0I created a fresh folder (same name) and moved all data but the '.sync' folder to the new folderre-connected the foldernow everything works again... Interestingly there is another folder showing the same habit (any other folders (3 more) are fine)... Very mysterious... - but now it works again (leaving a slightly bitter feeling behind). thx for your support

After the announcement of policy changes starting with 2.2.x I gave BTSync 2.2.0 a shot, but it doesn't work - I have 3 Macs (Home, Office, Mobile) synced fine (different folder-trees) using 1.4.x - Now I updated 1 Mac to 2.2.0 and syncing only goes into 1 direction (from 1.4 -> 2.0). -> Folders on the updated Mac show their '1.4' folder-sign -> If I add a file to one of the Macs using 1.4.111, sync works accross all devices -> If I add a file to the new 2.2.0, nothing is synced to the 1.4.111 How can I fix this? (And no: I can't/won't just update all devices, since I also share a couple of folders with family, who are far away and still on 1.4.111 and I don't have access to their machines.) Is there a way to sync from 2.0 to 1.4.111 reliably or do I need to go back to 1.4.111? (Can I go back without loosing all my synced settings)?

Hi Roman, it is simply blank.... In Google Chrome the UI sometimes shows 'Welcome to BitTorrent Sync' (after a while), but then stops there. Console is also blank. At this point I am able to open Preferences. When clicking the UI preferences, there are chances, the UI finally loads - but not before messing with the UI preferences. In Safari I found out, that if I open the Web-Inspector and follow the network-request, the last request doesn't come back most of the times... but sometimes it comes back after 30 seconds or a minute or more (this is on a 1Gbit LAN). Fonts (e.g. opensans-regular.woff have a mime-type of text/html - that should be "application/x-woff" anyway). Maybe the whole font-declaration in your css is not correct - that would explain those IE problems. (Btw: why not remove theses fonts alltogether - or optionally load them - and fall back to Helvetica and Arial; I think this would solve lots of IE problems automatically)? andy

@SyncSupport: Any solution for non-windows-users? (recap: BTSync running on Synology 409; since 1.4 -> white gui; clients: OSX 10.9 & 10.10)

I just updated to 1.4.83-8... no change... The Javascript stack starts running, but nothing is added to the body.... (Same devices as above)

I just got the auto-update of 1.4.-72on my Synology 409 and now I got a blank window as well. Please note: This is on Mac OSX 10.9.4 - I tried Safari, Chrome and Firefox (all latest builds available). Also I noticed, the btsync process takes 150MB RAM on the Syno (which is lots on this old chap). Can I somehow force it to purge btsync's memory?

@iElectric: get yourself a Mac - its dead simple there

I have BTSync on a couple of Macs, Ubuntu-Desktop, Virtual Server (Ubuntu) and I have iPad1, iPad3, iPad mini, iPhones and a couple of Android devices here - ready for testing

@GreatMarko: I am aware this is an alpha, that's why I didn't change anything on the repository. That means: same amount of files, same amount of peers... Nothing changed in this regard. I just wanted to make the increase in CPU load visible.

Although not really aggressive, BTSync 132 needs more CPU power when idle, than 116 did. On my MacBookPro (late 2008), 10.8.3, 8GB RAM it takes approx. 0.6-1.2% CPU (12 Threads) when idle. The 116 version took 0.02-0.06%... This is just an observation... I don't know if this is informative for anyone, but anyway... andy

Please don't sync the symlink's target, but the symlink itself. Or make it configurable... so everybody can shoot in their own foot, whenever they like. Andy PS: Following symlinks while synching is dangerous and wrong!