1 Single Large File: A) Indexing Slow B) Out Of Sync After Modification

[testifix]

Hi all,

 

I have recently installed Bittorrent on two systems, and I am facing two issues, namely A) very slow indexing performance, and   an out of sync error once I modify a file.

 

But before digging into the issue, let me describe the setup:

 

Computer 1:

• Windows7
• BitTorrent Sync 1.4.103
• 4 GB RAM
• 200 GB free disk space (internal HDD, NTFS formatted)

Computer 2:

• Mac OS X Yosemite 10.10
• BitTorrent Sync 1.4.103
• 4 GB RAM
• Sync folder on external Harddrive (ExFAT formatted, 1.5 TB free disk space)

Synchronisation:

• 1 File to be synced (20 GB large; Truecrypt file, filename = "pe") (referred to below as file)
• Connection established as "Read and Write"

 

 

And here's what I did...:

• Placed the file in the SyncFolder of Computer 1
• File was transferred to Computer 2
• I opened the file on Computer 2 and modified it (Saved it and unmounted Truecrypt container; Updated the timestamp of the Truecrypt container)

And the problem I'm experiencing...:

• Issue A: Slow indexing performance
  • Upon modification of the file on Computer 2, it takes roughly 20min for the indexing to finish; despite the fact it is only 1 file
• Issue B: Out of Sync
  • Once indexing has finished on Computer 2, I would expect the updated file to overwrite the previous file on Computer 1
  • Instead, I encounter an "Out of Sync" error
  •  

I would greatly appreciate your input! Really curious how this can be solved!

 

Thanks for your time and feedback, and a happy new year to all,

testifix

 

 

P.S. I attached below the sync logs:

• Computer 1:

• [2014-12-30 14:13:10] total physical memory 3871592448 max disk cache 33554432[2014-12-30 14:13:10] Using IP address 192.168.0.3[2014-12-30 14:13:10] test sha1: AE5BD8EFEA5322C4D9986D06680A781392F9A642[2014-12-30 14:13:10] test sha2: 630DCD2966C4336691125448BBB25B4FF412A49C732DB2C8ABC1B8581BD710DD[2014-12-30 14:13:10] Aes BCrypt algorithm provider has been loaded and initialized[2014-12-30 14:13:10] test aes: 0A940BB5416EF045F1C39458C653EA5A07FEEF74E1D5036E900EEE118E949293[2014-12-30 14:13:10] Loading config file version 1.4.103[2014-12-30 14:13:10] My PeerID: 1006541A7F0B6FF111F7502152472A8D6F7FEA62[2014-12-30 14:13:10] FC[E73A]: start reading DB[2014-12-30 14:13:10] FC[E73A]: end reading DB[2014-12-30 14:13:10] SyncFolderScanner: Adding notifier "\\?\M:\Backup\2 Synced"[2014-12-30 14:13:10] SF[E73A]: Loaded folder \\?\M:\Backup\2 Synced[2014-12-30 14:13:10] SF[E73A]: start reading 'C:\Users\Admin\AppData\Roaming\BitTorrent Sync\926703F865E68E7A9CC92F576788F054D5DFE73A.availability'[2014-12-30 14:13:10] SF[E73A]: end reading 'C:\Users\Admin\AppData\Roaming\BitTorrent Sync\926703F865E68E7A9CC92F576788F054D5DFE73A.availability'[2014-12-30 14:13:10] FolderConfigCache: Initializing domain config.usyncapp.com:[2014-12-30 14:13:10] Cached trackers:[2014-12-30 14:13:10] addr 0: 54.225.92.50:3000[2014-12-30 14:13:10] addr 1: 54.225.100.8:3000[2014-12-30 14:13:10] addr 2: 54.225.196.38:3000[2014-12-30 14:13:10] Cached relays:[2014-12-30 14:13:10] addr 0: 67.215.229.106:3000[2014-12-30 14:13:10] addr 1: 67.215.231.242:3000[2014-12-30 14:13:10] Cached mobile proxies:[2014-12-30 14:13:10] addr 0: 54.235.182.157:3000[2014-12-30 14:13:10] Refresh interval from cache: 3600[2014-12-30 14:13:10] loaded history: 11 events[2014-12-30 14:13:10] setup socket 1144 for local peer discovery for 192.168.0.3:0: success[2014-12-30 14:13:10] setup socket 1148 for local peer discovery for 127.0.0.1:0: success[2014-12-30 14:13:10] Send ping to peer (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A:[2014-12-30 14:13:10] ping 192.168.0.14:52304[2014-12-30 14:13:10] Checking connection to 192.168.0.14:52304:TCP[2014-12-30 14:13:10] Requesting folder config from config.usyncapp.com[2014-12-30 14:13:10] Requesting peers from tracker 54.225.92.50:3000 for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:10] Creating TCP tracker connection to 54.225.92.50:3000[2014-12-30 14:13:10] Creating uTP tracker connection to 54.225.92.50:3000[2014-12-30 14:13:10] Sending broadcast ping for 1 shares[2014-12-30 14:13:10] SF[E73A]: UpdatePeersStat[2014-12-30 14:13:10] SF[E73A] [451D]: up:0 down:132120576[2014-12-30 14:13:10] ScheduledTask:UpdatePeersStat invoked:immediately reason:Folder initialized[2014-12-30 14:13:10] Got ping (broadcast: 0) from peer 192.168.0.14:14740 (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:10] Got Teredo Address: ([2014-12-30 14:13:10] Got list of 2 peers for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:10] Peer 0: 77.58.134.90:14740 108AACC3F3DFE80110180F521F4CA03281DF451D[2014-12-30 14:13:10] Peer 0: local IP 192.168.0.14:14740[2014-12-30 14:13:10] Got list of 2 peers for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:10] Peer 0: 77.58.134.90:14740 108AACC3F3DFE80110180F521F4CA03281DF451D[2014-12-30 14:13:10] Peer 0: local IP 192.168.0.14:14740[2014-12-30 14:13:11] FC[E73A]: started periodic scan for "\\?\M:\Backup\2 Synced"[2014-12-30 14:13:11] FC[E73A]: file updated - processing file \\?\M:\Backup\2 Synced\wk t:1419247333 s:53687091200[2014-12-30 14:13:11] FC[E73A]: file updated - processing file \\?\M:\Backup\2 Synced\pe t:1419269812 s:21474836480[2014-12-30 14:13:11] FC[E73A]: finished periodic scan, checking for missing files in "\\?\M:\Backup\2 Synced"[2014-12-30 14:13:11] Trash: scan for folder "\\?\M:\Backup\2 Synced\.sync\Archive" started, max file age = 30 days[2014-12-30 14:13:11] Trash: scan for folder "\\?\M:\Backup\2 Synced\.sync\Archive" finished[2014-12-30 14:13:11] Trash: GetFileList failed for folder "\\?\M:\Backup\2 Synced\.sync\Streams" - error 3[2014-12-30 14:13:11] Got ping (broadcast: 0) from peer 192.168.0.14:14740 (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:11] Got ping (broadcast: 0) from peer 77.58.134.90:14740 (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:11] Send ping to peer (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A:[2014-12-30 14:13:11] ping 192.168.0.14:14740[2014-12-30 14:13:11] Checking connection to 192.168.0.14:14740:TCP[2014-12-30 14:13:11] Checking connection to 192.168.0.14:14740:uTP[2014-12-30 14:13:11] Send ping to peer (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A:[2014-12-30 14:13:11] ping 77.58.134.90:14740[2014-12-30 14:13:11] Checking connection to 77.58.134.90:14740:TCP[2014-12-30 14:13:11] Checking connection to 77.58.134.90:14740:uTP[2014-12-30 14:13:11] Sending broadcast ping for 1 shares[2014-12-30 14:13:11] Got tunnel to 77.58.134.90:14740:uTP, total tunnels: 1[2014-12-30 14:13:11] Best tunnel now is 77.58.134.90:14740:uTP[2014-12-30 14:13:11] SF[E73A] [451D]: Found peer 108AACC3F3DFE80110180F521F4CA03281DF451D 77.58.134.90:14740 direct:1 transport:1 version: 1.4.103[2014-12-30 14:13:11] Got tunnel to 192.168.0.14:14740:uTP, total tunnels: 2[2014-12-30 14:13:11] Best tunnel now is 192.168.0.14:14740:uTP[2014-12-30 14:13:11] SF[E73A] [451D]: Found peer 108AACC3F3DFE80110180F521F4CA03281DF451D 192.168.0.14:14740 direct:1 transport:1 version: 1.4.103[2014-12-30 14:13:11] TorrentFile: 0x03fbde90 created[2014-12-30 14:13:11] TorrentFile: 0x03fbde90 "\\?\M:\Backup\2 Synced\pe" - download type:recheck[2014-12-30 14:13:11] ScheduledTask:ConnectMorePeers invoked:immediately reason:OnPeerFound[2014-12-30 14:13:11] SF[E73A] [451D]: Going to send state notify to peer 192.168.0.14:14740 - root:BE03C71FE476F50722E160BF1DAB23E3E00C2941 pieces:CD27E023EF2151F27AC935CD261B583C4737648B[2014-12-30 14:13:11] ScheduledTask:StateNotify invoked:immediately reason:OnPeerFound[2014-12-30 14:13:11] Got tunnel to 192.168.0.14:14740:TCP, total tunnels: 3[2014-12-30 14:13:11] Best tunnel now is 192.168.0.14:14740:TCP[2014-12-30 14:13:11] SF[E73A] [451D]: Found peer 108AACC3F3DFE80110180F521F4CA03281DF451D 192.168.0.14:14740 direct:1 transport:1 version: 1.4.103[2014-12-30 14:13:11] Got tunnel to 77.58.134.90:14740:TCP, total tunnels: 4[2014-12-30 14:13:11] Best tunnel now is 192.168.0.14:14740:TCP[2014-12-30 14:13:11] SF[E73A] [451D]: Found peer 108AACC3F3DFE80110180F521F4CA03281DF451D 192.168.0.14:14740 direct:1 transport:1 version: 1.4.103[2014-12-30 14:13:12] Got ping (broadcast: 0) from peer 192.168.0.14:14740 (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:12] ScheduledTask:ConnectMorePeers invoked:timer reason:OnPeerFound[2014-12-30 14:13:12] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:3[2014-12-30 14:13:12] Sending broadcast ping for 1 shares[2014-12-30 14:13:12] Incoming connection from 192.168.0.14:14740[2014-12-30 14:13:12] Got ping (broadcast: 0) from peer 77.58.134.90:14740 (108AACC3F3DFE80110180F521F4CA03281DF451D) for share 926703F865E68E7A9CC92F576788F054D5DFE73A[2014-12-30 14:13:12] Incoming connection from 77.58.134.90:14740[2014-12-30 14:13:12] Got tunnel to 192.168.0.14:14740:uTP, total tunnels: 5[2014-12-30 14:13:12] Best tunnel now is 192.168.0.14:14740:TCP[2014-12-30 14:13:12] SF[E73A] [451D]: Found peer 108AACC3F3DFE80110180F521F4CA03281DF451D 192.168.0.14:14740 direct:1 transport:1 version: 1.4.103[2014-12-30 14:13:12] Got tunnel to 77.58.134.90:14740:uTP, total tunnels: 6[2014-12-30 14:13:12] Best tunnel now is 192.168.0.14:14740:TCP[2014-12-30 14:13:12] SF[E73A] [451D]: Found peer 108AACC3F3DFE80110180F521F4CA03281DF451D 192.168.0.14:14740 direct:1 transport:1 version: 1.4.103[2014-12-30 14:13:13] ScheduledTask:ConnectMorePeers invoked:timer reason:OnPeerFound[2014-12-30 14:13:13] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:3[2014-12-30 14:13:13] Sending broadcast ping for 1 shares[2014-12-30 14:13:13] UPnP(XP): Removed TCP port 0[2014-12-30 14:13:13] UPnP(XP): TCP port 20464 -> 192.168.0.3:20464 mapped successfully.[2014-12-30 14:13:13] UPnP(XP): UDP port 20464 -> 192.168.0.3:20464 mapped successfully.[2014-12-30 14:13:14] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:14] Sending broadcast ping for 1 shares[2014-12-30 14:13:15] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:15] Sending broadcast ping for 1 shares[2014-12-30 14:13:16] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:16] Sending broadcast ping for 1 shares[2014-12-30 14:13:17] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:17] Sending broadcast ping for 1 shares[2014-12-30 14:13:18] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:18] Sending broadcast ping for 1 shares[2014-12-30 14:13:19] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:19] Sending broadcast ping for 1 shares[2014-12-30 14:13:20] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:21] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:21] Incoming connection from 192.168.0.14:52376[2014-12-30 14:13:21] Got tunnel to 192.168.0.14:52376:TCP, total tunnels: 7[2014-12-30 14:13:21] Best tunnel now is 192.168.0.14:52376:TCP[2014-12-30 14:13:21] SF[E73A] [451D]: Found peer 108AACC3F3DFE80110180F521F4CA03281DF451D 192.168.0.14:52376 direct:1 transport:1 version: 1.4.103[2014-12-30 14:13:21] ScheduledTask:ConnectMorePeers invoked:immediately reason:OnPeerFound[2014-12-30 14:13:22] NAT-PMP: Unable to map port with NAT-PMP.[2014-12-30 14:13:22] SF[E73A] [451D]: Going to send state notify to peer 192.168.0.14:52376 - root:BE03C71FE476F50722E160BF1DAB23E3E00C2941 pieces:CD27E023EF2151F27AC935CD261B583C4737648B[2014-12-30 14:13:22] ScheduledTask:StateNotify invoked:timer reason:OnPeerFound[2014-12-30 14:13:22] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:23] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:24] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:25] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:26] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:27] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:28] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:29] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:3[2014-12-30 14:13:30] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:3[2014-12-30 14:13:31] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:32] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:33] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:34] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:35] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:36] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:37] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:38] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:39] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2[2014-12-30 14:13:40] SF[E73A]: Torrent \\?\M:\Backup\2 Synced\pe status:134 error:<NULL> meta:1 conns:0 io:2

• Computer 2:

• [20141230 14:05:57.522] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:05:57.523] ScheduledTask:SyncState invoked:timer reason:FinishStateSync - sync not completed[20141230 14:05:57.528] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:05:57.666] SF[E73A] [EA62]: Received request "id"[20141230 14:05:57.666] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:05:57.666] SF[E73A] [EA62]: Received request "peers"[20141230 14:05:57.666] SF[E73A] [EA62]: Received request "root"[20141230 14:05:57.666] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -4[20141230 14:05:57.666] MC[E73A] [EA62]: sending get_have_pieces, prevhash: CD27E023EF2151F27AC935CD261B583C4737648B[20141230 14:05:57.805] SF[E73A] [EA62]: Received request "have_pieces"[20141230 14:05:57.806] MC[E73A] [EA62]: processing have_pieces message[20141230 14:05:57.806] SF[E73A] [EA62]: State sync finished[20141230 14:05:57.806] SF[E73A]: UpdatePeersStat[20141230 14:05:57.806] SF[E73A] [EA62]: up:132120576 down:0[20141230 14:05:57.806] ScheduledTask:UpdatePeersStat invoked:immediately reason:FinishStateSync[20141230 14:05:59.333] saved history: 18 events[20141230 14:05:59.333] SyncFolderScanner: Removing notifier "/Volumes/ExternalHDD/2 Synced"[20141230 14:05:59.334] SF[E73A] [EA62]: peer lost[20141230 14:05:59.334] SyncFolderScanner: shutting down working thread...[20141230 14:06:00.052] Incoming connection from 192.168.0.3:49655[20141230 14:06:00.053] SyncEncryptHandler: bad shareID (STATE_BEGIN)[20141230 14:06:00.056] Incoming connection from 77.58.134.90:49656[20141230 14:06:00.057] SyncEncryptHandler: bad shareID (STATE_BEGIN)[20141230 14:06:00.692] SyncFolderScanner: shut down[20141230 14:06:00.692] Shutdown. Saving config sync.dat[20141230 14:06:02.776] total physical memory 536870912 max disk cache 33554432[20141230 14:06:02.776] Using IP address 192.168.0.14[20141230 14:06:02.777] test sha1: AE5BD8EFEA5322C4D9986D06680A781392F9A642[20141230 14:06:02.777] test sha2: 630DCD2966C4336691125448BBB25B4FF412A49C732DB2C8ABC1B8581BD710DD[20141230 14:06:02.777] test aes: 0A940BB5416EF045F1C39458C653EA5A07FEEF74E1D5036E900EEE118E949293[20141230 14:06:02.777] Loading config file version 1.4.103[20141230 14:06:02.777] My PeerID: 108AACC3F3DFE80110180F521F4CA03281DF451D[20141230 14:06:02.779] FC[E73A]: start reading DB[20141230 14:06:02.780] FC[E73A]: end reading DB[20141230 14:06:02.780] SyncFolderScanner: Adding notifier "/Volumes/ExternalHDD/2 Synced"[20141230 14:06:02.883] MasterInvite: E077312AFDB583D335B1606A467BD87F8E005C79 loaded[20141230 14:06:02.883] MasterInvite: CBC324FE2CE344A2D86899C47C65321950DEA854 loaded[20141230 14:06:02.883] SF[E73A]: Loaded folder /Volumes/ExternalHDD/2 Synced[20141230 14:06:02.883] SF[E73A]: start reading '/Users/username/Library/Application Support/BitTorrent Sync//926703F865E68E7A9CC92F576788F054D5DFE73A.availability'[20141230 14:06:02.883] SF[E73A]: end reading '/Users/username/Library/Application Support/BitTorrent Sync//926703F865E68E7A9CC92F576788F054D5DFE73A.availability'[20141230 14:06:02.884] FolderConfigCache: Initializing domain config.usyncapp.com:[20141230 14:06:02.884] Cached trackers:[20141230 14:06:02.884] addr 0: 54.225.92.50:3000[20141230 14:06:02.884] addr 1: 54.225.100.8:3000[20141230 14:06:02.884] addr 2: 54.225.196.38:3000[20141230 14:06:02.884] Cached relays:[20141230 14:06:02.884] addr 0: 67.215.229.106:3000[20141230 14:06:02.884] addr 1: 67.215.231.242:3000[20141230 14:06:02.884] Cached mobile proxies:[20141230 14:06:02.884] addr 0: 54.235.182.157:3000[20141230 14:06:02.884] Refresh interval from cache: 3600[20141230 14:06:02.884] FC[E73A]: started periodic scan for "/Volumes/ExternalHDD/2 Synced"[20141230 14:06:02.884] FC[E73A]: file updated - processing file /Volumes/ExternalHDD/2 Synced/pe t:1419941420 s:21474836480[20141230 14:06:02.884] loaded history: 18 events[20141230 14:06:02.885] setup socket 37 for local peer discovery for 127.0.0.1:0: success[20141230 14:06:02.885] setup socket 38 for local peer discovery for 192.168.0.14:0: success[20141230 14:06:02.885] setup socket 39 for local peer discovery for 192.168.56.1:0: success[20141230 14:06:02.885] Requesting folder config from config.usyncapp.com[20141230 14:06:02.885] Requesting peers from tracker 54.225.92.50:3000 for share E077312AFDB583D335B1606A467BD87F8E005C79[20141230 14:06:02.885] Creating TCP tracker connection to 54.225.92.50:3000[20141230 14:06:02.885] Creating uTP tracker connection to 54.225.92.50:3000[20141230 14:06:02.885] Requesting peers from tracker 54.225.100.8:3000 for share CBC324FE2CE344A2D86899C47C65321950DEA854[20141230 14:06:02.885] Creating TCP tracker connection to 54.225.100.8:3000[20141230 14:06:02.885] Creating uTP tracker connection to 54.225.100.8:3000[20141230 14:06:02.885] Send ping to peer (1006541A7F0B6FF111F7502152472A8D6F7FEA62) for share 926703F865E68E7A9CC92F576788F054D5DFE73A:[20141230 14:06:02.886] ping 192.168.0.3:20464[20141230 14:06:02.886] Checking connection to 192.168.0.3:20464:TCP[20141230 14:06:02.886] Requesting peers from tracker 54.225.92.50:3000 for share 926703F865E68E7A9CC92F576788F054D5DFE73A[20141230 14:06:02.886] Using existent uTP tracker connection to 54.225.92.50:3000[20141230 14:06:02.886] Sending broadcast ping for 3 shares[20141230 14:06:02.886] SF[E73A]: UpdatePeersStat[20141230 14:06:02.886] SF[E73A] [EA62]: up:132120576 down:0[20141230 14:06:02.886] ScheduledTask:UpdatePeersStat invoked:immediately reason:Folder initialized[20141230 14:06:02.886] FC[E73A]: file updated - processing file /Volumes/ExternalHDD/2 Synced/wk t:1419247333 s:53687091200[20141230 14:06:02.887] FC[E73A]: finished periodic scan, checking for missing files in "/Volumes/ExternalHDD/2 Synced"[20141230 14:06:02.887] Trash: scan for folder "/Volumes/ExternalHDD/2 Synced/.sync/Archive" started, max file age = 30 days[20141230 14:06:02.887] Trash: scan for folder "/Volumes/ExternalHDD/2 Synced/.sync/Archive" finished[20141230 14:06:02.887] Trash: GetFileList failed for folder "/Volumes/ExternalHDD/2 Synced/.sync/Streams" - error 2[20141230 14:06:02.911] API: callback id=0, value="", can_deferred=1, _delegate=0x33ac0[20141230 14:06:02.943] Got ping (broadcast: 0) from peer 192.168.0.3:20464 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) for share 926703F865E68E7A9CC92F576788F054D5DFE73A[20141230 14:06:02.943] Incoming connection from 192.168.0.3:20464[20141230 14:06:02.943] Got ping (broadcast: 0) from peer 77.58.134.90:20464 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) for share 926703F865E68E7A9CC92F576788F054D5DFE73A[20141230 14:06:03.050] Got tunnel to 192.168.0.3:20464:TCP, total tunnels: 1[20141230 14:06:03.051] Best tunnel now is 192.168.0.3:20464:TCP[20141230 14:06:03.051] SF[E73A] [EA62]: Found peer 1006541A7F0B6FF111F7502152472A8D6F7FEA62 192.168.0.3:20464 direct:1 transport:1 version: 1.4.103[20141230 14:06:03.051] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:06:03.051] ScheduledTask:SyncState invoked:immediately reason:OnPeerFound[20141230 14:06:03.051] Send ping to peer (1006541A7F0B6FF111F7502152472A8D6F7FEA62) for share 926703F865E68E7A9CC92F576788F054D5DFE73A:[20141230 14:06:03.051] ping 192.168.0.3:20464[20141230 14:06:03.051] Checking connection to 192.168.0.3:20464:uTP[20141230 14:06:03.051] Sending broadcast ping for 3 shares[20141230 14:06:03.053] Incoming connection from 192.168.0.3:20464[20141230 14:06:03.053] SF[E73A] [EA62]: Received request "state_notify"[20141230 14:06:03.053] SF[E73A] [EA62]: Got state notify - tree:BE03C71FE476F50722E160BF1DAB23E3E00C2941 pieces:CD27E023EF2151F27AC935CD261B583C4737648B[20141230 14:06:03.075] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:06:03.105] Got tunnel to 192.168.0.3:20464:uTP, total tunnels: 2[20141230 14:06:03.105] Best tunnel now is 192.168.0.3:20464:TCP[20141230 14:06:03.105] SF[E73A] [EA62]: Found peer 1006541A7F0B6FF111F7502152472A8D6F7FEA62 192.168.0.3:20464 direct:1 transport:1 version: 1.4.103[20141230 14:06:03.160] Got list of 1 peers for share E077312AFDB583D335B1606A467BD87F8E005C79[20141230 14:06:03.192] Got list of 1 peers for share CBC324FE2CE344A2D86899C47C65321950DEA854[20141230 14:06:03.252] Got tunnel to 192.168.0.3:20464:uTP, total tunnels: 3[20141230 14:06:03.252] Best tunnel now is 192.168.0.3:20464:TCP[20141230 14:06:03.253] SF[E73A] [EA62]: Found peer 1006541A7F0B6FF111F7502152472A8D6F7FEA62 192.168.0.3:20464 direct:1 transport:1 version: 1.4.103[20141230 14:06:03.279] Got list of 1 peers for share 926703F865E68E7A9CC92F576788F054D5DFE73A[20141230 14:06:03.279] Got list of 2 peers for share 926703F865E68E7A9CC92F576788F054D5DFE73A[20141230 14:06:03.279] Peer 0: 77.58.134.90:20464 1006541A7F0B6FF111F7502152472A8D6F7FEA62[20141230 14:06:03.279] Peer 0: local IP 192.168.0.3:20464[20141230 14:06:03.346] SF[E73A] [EA62]: Received request "id"[20141230 14:06:03.346] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:06:03.346] SF[E73A] [EA62]: Received request "peers"[20141230 14:06:03.346] SF[E73A] [EA62]: Received request "root"[20141230 14:06:03.346] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -4[20141230 14:06:03.346] MC[E73A] [EA62]: sending get_have_pieces, prevhash: 0000000000000000000000000000000000000000[20141230 14:06:03.488] SF[E73A] [EA62]: Received request "have_pieces"[20141230 14:06:03.489] MC[E73A] [EA62]: processing have_pieces message[20141230 14:06:03.489] SF[E73A] [EA62]: State sync finished[20141230 14:06:03.489] SF[E73A] [EA62]: Last pieces hash is CD27E023EF2151F27AC935CD261B583C4737648B[20141230 14:06:03.489] ScheduledTask:ConnectMorePeers invoked:immediately reason:FinishStateSync - received availability info[20141230 14:06:03.582] API: --> userlang(t=1419944763552)[20141230 14:06:03.582] API: <-- userlang : { "status": 200, "value": "en" }[20141230 14:06:03.677] API: --> licenseagreed(t=1419944763657)[20141230 14:06:03.677] API: <-- licenseagreed : { "status": 200, "value": { "licenseagreed": true, "passwordset": false } }[20141230 14:06:03.680] API: --> localstorage(t=1419944763678)[20141230 14:06:03.680] API: <-- localstorage : { "status": 200, "value": "{\"columns\":{\"name\":true,\"receivingSpeed\":true,\"sendingSpeed\":true,\"progress\":true,\"path\":false,\"size\":false,\"date_added\":false,\"last_modified\":false,\"peers\":false},\"firstRunTips\":{\"addFolder\":true},\"foldersAdded\":true,\"shareOptions\":{\"AGUMG7Z5YCUNCD6CTXALTZOHQVHYKBL7R\":{\"readwrite\":true,\"expiration\":3,\"usages\":0,\"approvals\":true}}}" }[20141230 14:06:03.770] API: callback id=0, value="", can_deferred=0, _delegate=0x33ac0[20141230 14:06:03.780] API: --> settings(t=1419944763681)[20141230 14:06:03.783] API: <-- settings : { "status": 200, "value": { "autostart": true, "check_update": true, "debug_logging": true, "devicename": "MonsterII", "display_new_version": true, "dlrate": 0, "listeningport": 14740, "portmapping": true, "show_copy_key": false, "show_notifications": true, "ulrate": 0 } }[20141230 14:06:03.784] API: --> pause(t=1419944763683)[20141230 14:06:03.784] API: <-- pause : { "status": 200, "value": false }[20141230 14:06:03.808] API: --> setlocalstorage(status=200&value=%7B%22columns%22%3A%7B%22name%22%3Atrue%2C%22receivingSpeed%22%3Atrue%2C%22sendingSpeed%22%3Atrue%2C%22progress%22%3Atrue%2C%22path%22%3Afalse%2C%22size%22%3Afalse%2C%22date_added%22%3Afalse%2C%22last_modified%22%3Afalse%2C%22peers%22%3Afalse%7D%2C%22firstRunTips%22%3A%7B%22addFolder%22%3Atrue%7D%2C%22foldersAdded%22%3Atrue%2C%22shareOptions%22%3A%7B%22AGUMG7Z5YCUNCD6CTXALTZOHQVHYKBL7R%22%3A%7B%22readwrite%22%3Atrue%2C%22expiration%22%3A3%2C%22usages%22%3A0%2C%22approvals%22%3Atrue%7D%7D%7D&t=1419944763717)[20141230 14:06:03.808] API: <-- setlocalstorage : { "status": 200 }[20141230 14:06:03.919] API: --> getsyncfolders(discovery=1&t=1419944763756)[20141230 14:06:03.919] API: <-- getsyncfolders : { "folders": [ { "archive": "/Volumes/ExternalHDD/2 Synced/.sync/Archive", "archive_files": 0, "archive_size": 0, "canencrypt": false, "date_added": 1419847248, "down_eta": 0, "down_speed": 0, "down_status": 100, "error": 0, "files": 2, "has_key": true, "id": 7477287633831525045, "indexing": false, "iswritable": true, "last_modified": 1419941421, "name": "2 Synced", "path": "/Volumes/ExternalHDD/2 Synced", "peers": [ { "direct": true, "downdiff": 0, "downfiles": 0, "id": "1006541A7F0B6FF111F7502152472A8D6F7FEA62", "isonline": true, "lastreceivedtime": 1419905050, "lastsenttime": 0, "lastsynctime": 0, "name": "Computer1", "updiff": 132120576, "upfiles": 1 } ], "readonlysecret": "secret", "secret": "secret", "secrettype": 1, "size": 75161927680, "status": "70.0 GB in 2 files", "up_eta": 0, "up_speed": 0, "up_status": 100 } ], "loading": false, "speed": { "downspeed": 0, "upspeed": 0 }, "status": 200 }[20141230 14:06:03.949] API: --> useridentity(t=1419944763757)[20141230 14:06:03.949] API: <-- useridentity : { "status": 200, "value": { "devicename": "MonsterII", "fingerprint": "64263F681899B9A8A423DCF03A3B45B88E1AE502", "username": "testifix" } }[20141230 14:06:03.949] API: --> getsysteminfo(t=1419944763770)[20141230 14:06:03.950] API: <-- getsysteminfo : { "status": 200, "value": { "hostname": "MonsterII", "language": "de", "os": "mac", "username": "testifix" } }[20141230 14:06:03.951] API: --> version(t=1419944763770)[20141230 14:06:03.951] API: <-- version : { "status": 200, "value": 17039463 }[20141230 14:06:03.951] API: --> debugmode(t=1419944763770)[20141230 14:06:03.951] API: <-- debugmode : { "status": 200, "value": false }[20141230 14:06:03.952] API: --> getpendingrequests(t=1419944763920)[20141230 14:06:03.952] API: <-- getpendingrequests : { "status": 200, "value": [  ] }[20141230 14:06:03.955] API: --> setlocalstorage(status=200&value=%7B%22columns%22%3A%7B%22name%22%3Atrue%2C%22receivingSpeed%22%3Atrue%2C%22sendingSpeed%22%3Atrue%2C%22progress%22%3Atrue%2C%22path%22%3Afalse%2C%22size%22%3Afalse%2C%22date_added%22%3Afalse%2C%22last_modified%22%3Afalse%2C%22peers%22%3Afalse%7D%2C%22firstRunTips%22%3A%7B%22addFolder%22%3Atrue%7D%2C%22foldersAdded%22%3Atrue%2C%22shareOptions%22%3A%7B%22AGUMG7Z5YCUNCD6CTXALTZOHQVHYKBL7R%22%3A%7B%22readwrite%22%3Atrue%2C%22expiration%22%3A3%2C%22usages%22%3A0%2C%22approvals%22%3Atrue%7D%7D%7D&t=1419944763948)[20141230 14:06:03.955] API: <-- setlocalstorage : { "status": 200 }[20141230 14:06:03.955] API: --> setlocalstorage(status=200&value=%7B%22columns%22%3A%7B%22name%22%3Atrue%2C%22receivingSpeed%22%3Atrue%2C%22sendingSpeed%22%3Atrue%2C%22progress%22%3Atrue%2C%22path%22%3Afalse%2C%22size%22%3Afalse%2C%22date_added%22%3Afalse%2C%22last_modified%22%3Afalse%2C%22peers%22%3Afalse%7D%2C%22firstRunTips%22%3A%7B%22addFolder%22%3Atrue%7D%2C%22foldersAdded%22%3Atrue%2C%22shareOptions%22%3A%7B%22AGUMG7Z5YCUNCD6CTXALTZOHQVHYKBL7R%22%3A%7B%22readwrite%22%3Atrue%2C%22expiration%22%3A3%2C%22usages%22%3A0%2C%22approvals%22%3Atrue%7D%7D%7D&t=1419944763949)[20141230 14:06:03.955] API: --> getfoldersettings(t=1419944763950)[20141230 14:06:03.955] API: <-- getfoldersettings : { "error": "unknown function", "status": 400 }[20141230 14:06:03.956] API: --> getsyncfolders(discovery=1&t=1419944763950)[20141230 14:06:03.956] API: <-- getsyncfolders : { "folders": [ { "archive": "/Volumes/ExternalHDD/2 Synced/.sync/Archive", "archive_files": 0, "archive_size": 0, "canencrypt": false, "date_added": 1419847248, "down_eta": 0, "down_speed": 0, "down_status": 100, "error": 0, "files": 2, "has_key": true, "id": 7477287633831525045, "indexing": false, "iswritable": true, "last_modified": 1419941421, "name": "2 Synced", "path": "/Volumes/ExternalHDD/2 Synced", "peers": [ { "direct": true, "downdiff": 0, "downfiles": 0, "id": "1006541A7F0B6FF111F7502152472A8D6F7FEA62", "isonline": true, "lastreceivedtime": 1419905050, "lastsenttime": 0, "lastsynctime": 0, "name": "Computer1", "updiff": 132120576, "upfiles": 1 } ], "readonlysecret": "secret", "secret": "secret", "secrettype": 1, "size": 75161927680, "status": "70.0 GB in 2 files", "up_eta": 0, "up_speed": 0, "up_status": 100 } ], "loading": false, "speed": { "downspeed": 0, "upspeed": 0 }, "status": 200 }[20141230 14:06:03.993] API: --> checknewversion(t=1419944763951)[20141230 14:06:03.993] API: <-- checknewversion : { "status": 200, "version": { "url": "", "version": 0 } }[20141230 14:06:04.493] Sending broadcast ping for 3 shares[20141230 14:06:04.963] API: --> getsyncfolders(discovery=1&t=1419944764958)[20141230 14:06:04.963] API: <-- getsyncfolders : { "folders": [ { "archive": "/Volumes/ExternalHDD/2 Synced/.sync/Archive", "archive_files": 0, "archive_size": 0, "canencrypt": false, "date_added": 1419847248, "down_eta": 0, "down_speed": 0, "down_status": 100, "error": 0, "files": 2, "has_key": true, "id": 7477287633831525045, "indexing": false, "iswritable": true, "last_modified": 1419941421, "name": "2 Synced", "path": "/Volumes/ExternalHDD/2 Synced", "peers": [ { "direct": true, "downdiff": 0, "downfiles": 0, "id": "1006541A7F0B6FF111F7502152472A8D6F7FEA62", "isonline": true, "lastreceivedtime": 1419905050, "lastsenttime": 0, "lastsynctime": 0, "name": "Computer1", "updiff": 132120576, "upfiles": 1 } ], "readonlysecret": "secret", "secret": "secret", "secrettype": 1, "size": 75161927680, "status": "70.0 GB in 2 files", "up_eta": 0, "up_speed": 0, "up_status": 100 } ], "loading": false, "speed": { "downspeed": 0, "upspeed": 0 }, "status": 200 }[20141230 14:06:05.970] API: --> getsyncfolders(discovery=1&t=1419944765965)[20141230 14:06:06.003] Sending broadcast ping for 3 shares[20141230 14:06:06.003] Sending broadcast ping for 3 shares[20141230 14:06:06.977] API: --> getsyncfolders(discovery=1&t=1419944766972)[20141230 14:06:07.009] Sending broadcast ping for 3 shares[20141230 14:06:07.985] API: --> getsyncfolders(discovery=1&t=1419944767980)[20141230 14:06:08.019] Sending broadcast ping for 3 shares[20141230 14:06:08.991] API: --> getsyncfolders(discovery=1&t=1419944768987)[20141230 14:06:09.026] Sending broadcast ping for 3 shares[20141230 14:06:09.999] API: --> getsyncfolders(discovery=1&t=1419944769994)[20141230 14:06:10.034] Sending broadcast ping for 3 shares[20141230 14:06:10.062] Incoming connection from 192.168.0.3:49657[20141230 14:06:10.309] Got tunnel to 192.168.0.3:49657:TCP, total tunnels: 4[20141230 14:06:10.309] Best tunnel now is 192.168.0.3:20464:TCP[20141230 14:06:10.309] SF[E73A] [EA62]: Found peer 1006541A7F0B6FF111F7502152472A8D6F7FEA62 192.168.0.3:20464 direct:1 transport:1 version: 1.4.103[20141230 14:06:11.005] API: --> getsyncfolders(discovery=1&t=1419944771000)[20141230 14:06:11.331] Sending broadcast ping for 3 shares[20141230 14:06:12.013] API: --> getsyncfolders(discovery=1&t=1419944772008)[20141230 14:06:12.541] NAT-PMP: Unable to map port with NAT-PMP.[20141230 14:06:12.843] SF[E73A]: UpdatePeersStat[20141230 14:06:12.843] SF[E73A] [EA62]: up:132120576 down:0[20141230 14:06:12.843] ScheduledTask:UpdatePeersStat invoked:timer reason:FinishStateSync[20141230 14:06:13.020] API: --> getsyncfolders(discovery=1&t=1419944773015)[20141230 14:06:13.846] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:06:13.847] ScheduledTask:SyncState invoked:timer reason:OnReceivedStateNotify[20141230 14:06:13.858] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:06:13.978] SF[E73A] [EA62]: Received request "id"[20141230 14:06:13.978] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:06:13.978] SF[E73A] [EA62]: Received request "peers"[20141230 14:06:13.979] SF[E73A] [EA62]: Received request "root"[20141230 14:06:13.979] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -5[20141230 14:06:13.979] SF[E73A] [EA62]: State sync finished[20141230 14:06:14.028] API: --> getsyncfolders(discovery=1&t=1419944774023)[20141230 14:06:15.036] API: --> getsyncfolders(discovery=1&t=1419944775031)[20141230 14:06:16.045] API: --> getsyncfolders(discovery=1&t=1419944776039)[20141230 14:06:17.053] API: --> getsyncfolders(discovery=1&t=1419944777047)[20141230 14:06:18.061] API: --> getsyncfolders(discovery=1&t=1419944778055)[20141230 14:06:19.069] API: --> getsyncfolders(discovery=1&t=1419944779063)[20141230 14:06:20.079] API: --> getsyncfolders(discovery=1&t=1419944780074)[20141230 14:06:21.089] API: --> getsyncfolders(discovery=1&t=1419944781083)[20141230 14:06:22.097] API: --> getsyncfolders(discovery=1&t=1419944782092)[20141230 14:06:23.040] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:06:23.041] ScheduledTask:SyncState invoked:timer reason:FinishStateSync - sync not completed[20141230 14:06:23.102] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:06:23.105] API: --> getsyncfolders(discovery=1&t=1419944783100)[20141230 14:06:23.216] SF[E73A] [EA62]: Received request "id"[20141230 14:06:23.216] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:06:23.216] SF[E73A] [EA62]: Received request "peers"[20141230 14:06:23.216] SF[E73A] [EA62]: Received request "root"[20141230 14:06:23.216] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -4[20141230 14:06:23.216] SF[E73A] [EA62]: State sync finished[20141230 14:06:24.112] API: --> getsyncfolders(discovery=1&t=1419944784107)[20141230 14:06:25.119] API: --> getsyncfolders(discovery=1&t=1419944785114)[20141230 14:06:26.127] API: --> getsyncfolders(discovery=1&t=1419944786122)[20141230 14:06:27.135] API: --> getsyncfolders(discovery=1&t=1419944787130)[20141230 14:06:28.142] API: --> getsyncfolders(discovery=1&t=1419944788137)[20141230 14:06:29.150] API: --> getsyncfolders(discovery=1&t=1419944789145)[20141230 14:06:30.158] API: --> getsyncfolders(discovery=1&t=1419944790152)[20141230 14:06:31.166] API: --> getsyncfolders(discovery=1&t=1419944791160)[20141230 14:06:32.173] API: --> getsyncfolders(discovery=1&t=1419944792168)[20141230 14:06:32.612] Lost tunnel to 192.168.0.3:20464:uTP[20141230 14:06:32.612] Lost tunnel to 192.168.0.3:20464:uTP[20141230 14:06:33.180] API: --> getsyncfolders(discovery=1&t=1419944793175)[20141230 14:06:33.771] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:06:33.771] ScheduledTask:SyncState invoked:timer reason:FinishStateSync - sync not completed[20141230 14:06:33.772] Best tunnel now is 192.168.0.3:20464:TCP[20141230 14:06:33.772] SF[E73A] [EA62]: Found peer 1006541A7F0B6FF111F7502152472A8D6F7FEA62 192.168.0.3:20464 direct:1 transport:1 version: 1.4.103[20141230 14:06:33.776] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:06:33.902] SF[E73A] [EA62]: Received request "id"[20141230 14:06:33.902] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:06:33.902] SF[E73A] [EA62]: Received request "peers"[20141230 14:06:33.902] SF[E73A] [EA62]: Received request "root"[20141230 14:06:33.902] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -4[20141230 14:06:33.902] SF[E73A] [EA62]: State sync finished[20141230 14:06:34.188] API: --> getsyncfolders(discovery=1&t=1419944794183)[20141230 14:06:35.196] API: --> getsyncfolders(discovery=1&t=1419944795190)[20141230 14:06:36.204] API: --> getsyncfolders(discovery=1&t=1419944796199)[20141230 14:06:37.213] API: --> getsyncfolders(discovery=1&t=1419944797208)[20141230 14:06:38.221] API: --> getsyncfolders(discovery=1&t=1419944798216)[20141230 14:06:39.229] API: --> getsyncfolders(discovery=1&t=1419944799224)[20141230 14:06:39.570] Lost tunnel to 192.168.0.3:49657:TCP[20141230 14:06:40.237] API: --> getsyncfolders(discovery=1&t=1419944800232)[20141230 14:06:40.946] Best tunnel now is 192.168.0.3:20464:TCP[20141230 14:06:40.946] SF[E73A] [EA62]: Found peer 1006541A7F0B6FF111F7502152472A8D6F7FEA62 192.168.0.3:20464 direct:1 transport:1 version: 1.4.103[20141230 14:06:41.245] API: --> getsyncfolders(discovery=1&t=1419944801239)[20141230 14:06:42.251] API: --> getsyncfolders(discovery=1&t=1419944802246)[20141230 14:06:43.258] API: --> getsyncfolders(discovery=1&t=1419944803253)[20141230 14:06:43.967] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:06:43.967] ScheduledTask:SyncState invoked:timer reason:FinishStateSync - sync not completed[20141230 14:06:43.982] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:06:44.265] API: --> getsyncfolders(discovery=1&t=1419944804260)[20141230 14:06:44.771] SF[E73A] [EA62]: Received request "id"[20141230 14:06:44.771] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:06:44.771] SF[E73A] [EA62]: Received request "peers"[20141230 14:06:44.771] SF[E73A] [EA62]: Received request "root"[20141230 14:06:44.771] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -5[20141230 14:06:44.771] SF[E73A] [EA62]: State sync finished[20141230 14:06:45.272] API: --> getsyncfolders(discovery=1&t=1419944805267)[20141230 14:06:46.280] API: --> getsyncfolders(discovery=1&t=1419944806275)[20141230 14:06:47.289] API: --> getsyncfolders(discovery=1&t=1419944807283)[20141230 14:06:48.297] API: --> getsyncfolders(discovery=1&t=1419944808291)[20141230 14:06:49.304] API: --> getsyncfolders(discovery=1&t=1419944809299)[20141230 14:06:50.312] API: --> getsyncfolders(discovery=1&t=1419944810307)[20141230 14:06:51.319] API: --> getsyncfolders(discovery=1&t=1419944811314)[20141230 14:06:52.326] API: --> getsyncfolders(discovery=1&t=1419944812321)[20141230 14:06:53.313] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:06:53.313] ScheduledTask:SyncState invoked:timer reason:FinishStateSync - sync not completed[20141230 14:06:53.317] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:06:53.334] API: --> getsyncfolders(discovery=1&t=1419944813329)[20141230 14:06:53.469] SF[E73A] [EA62]: Received request "id"[20141230 14:06:53.469] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:06:53.469] SF[E73A] [EA62]: Received request "peers"[20141230 14:06:53.469] SF[E73A] [EA62]: Received request "root"[20141230 14:06:53.469] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -4[20141230 14:06:53.469] SF[E73A] [EA62]: State sync finished[20141230 14:06:54.341] API: --> getsyncfolders(discovery=1&t=1419944814336)[20141230 14:06:55.348] API: --> getsyncfolders(discovery=1&t=1419944815343)[20141230 14:07:06.355] API: --> getsyncfolders(discovery=1&t=1419944816351)[20141230 14:07:16.946] Closing uTP tracker connection to 54.225.92.50:3000. EOF[20141230 14:07:16.947] SF[E73A] [EA62]: Going to sync state with peer 192.168.0.3:20464[20141230 14:07:16.947] ScheduledTask:SyncState invoked:timer reason:FinishStateSync - sync not completed[20141230 14:07:16.947] Requesting peers from tracker 54.225.92.50:3000 for share E077312AFDB583D335B1606A467BD87F8E005C79[20141230 14:07:16.948] Creating TCP tracker connection to 54.225.92.50:3000[20141230 14:07:16.948] Creating uTP tracker connection to 54.225.92.50:3000[20141230 14:07:17.069] MC[E73A] [EA62]: generating intial request with root BE03C71FE476F50722E160BF1DAB23E3E00C2941[20141230 14:07:17.184] Got list of 1 peers for share E077312AFDB583D335B1606A467BD87F8E005C79[20141230 14:07:17.250] SF[E73A] [EA62]: Received request "id"[20141230 14:07:17.250] SF[E73A] [EA62]: Got id message from peer Computer1 (1006541A7F0B6FF111F7502152472A8D6F7FEA62) 1.4.103[20141230 14:07:17.250] SF[E73A] [EA62]: Received request "peers"[20141230 14:07:17.312] SF[E73A] [EA62]: Received request "root"[20141230 14:07:17.312] MC[E73A] [EA62]: processing root message, remote hash BE03C71FE476F50722E160BF1DAB23E3E00C2941, timediff: -4[20141230 14:07:17.312] MC[E73A] [EA62]: sending get_have_pieces, prevhash: CD27E023EF2151F27AC935CD261B583C4737648B[20141230 14:07:27.361] API: --> getsyncfolders(discovery=1&t=1419944837357)[20141230 14:07:32.921] SF[E73A] [EA62]: Received request "have_pieces"[20141230 14:07:32.921] MC[E73A] [EA62]: processing have_pieces message[20141230 14:07:32.921] SF[E73A] [EA62]: State sync finished[20141230 14:07:32.921] SF[E73A]: UpdatePeersStat[20141230 14:07:32.921] SF[E73A] [EA62]: up:132120576 down:0[20141230 14:07:32.921] ScheduledTask:UpdatePeersStat invoked:immediately reason:FinishStateSync[20141230 14:07:34.685] API: --> getsyncfolders(discovery=1&t=1419944852826)[20141230 14:07:41.183] API: --> getsyncfolders(discovery=1&t=1419944859654)[20141230 14:07:42.245] API: --> getsyncfolders(discovery=1&t=1419944862240)[20141230 14:07:43.252] API: --> getsyncfolders(discovery=1&t=1419944863247)[20141230 14:07:44.259] API: --> getsyncfolders(discovery=1&t=1419944864254)

 

 

[GreatMarko]

The speed of indexing can depend on a number of factors including the number of files to be indexed, the speed of your CPU, available memory... but what's interesting is that you mention a 20GB Trucrypt volume.

 

Do you get the same slow indexing performance if you try with a 20GB folder that's isn't a Trucrypt volume?

 

In relation to "out of sync" - you've not mentioned if the devices you're syncing with are all using full access Sync keys (Read/Write), or read-only keys? If it's the latter, you may need to select the "Overwrite any changed files" option on your read-only nodes to bring them back into sync with read/write nodes

[testifix]

Hi Marko,

 

thanks for your post. 

 

As I mentioned - its only one file that needs to be indexed, hence I'm a bit confused why that takes so long. I would expect it to be rather quick. I can try later today with 20 GB of normal data and get back regarding indexing speed. 

 

Regarding "out of sync": 

I have connected the devices with "Read/Write" keys. The update on Computer 2 is not reflected on Computer 1, instead, they go out of sync.

Would you suggest to only connect them with "Read" keys and activate the "Overwrite any changes" option? 

 

Thanks, and happy new year,

testifix

[testifix]

and ideas?

[testifix]

anyone?

[GreatMarko]

Regarding "out of sync": 

I have connected the devices with "Read/Write" keys. The update on Computer 2 is not reflected on Computer 1, instead, they go out of sync.

If you're sure both devices are using full-access (Read/Write) keys and you're experiencing "out of sync" issues, there are a couple of things you can check:

1) That the contents of .sync/IgnoreList is identical on both devices

2) That none of the files in the folder you're syncing are currently open/locked/in use by other applications

[testifix]

Hi Marko,

 

I have verified that

1) both devices are using full-access

2) the ignorelists are identical

3) both folders are not currently locked  or protected

 

but unfortunately the issue continues - out of sync.

Is there any way we can get this solved? Any information I can provide? I'm a bit lost...

 

Thanks,

testifix

[GreatMarko]

but unfortunately the issue continues - out of sync.

Is there any way we can get this solved? Any information I can provide? I'm a bit lost...

A number of users have experienced various "out of sync" issues recently. An experimental build is available which may possible resolve this issue for you. For more information, please see this post.