Sometimes 450 Internal Error renaming the file
Posted: 2019-05-17 12:13
We have an ftp server which gets files from a batch-process on another server.
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?
Anybody have any idea if this is the cause or any other way to fix it?
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"