The batch-process produces around 200 files per batch which are alle uploaded / processed / downloaded as fast as possible.
During the batch we get usually about 10 error 450's on renaming files.
Where we get zero if we start a single job manually. Because we retry every failure, everything eventually completes. But it is very irritating to see every day a couple of errors which solve themselves.
Our way of working is :
- Upload file with extension .filepart
- Check if original file exists and then delete original file
- Rename .filepart to original filename
And in step 3 we are getting the error.
At first we thought it could be a virusscanner / network delay or something so currently we are pausing for 2 seconds on getting a 450 error and then retrying it for 3 times.
But we are still getting the errors.
Now I noticed something in the logs.
Is it possible that the error comes because we use the same (default) filename only to / from different directories ( the directories are per customer, but the file usually has the same default-name)
A sample of the log is below, and login2 has started the transfer for Webshop.zip before 01:07:24
Then login1 tries to rename a different file named Webshop.zip (in different directory) and this fails.
And finally i see that login2 has succesfully transfered the file.
Could this have something to do with it?
Code: Select all
(089113) 17-5-2019 01:07:24 - login1 (192.168.100.1)> 226 Successfully transferred "/customer1/out/Webexport.zip_filepart"
(089113) 17-5-2019 01:07:24 - login1 (192.168.100.1)> MFMT 20190516230722 Webexport.zip_filepart
(089113) 17-5-2019 01:07:24 - login1 (192.168.100.1)> 213 modify=20190516230722; /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> MLST /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> 250-Listing /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> type=file;size=9853042;modify=20190516000640; /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> 250 End
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> MLST /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> 250-Listing /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> type=file;size=9853042;modify=20190516000640; /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> 250 End
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> DELE /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:25 - login1 (192.168.100.1)> 250 File deleted successfully
...
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> MLST /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> 250-Listing /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> type=file;size=9852960;modify=20190516230722; /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> 250 End
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> RNFR /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> 350 File exists, ready for destination name.
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> RNTO /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:27 - login1 (192.168.100.1)> 450 Internal error renaming the file
...
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> MLST /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> 250-Listing /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> type=file;size=9852960;modify=20190516230722; /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> 250 End
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> RNFR /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> 350 File exists, ready for destination name.
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> RNTO /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:30 - login1 (192.168.100.1)> 450 Internal error renaming the file
...
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> MLST /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> 250-Listing /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> type=file;size=9852960;modify=20190516230722; /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> 250 End
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> RNFR /customer1/out/Webexport.zip_filepart
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> 350 File exists, ready for destination name.
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> RNTO /customer1/out/Webexport.zip
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> 450 Internal error renaming the file
(089113) 17-5-2019 01:07:32 - login1 (192.168.100.1)> disconnected.
...
(089110) 17-5-2019 01:07:38 - login2 (###.###.###.###)> 226 Successfully transferred "/customer2/Webshop.zip"
