JoSZ
01-07-2009, 10:03 PM
Hello supporters,
we have a problem that only on server 1 is worked on a file. It happened 2 days in a row now that in the evening, after the worker finished the new version was deleted (to .recycle) and an old version from server2 was copied over the newer version.
It is SURE that the file has never been touched on server 2.
How would we correct that? And how would we regain trust of the user into the software, now that it failed 2 times. They ask which other files were affected too?
Thanks Jo
Debug Log:
168448 bytes = new version
155136 bytes = old version
[18:09:59:618] DEBUG [Node 'Server1']: FileListChange received: FolderFilesChanged 'Gemeinsamer-Ordner': 1 files, 0 removed
[18:09:59:618] DEBUG [Folder 'Gemeinsamer-Ordner']: Triing to find same files in remote list with 1 files from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:09:59:618] DEBUG [Folder 'Gemeinsamer-Ordner']: Deleting files, which are deleted by friends. con-members: [Member 'Server1' (Server1.company.local/192.168.1.2:1337)]
[18:09:59:618] INFO [FileRequestor]: Start requesting files for 1 folder(s)
[18:09:59:650] DEBUG [Folder 'Gemeinsamer-Ordner']: Incoming files 93
[18:09:59:650] WARN [Download 'Sheet1.xls']: Tempfile exists for [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, tempFile: C:\Excel Sheets\(incomplete) Sheet1.xls, removed : Modified date of tempfile (Tue Jan 06 17:49:44 CET 2009) does not match with file (Tue Jan 06 18:09:50 CET 2009)
[18:09:59:681] DEBUG [TransferManager]: Requesting [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1' from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:09:59:868] DEBUG [Node 'Server1']: Received folder change on 'Gemeinsamer-Ordner. Expecting -116 more deltas
[18:10:00:134] INFO [Download 'Sheet1.xls']: Uploader supports partial transfers, sending record-request.
[18:10:00:478] INFO [Download 'Sheet1.xls']: Received parts record
[18:10:00:478] DEBUG [Download 'Sheet1.xls']: Processing FilePartsRecord.
[18:10:00:493] DEBUG [TransferManager]: Transfers: 1 download(s), 0 active upload(s), 0 in queue, 0 KByte/s
[18:10:00:509] INFO [Download 'Sheet1.xls']: Starting to request parts - NOW
[18:10:00:509] DEBUG [Download 'Sheet1.xls']: DONE - Processing FilePartsRecord.
[18:10:00:790] DEBUG [TransferManager]: Transfer started: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1' from 'Server1' (local-net)
[18:10:01:540] DEBUG [ReconnectManager]: Freshly filled reconnection queue with 0 nodes, 0 were in queue before
[18:10:01:540] DEBUG [ReconnectManager]: Reconnection queue empty after rebuild.Going on idle for 5 seconds
[18:10:01:712] DEBUG [Download 'Sheet1.xls']: Download completed: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1' from 'Server1' (local-net)
[18:10:01:712] DEBUG [Download 'Sheet1.xls']: Verifying file hash.
[18:10:01:712] INFO [Download 'Sheet1.xls']: Successfully checked file hash!
[18:13:58:43] DEBUG [FolderScanner]: Scan of folder: Gemeinsamer-Ordner start
[18:13:58:746] WARN [FolderScanner]: Changed file detected: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1'. On disk: size: 155136, lastMod: 1231160167394
[18:13:59:434] DEBUG [FolderScanner]: Scan of folder Gemeinsamer-Ordner done in 1391ms. Result: SCANNED
[18:13:59:434] WARN [Folder 'Gemeinsamer-Ordner']: Broadcasted folder changes 0 addedmsgs, 1 changedmsgs, 0 deletedmsgs, 0 restoredmsgs
[18:13:59:434] DEBUG [Folder 'Gemeinsamer-Ordner']: Scanned 14039 total, 1 changed, 0 new, 0 restored, 0 removed, 0 problems
[18:13:59:434] DEBUG [Folder 'Gemeinsamer-Ordner']: commitScanResult DONE
[18:13:59:434] DEBUG [Folder 'Gemeinsamer-Ordner']: Triing to find same files in remote list with 15553 files from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:14:00:450] DEBUG [TransferManager]: Upload enqueud: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 155136 bytes, version: 3148, modified: Mon Jan 05 13:56:07 CET 2009 (1231160167394) by 'Server2', startOffset: 0, to: Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:14:00:653] DEBUG [TransferManager]: Transfer started: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls to 'Server1' (local-net)
[18:14:00:778] INFO [Upload 'Sheet1.xls']: Received request for a parts record.
[18:14:00:778] INFO [Upload 'Sheet1.xls']: Checking for parts request.
[18:14:00:778] WARN [FileInfo]: Built file parts for [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls. took 0ms
[18:14:01:121] INFO [Upload 'Sheet1.xls']: Upload started Upload Sheet1.xls to Server1
[18:14:02:668] DEBUG [ReconnectManager]: Freshly filled reconnection queue with 0 nodes, 0 were in queue before
[18:14:02:668] DEBUG [ReconnectManager]: Reconnection queue empty after rebuild.Going on idle for 5 seconds
[18:14:02:950] INFO [TransferManager]: Upload completed: 155'136 bytes in 1s (82.83 KByte/s): [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls to 'Server1'
[18:14:02:950] DEBUG [Node 'Server1']: FileListChange received: FolderFilesChanged 'Gemeinsamer-Ordner': 1 files, 0 removed
[18:14:02:950] DEBUG [Folder 'Gemeinsamer-Ordner']: Triing to find same files in remote list with 1 files from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:14:02:950] DEBUG [Folder 'Gemeinsamer-Ordner']: Deleting files, which are deleted by friends. con-members: [Member 'Server1' (Server1.company.local/192.168.1.2:1337)]
[18:14:02:965] DEBUG [TransferManager]: Transfer completed: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls to 'Server1' (local-net)
[18:14:03:121] DEBUG [Node 'Server1']: Received folder change on 'Gemeinsamer-Ordner. Expecting -117 more deltas
[18:14:03:184] DEBUG [TransferManager]: Transfers: 0 download(s), 0 active upload(s), 0 in queue, 2.4 KByte/s
we have a problem that only on server 1 is worked on a file. It happened 2 days in a row now that in the evening, after the worker finished the new version was deleted (to .recycle) and an old version from server2 was copied over the newer version.
It is SURE that the file has never been touched on server 2.
How would we correct that? And how would we regain trust of the user into the software, now that it failed 2 times. They ask which other files were affected too?
Thanks Jo
Debug Log:
168448 bytes = new version
155136 bytes = old version
[18:09:59:618] DEBUG [Node 'Server1']: FileListChange received: FolderFilesChanged 'Gemeinsamer-Ordner': 1 files, 0 removed
[18:09:59:618] DEBUG [Folder 'Gemeinsamer-Ordner']: Triing to find same files in remote list with 1 files from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:09:59:618] DEBUG [Folder 'Gemeinsamer-Ordner']: Deleting files, which are deleted by friends. con-members: [Member 'Server1' (Server1.company.local/192.168.1.2:1337)]
[18:09:59:618] INFO [FileRequestor]: Start requesting files for 1 folder(s)
[18:09:59:650] DEBUG [Folder 'Gemeinsamer-Ordner']: Incoming files 93
[18:09:59:650] WARN [Download 'Sheet1.xls']: Tempfile exists for [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, tempFile: C:\Excel Sheets\(incomplete) Sheet1.xls, removed : Modified date of tempfile (Tue Jan 06 17:49:44 CET 2009) does not match with file (Tue Jan 06 18:09:50 CET 2009)
[18:09:59:681] DEBUG [TransferManager]: Requesting [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1' from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:09:59:868] DEBUG [Node 'Server1']: Received folder change on 'Gemeinsamer-Ordner. Expecting -116 more deltas
[18:10:00:134] INFO [Download 'Sheet1.xls']: Uploader supports partial transfers, sending record-request.
[18:10:00:478] INFO [Download 'Sheet1.xls']: Received parts record
[18:10:00:478] DEBUG [Download 'Sheet1.xls']: Processing FilePartsRecord.
[18:10:00:493] DEBUG [TransferManager]: Transfers: 1 download(s), 0 active upload(s), 0 in queue, 0 KByte/s
[18:10:00:509] INFO [Download 'Sheet1.xls']: Starting to request parts - NOW
[18:10:00:509] DEBUG [Download 'Sheet1.xls']: DONE - Processing FilePartsRecord.
[18:10:00:790] DEBUG [TransferManager]: Transfer started: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1' from 'Server1' (local-net)
[18:10:01:540] DEBUG [ReconnectManager]: Freshly filled reconnection queue with 0 nodes, 0 were in queue before
[18:10:01:540] DEBUG [ReconnectManager]: Reconnection queue empty after rebuild.Going on idle for 5 seconds
[18:10:01:712] DEBUG [Download 'Sheet1.xls']: Download completed: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1' from 'Server1' (local-net)
[18:10:01:712] DEBUG [Download 'Sheet1.xls']: Verifying file hash.
[18:10:01:712] INFO [Download 'Sheet1.xls']: Successfully checked file hash!
[18:13:58:43] DEBUG [FolderScanner]: Scan of folder: Gemeinsamer-Ordner start
[18:13:58:746] WARN [FolderScanner]: Changed file detected: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 168448 bytes, version: 3147, modified: Tue Jan 06 18:09:50 CET 2009 (1231261790808) by 'Server1'. On disk: size: 155136, lastMod: 1231160167394
[18:13:59:434] DEBUG [FolderScanner]: Scan of folder Gemeinsamer-Ordner done in 1391ms. Result: SCANNED
[18:13:59:434] WARN [Folder 'Gemeinsamer-Ordner']: Broadcasted folder changes 0 addedmsgs, 1 changedmsgs, 0 deletedmsgs, 0 restoredmsgs
[18:13:59:434] DEBUG [Folder 'Gemeinsamer-Ordner']: Scanned 14039 total, 1 changed, 0 new, 0 restored, 0 removed, 0 problems
[18:13:59:434] DEBUG [Folder 'Gemeinsamer-Ordner']: commitScanResult DONE
[18:13:59:434] DEBUG [Folder 'Gemeinsamer-Ordner']: Triing to find same files in remote list with 15553 files from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:14:00:450] DEBUG [TransferManager]: Upload enqueud: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls, size: 155136 bytes, version: 3148, modified: Mon Jan 05 13:56:07 CET 2009 (1231160167394) by 'Server2', startOffset: 0, to: Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:14:00:653] DEBUG [TransferManager]: Transfer started: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls to 'Server1' (local-net)
[18:14:00:778] INFO [Upload 'Sheet1.xls']: Received request for a parts record.
[18:14:00:778] INFO [Upload 'Sheet1.xls']: Checking for parts request.
[18:14:00:778] WARN [FileInfo]: Built file parts for [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls. took 0ms
[18:14:01:121] INFO [Upload 'Sheet1.xls']: Upload started Upload Sheet1.xls to Server1
[18:14:02:668] DEBUG [ReconnectManager]: Freshly filled reconnection queue with 0 nodes, 0 were in queue before
[18:14:02:668] DEBUG [ReconnectManager]: Reconnection queue empty after rebuild.Going on idle for 5 seconds
[18:14:02:950] INFO [TransferManager]: Upload completed: 155'136 bytes in 1s (82.83 KByte/s): [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls to 'Server1'
[18:14:02:950] DEBUG [Node 'Server1']: FileListChange received: FolderFilesChanged 'Gemeinsamer-Ordner': 1 files, 0 removed
[18:14:02:950] DEBUG [Folder 'Gemeinsamer-Ordner']: Triing to find same files in remote list with 1 files from Member 'Server1' (Server1.company.local/192.168.1.2:1337)
[18:14:02:950] DEBUG [Folder 'Gemeinsamer-Ordner']: Deleting files, which are deleted by friends. con-members: [Member 'Server1' (Server1.company.local/192.168.1.2:1337)]
[18:14:02:965] DEBUG [TransferManager]: Transfer completed: [Gemeinsamer-Ordner]:/Excel Sheets/Sheet1.xls to 'Server1' (local-net)
[18:14:03:121] DEBUG [Node 'Server1']: Received folder change on 'Gemeinsamer-Ordner. Expecting -117 more deltas
[18:14:03:184] DEBUG [TransferManager]: Transfers: 0 download(s), 0 active upload(s), 0 in queue, 2.4 KByte/s