Copy/Error now with Log

[japp]

Here's the Log showing errors for moving files.


2015-05-14 12:59:27.984 File operations log started.
2015-05-14 14:09:28.522 Created Folder : "M:\_DVDs\Filename (2014) DVD9 R5"
2015-05-14 14:09:28.522 Created Folder : "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS"
2015-05-14 14:09:28.522 Deleted : E:\Agent downloads\Filename (2014) DVD9 R5\AUDIO_TS
2015-05-14 14:09:28.662 Copied : "E:\Agent downloads\Filename (2014) DVD9 R5\Filename (2014).jpg" => "M:\_DVDs\Filename (2014) DVD9 R5\Filename (2014).jpg" - 495307 bytes in 141ms - Average Speed : 3512815 bytes/sec (3,4 MB/sec), Read data time 6ms (78,7 MB/sec), Write data time 2ms (236,2 MB/sec)
2015-05-14 14:09:28.662 Deleted : E:\Agent downloads\Filename (2014) DVD9 R5\Filename (2014).jpg
2015-05-14 14:09:28.709 Write data failed (WinError 87) (1660 , 388, 388)
2015-05-14 14:09:28.709 [47036] WriteThread - Failed to write to file (Error code : 173, Internal Error Code : 87 - DBG 0x0000000002CA0000 , 1660 , 0)
2015-05-14 14:09:28.818 File Operations error : 173 (87) -
2015-05-14 14:09:36.668 [47036] WriteThread : User requested abort of operation
2015-05-14 14:09:36.777 [47056] WaitForState : wait for 6 , current state is 6
2015-05-14 14:09:36.777 Copied : "E:\Agent downloads\Filename (2014) DVD9 R5\Filename (2014).txt" => "M:\_DVDs\Filename (2014) DVD9 R5\Filename (2014).txt" - 0 bytes in 0ms - Average Speed : 0 bytes/sec (0 B/sec), Read data time 25ms (0 B/sec), Write data time 2ms (0 B/sec)
2015-05-14 14:09:36.777 Failed to remove the last 388 bytes from file - (Error Code : 131)
2015-05-14 14:09:36.777 Current file size not same as expected file size - File : "M:\_DVDs\Filename (2014) DVD9 R5\Filename (2014).txt", Current Size : 0 - Expected Size : 1660
2015-05-14 14:10:14.826 Copied : "E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VIDEO_TS.BUP" => "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VIDEO_TS.BUP" - 32768 bytes in 16ms - Average Speed : 2048000 bytes/sec (1,9 MB/sec), Read data time 1ms (31,3 MB/sec), Write data time 4ms (7,8 MB/sec)
2015-05-14 14:10:14.826 Deleted : E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VIDEO_TS.BUP
2015-05-14 14:10:14.966 Copied : "E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VIDEO_TS.IFO" => "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VIDEO_TS.IFO" - 32768 bytes in 140ms - Average Speed : 234057 bytes/sec (228,6 KB/sec), Read data time 14ms (2,2 MB/sec), Write data time 1ms (31,3 MB/sec)
2015-05-14 14:10:14.966 Deleted : E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VIDEO_TS.IFO
2015-05-14 14:10:18.804 Copied : "E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VIDEO_TS.VOB" => "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VIDEO_TS.VOB" - 383139840 bytes (365,4 MB) in 4sec - Average Speed : 99827993 bytes/sec (95,2 MB/sec), Read data time 1123ms (325,4 MB/sec), Write data time 3689ms (99,0 MB/sec)
2015-05-14 14:10:18.850 Deleted : E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VIDEO_TS.VOB
2015-05-14 14:10:18.866 Write data failed (WinError 87) (18432 , 0, 512)
2015-05-14 14:10:18.866 [39964] WriteThread - Failed to write to file (Error code : 173, Internal Error Code : 87 - DBG 0x0000000004E00000 , 18432 , 0)
2015-05-14 14:10:18.975 File Operations error : 173 (87) -
2015-05-14 14:10:22.589 [39964] WriteThread : User requested abort of operation
2015-05-14 14:10:22.698 [45916] WaitForState : wait for 6 , current state is 6
2015-05-14 14:10:22.698 Copied : "E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VTS_01_0.BUP" => "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VTS_01_0.BUP" - 0 bytes in 0ms - Average Speed : 0 bytes/sec (0 B/sec), Read data time 0ms (0 B/sec), Write data time 3689ms (0 B/sec)
2015-05-14 14:10:22.698 Current file size not same as expected file size - File : "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VTS_01_0.BUP", Current Size : 0 - Expected Size : 18432
2015-05-14 14:10:31.434 Unable to open target file : M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VTS_01_0.BUP ( Error code : 10, Internal Error Code : 80)
2015-05-14 14:10:31.434 File Operations error : 10 (80) -
2015-05-14 14:10:34.305 Write data failed (WinError 87) (18432 , 0, 512)
2015-05-14 14:10:34.305 [46620] WriteThread - Failed to write to file (Error code : 173, Internal Error Code : 87 - DBG 0x0000000002740000 , 18432 , 0)
2015-05-14 14:10:34.320 Write data failed (WinError 87) (18432 , 0, 512)
2015-05-14 14:10:34.320 [46620] WriteThread - Failed to write to file (Error code : 173, Internal Error Code : 87 - DBG 0x0000000002740000 , 18432 , 0)
2015-05-14 14:10:34.430 File Operations error : 173 (87) -
2015-05-14 14:10:38.316 [46620] WriteThread : User requested abort of operation
2015-05-14 14:10:38.425 [39912] WaitForState : wait for 6 , current state is 6
2015-05-14 14:10:38.425 Copied : "E:\Agent downloads\Filename (2014) DVD9 R5\VIDEO_TS\VTS_01_0.BUP" => "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VTS_01_0.BUP" - 0 bytes in 0ms - Average Speed : 0 bytes/sec (0 B/sec), Read data time 0ms (0 B/sec), Write data time 0ms (0 B/sec)
2015-05-14 14:10:38.425 Current file size not same as expected file size - File : "M:\_DVDs\Filename (2014) DVD9 R5\AUDIO_TS\VTS_01_0.BUP", Current Size : 0 - Expected Size : 18432

[Mathias (Author)]

By default MC is using optimized file transfers. But this will fail of some part of the hardware layers that the data passes through do not support that.
It is very uncommon these days but it can happen.

You can turn that off in  Menu > Configuration > Core Settings > File System (Tab)
Under the "Default File operations options"
uncheck "Automatically detect read/write strategy for file copy"

Unchecking that optimized file transfers will not be used.