STOR command randomly pending

Here you can submit your bug reports.
Post Reply
fred9176
Posts: 23
Joined: Mon Jul 02, 2012 9:29 am

STOR command randomly pending

Post by fred9176 »

Hi,

We upgraded to the last version 4.7.1 a month ago and since then many users have problem to upload files.

In the log, if can see that a STOR command randomly never ends and generally, when clients try to send another command, they receive a "451 Another command is currently pending, please try again later." message.

Most uploaded files are small jpg files and a client can send many of these files before encountering the problem.

I checked one month of logs and this message was never seen before upgrade.

Do you have any idea on how to solve this problem ?

Thanks,

Fred
fred9176
Posts: 23
Joined: Mon Jul 02, 2012 9:29 am

Re: STOR command randomly pending

Post by fred9176 »

I forgot to say that I'm using Wing FTP with Debian and that the destiantion folders are NFS mounts.
fred9176
Posts: 23
Joined: Mon Jul 02, 2012 9:29 am

Re: STOR command randomly pending

Post by fred9176 »

Hi,

Do you have any news about this problem ?
This occurs frequently since we upgraded and it's very annoying.

Can I easily do a rollback to the previsous version ?

Regards,

Fred
FTP
Site Admin
Posts: 2072
Joined: Tue Sep 29, 2009 6:09 am

Re: STOR command randomly pending

Post by FTP »

OK, please check the server logs, before the message "451 Another command is currently pending, please try again later.", you must can find an unfinished FTP transfer command, if you can't figure it out, please paste a full FTP session logs.
fred9176
Posts: 23
Joined: Mon Jul 02, 2012 9:29 am

Re: STOR command randomly pending

Post by fred9176 »

Hi,

Thank you for your answer.

In fact, after the pending STOP, nothing happens.

Here is an extract of the session where no command was sent after the STOR command (this customer is using a batch to send files one after the other and I guess the batch was waiting for the STOR command to finish before sending another file)

Code: Select all

[02] Tue, 17 May 2016 10:51:44 (0028642) Connected from xxx.xxx.xxx.xxx (local address xxx.xxx.xxx.xxx, port 21)
[04] Tue, 17 May 2016 10:51:44 (0028642) 220 Welcome to ftp.**********.com
[03] Tue, 17 May 2016 10:51:44 (0028642) USER **********
[04] Tue, 17 May 2016 10:51:44 (0028642) 331 Password required for **********
[03] Tue, 17 May 2016 10:51:44 (0028642) PASS **********
[04] Tue, 17 May 2016 10:51:44 (0028642) 230 User ********** logged in.
[03] Tue, 17 May 2016 10:51:44 (0028642) PASV
[04] Tue, 17 May 2016 10:51:44 (0028642) 227 Entering Passive Mode (xxx,xxx,xxx,xxx,40,154)
[03] Tue, 17 May 2016 10:51:44 (0028642) DELE /492764/492764_303592.jpg
[04] Tue, 17 May 2016 10:51:44 (0028642) 550 Invalid file name
[03] Tue, 17 May 2016 10:51:44 (0028642) TYPE I
[04] Tue, 17 May 2016 10:51:44 (0028642) 200 Type set to I
[03] Tue, 17 May 2016 10:51:44 (0028642) PORT xxx,xxx,xxx,xxx,166,77
[04] Tue, 17 May 2016 10:51:44 (0028642) 200 Port command successful
[03] Tue, 17 May 2016 10:51:44 (0028642) STOR /492764/492764_303592.jpg
[04] Tue, 17 May 2016 10:51:44 (0028642) 150 Opening BINARY mode data connection for file transfer.
[04] Tue, 17 May 2016 10:51:45 (0028642) 226 File received ok.Transfer bytes:77059Bytes;Average speed is:75.253KB/s
... many successfull STOR commands ...
[03] Tue, 17 May 2016 10:55:54 (0028642) PASV
[04] Tue, 17 May 2016 10:55:54 (0028642) 227 Entering Passive Mode (xxx,xxx,xxx,xxx,41,43)
[03] Tue, 17 May 2016 10:55:54 (0028642) DELE /246601/246601_29045-1.jpg
[04] Tue, 17 May 2016 10:55:54 (0028642) 550 Invalid file name
[03] Tue, 17 May 2016 10:55:54 (0028642) TYPE I
[04] Tue, 17 May 2016 10:55:54 (0028642) 200 Type set to I
[03] Tue, 17 May 2016 10:55:54 (0028642) PORT xxx,xxx,xxx,xxx,234,209
[04] Tue, 17 May 2016 10:55:54 (0028642) 200 Port command successful
[03] Tue, 17 May 2016 10:55:54 (0028642) STOR /246601/246601_29045-1.jpg
[02] Tue, 17 May 2016 11:00:54 (0028642) Closed session,disconnected from xxx.xxx.xxx.xxx
And her is another custormer with a batch wich send a delete command after two minutes without result and which generates a 451 error :

Code: Select all

[02] Wed, 20 Apr 2016 16:54:00 (0031707) Connected from xxx.xxx.xxx.xxx (local address xxx.xxx.xxx.xxx, port 21)
[04] Wed, 20 Apr 2016 16:54:00 (0031707) 220 Welcome to **********
[03] Wed, 20 Apr 2016 16:54:00 (0031707) USER *********
[04] Wed, 20 Apr 2016 16:54:00 (0031707) 331 Password required for ******
[03] Wed, 20 Apr 2016 16:54:00 (0031707) PASS **********
[04] Wed, 20 Apr 2016 16:54:00 (0031707) 230 User ********** logged in.
[03] Wed, 20 Apr 2016 16:54:01 (0031707) PASV
[04] Wed, 20 Apr 2016 16:54:01 (0031707) 227 Entering Passive Mode (xxx,xxx,xxx,xxx,238,190)
[03] Wed, 20 Apr 2016 16:54:01 (0031707) TYPE I
[04] Wed, 20 Apr 2016 16:54:01 (0031707) 200 Type set to I
[03] Wed, 20 Apr 2016 16:54:01 (0031707) STOR AV1427-1.jpg
[04] Wed, 20 Apr 2016 16:54:01 (0031707) 150 Opening BINARY mode data connection for file transfer.
[04] Wed, 20 Apr 2016 16:54:01 (0031707) 226 File received ok.Transfer bytes:85252Bytes;Average speed is:83.254KB/s
[03] Wed, 20 Apr 2016 16:54:01 (0031707) SIZE AV1427-1.jpg
[04] Wed, 20 Apr 2016 16:54:01 (0031707) 213 85252
[03] Wed, 20 Apr 2016 16:54:32 (0031707) PASV
[04] Wed, 20 Apr 2016 16:54:32 (0031707) 227 Entering Passive Mode (xxx,xxx,xxx,xxx,240,70)
[03] Wed, 20 Apr 2016 16:54:32 (0031707) STOR vv920-2.jpg
[04] Wed, 20 Apr 2016 16:54:32 (0031707) 150 Opening BINARY mode data connection for file transfer.
[04] Wed, 20 Apr 2016 16:54:32 (0031707) 226 File received ok.Transfer bytes:77558Bytes;Average speed is:75.740KB/s
[03] Wed, 20 Apr 2016 16:54:32 (0031707) SIZE vv920-2.jpg
[04] Wed, 20 Apr 2016 16:54:32 (0031707) 213 77558
... many successfull STOR commands ...
[03] Wed, 20 Apr 2016 16:54:32 (0031707) PASV
[04] Wed, 20 Apr 2016 16:54:32 (0031707) 227 Entering Passive Mode (xxx,xxx,xxx,xxx,240,71)
[03] Wed, 20 Apr 2016 16:54:32 (0031707) STOR vv920-3.jpg
[03] Wed, 20 Apr 2016 16:56:02 (0031707) DELE vk3092-1.jpg
[04] Wed, 20 Apr 2016 16:56:02 (0031707) 451 Another command is currently pending, please try again later.
...
[03] Wed, 20 Apr 2016 16:56:03 (0031707) QUIT
[04] Wed, 20 Apr 2016 16:56:03 (0031707) 451 Another command is currently pending, please try again later.
[04] Wed, 20 Apr 2016 16:56:03 (0031707) 221 Goodbye.
[02] Wed, 20 Apr 2016 16:56:03 (0031707) Closed session,disconnected from xxx.xxx.xxx.xxx
As you can see, in both case, there is no error or disconnection.

And this really started to happen after upgrade and with different customers.
FTP
Site Admin
Posts: 2072
Joined: Tue Sep 29, 2009 6:09 am

Re: STOR command randomly pending

Post by FTP »

Yes, because you did not finish the file uploading (command "STOR"), so you can't execute another FTP command. It is caused by the FTP client software mostly, because most popular FTP client software will use multiple threads to execute multiple FTP commands at the same time.
fred9176
Posts: 23
Joined: Mon Jul 02, 2012 9:29 am

Re: STOR command randomly pending

Post by fred9176 »

The customers who reported this problem are using batches to send files to our ftp server. THey don't use regular ftp client, so I don't think they can upgrade.

And these customers are connecting to our ftp since many years without problems. They have this problem since we upgraded wftpserver.

The only way for us to check that point is to downgrade Wftpserver. Is it possible ?
Post Reply