Start a new topic

Upload timeout during plugin copy

Just installed CrushFTP on a MS Server 2008 as a service. Using FileZilla, 20 500 byte files were uploaded to the server. After 8 or so files, the server stops the upload. FileZilla timeouts and restarts the upload. The problem appears to be that CrushFTP stops the uploads when it executes the plugins to copy and move each uploaded file. Is there an asynchronous option available for the plugins? If not, these 500 byte files should not take but a few seconds to copy/move. Instead, the 25 second timeout is consistently occurring. Snippets from both logs are pasted below. Note the success of the files before and after ...2054.zip, which fails with a timeout. Status: Starting upload of C:\webs\users\stat\statAdmin\ftp\xferout\UM-A10339-20170417052125.ZIP Status: File transfer successful, transferred 550 bytes in 1 second Status: Starting upload of C:\webs\users\stat\statAdmin\ftp\xferout\UM-A10339-20170417052054.ZIP Command: PASV Response: 227 Entering Passive Mode (128,90,206,9,240,163) Command: STOR UM-A10339-20170417052054.ZIP Error: Connection timed out after 20 seconds of inactivity Error: File transfer failed Status: Disconnected from server Status: Connecting to 128.90.206.9:21... Status: Connection established, waiting for welcome message... Status: Logged in Status: Starting upload of C:\webs\users\stat\statAdmin\ftp\xferout\UM-A10339-20170417052054.ZIP Status: Retrieving directory listing of "/xferin"... Status: File transfer successful, transferred 555 bytes in 1 second Status: Starting upload of C:\webs\users\stat\statAdmin\ftp\xferout\UM-A10339-20170417052021.ZIP Status: File transfer successful, transferred 552 bytes in 1 second *************************************************************************************** 106 STOR|04/17/2017 22:48:13.170|[FTP:3974:slogin:69.20.59.14] WROTE: *226 Transfer complete. MD5=b510a8e7c533241259c8335789ab6937 ("/xferin/UM-A10339-20170417052125.ZIP" 550) STOR* 107 STOR|04/17/2017 22:48:13.170|[3974:slogin:69.20.59.14] WROTE: *Adding UM-A10339-20170417052125.ZIP to event tracking.*108 PASV|04/17/2017 22:48:13.446|[FTP:3974:slogin:69.20.59.14] READ : *PASV * 109 PASV|04/17/2017 22:48:13.447|[FTP:3974:slogin:69.20.59.14] WROTE: *227 Entering Passive Mode (128,90,206,9,240,163)* 110 STOR|04/17/2017 22:48:13.486|[FTP:3974:slogin:69.20.59.14] READ : *STOR UM-A10339-20170417052054.ZIP* 111 EVENT|04/17/2017 22:48:16.009|Grouping event on id:default 112 EVENT|04/17/2017 22:48:16.009|Event:PLUGIN Copy to Process:|null:getStackTrace:-1|Events.java:doEventPlugin:1003|Events.java:run:642|null:run:-1 113 EVENT|04/17/2017 22:48:16.009|Event:PLUGIN Copy to Process:CrushTask:8FTwykm7OX: 114 SERVER|tasks.Task.:CrushTask items size=1 115 SERVER|tasks.CopyTask.Move:Attempting rename move /C:/webs/users/stat/statAdmin/ftp/xferin/UM-A10339-20170417052511.ZIP to /C:/webs/users/stat/statAdmin/Process/UM-A10339-20170417052511.ZIP116 EVENT|04/17/2017 22:48:19.093|Grouping event on id:default 117 EVENT|04/17/2017 22:48:19.093|Event:PLUGIN Copy to Process:|null:getStackTrace:-1|Events.java:doEventPlugin:1003|Events.java:run:642|null:run:-1 118 EVENT|04/17/2017 22:48:19.094|Event:PLUGIN Copy to Process:CrushTask:8FTwykm7OX: 119 SERVER|tasks.Task.:CrushTask items size=1 120 SERVER|tasks.CopyTask.Move:Attempting rename move /C:/webs/users/stat/statAdmin/ftp/xferin/UM-A10339-20170417052440.ZIP to /C:/webs/users/stat/statAdmin/Process/UM-A10339-20170417052440.ZIP121 POST|04/17/2017 126 EVENT|04/17/2017 22:48:22.164|Grouping event on id:default 127 EVENT|04/17/2017 22:48:22.164|Event:PLUGIN Copy to Process:|null:getStackTrace:-1|Events.java:doEventPlugin:1003|Events.java:run:642|null:run:-1 128 EVENT|04/17/2017 22:48:22.165|Event:PLUGIN Copy to Process:CrushTask:8FTwykm7OX: 129 SERVER|tasks.Task.:CrushTask items size=1 130 SERVER|tasks.CopyTask.Move:Attempting rename move /C:/webs/users/stat/statAdmin/ftp/xferin/UM-A10339-20170417052408.ZIP to /C:/webs/users/stat/statAdmin/Process/UM-A10339-20170417052408.ZIP131 EVENT|04/17/2017 22:48:25.229|Grouping event on id:default 132 EVENT|04/17/2017 22:48:25.229|Event:PLUGIN Copy to Process:|null:getStackTrace:-1|Events.java:doEventPlugin:1003|Events.java:run:642|null:run:-1 133 EVENT|04/17/2017 22:48:25.230|Event:PLUGIN Copy to Process:CrushTask:8FTwykm7OX: 134 SERVER|tasks.Task.:CrushTask items size=1135 SERVER|tasks.CopyTask.Move:Attempting rename move /C:/webs/users/stat/statAdmin/ftp/xferin/UM-A10339-20170417052332.ZIP to /C:/webs/users/stat/statAdmin/Process/UM-A10339-20170417052332.ZIP136 EVENT|04/17/2017 22:48:28.298|Grouping event on id:default 137 EVENT|04/17/2017 22:48:28.298|Event:PLUGIN Copy to Process:|null:getStackTrace:-1|Events.java:doEventPlugin:1003|Events.java:run:642|null:run:-1 138 EVENT|04/17/2017 22:48:28.299|Event:PLUGIN Copy to Process:CrushTask:8FTwykm7OX: 139 SERVER|tasks.Task.:CrushTask items size=1 140 SERVER|tasks.CopyTask.Move:Attempting rename move /C:/webs/users/stat/statAdmin/ftp/xferin/UM-A10339-20170417052300.ZIP to /C:/webs/users/stat/statAdmin/Process/UM-A10339-20170417052300.ZIP141 EVENT|04/17/2017 22:48:31.368|Grouping event on id:default 142 EVENT|04/17/2017 22:48:31.368|Event:PLUGIN Copy to Process:|null:getStackTrace:-1|Events.java:doEventPlugin:1003|Events.java:run:642|null:run:-1 143 EVENT|04/17/2017 22:48:31.368|Event:PLUGIN Copy to Process:CrushTask:8FTwykm7OX: 144 SERVER|tasks.Task.:CrushTask items size=1 145 SERVER|tasks.CopyTask.Move:Attempting rename move /C:/webs/users/stat/statAdmin/ftp/xferin/UM-A10339-20170417052228.ZIP to /C:/webs/users/stat/statAdmin/Process/UM-A10339-20170417052228.ZIP 146 ACCEPT|04/17/2017 22:48:33.596|[FTP:lookup:21][3975] Accepting connection from: 69.20.59.14:58503 147 USER|04/17/2017 22:48:33.627|[FTP:3975::69.20.59.14] READ : *USER slogin* 148 USER|04/17/2017 22:48:33.627|[FTP:3975:slogin:69.20.59.14] WROTE: *331 Username OK. Need password.* 149 USER|04/17/2017 22:48:33.705|[FTP:3975:slogin:69.20.59.14] WROTE: *230 Password OK. Connected. logged in*150 EVENT|04/17/2017 22:48:34.439|Grouping event on id:default 151 EVENT|04/17/2017 22:48:34.439|Event:PLUGIN Copy to Process:|null:getStackTrace:-1|Events.java:doEventPlugin:1003|Events.java:run:642|null:run:-1 152 EVENT|04/17/2017 22:48:34.440|Event:PLUGIN Copy to Process:CrushTask:8FTwykm7OX: 153 SERVER|tasks.Task.:CrushTask items size=1 154 SERVER|tasks.CopyTask.Move:Attempting rename move /C:/webs/users/stat/statAdmin/ftp/xferin/UM-A10339-20170417052157.ZIP to /C:/webs/users/stat/statAdmin/Process/UM-A10339-20170417052157.ZIP155 EVENT|04/17/2017 22:48:37.500|Matched event dir:/ftp/xferin/UM-A10339-20170417052125.ZIP starts with:/xferin/ 156 QUIT|04/17/2017 22:48:37.534|[FTP:3974:slogin:69.20.59.14] *Disconnected.* 157 STOR|04/17/2017 22:48:37.536|[FTP:3974:slogin:69.20.59.14] WROTE: *150 Opening BINARY mode data connection. Ready to write file . "/xferin/UM-A10339-20170417052054.ZIP") S T O R* 158 STOR|04/17/2017 22:48:37.538|[FTP:3974:slogin:69.20.59.14] WROTE: *226 Transfer complete. MD5=516f94cff754919f577b2dc44090387b ("/xferin/UM-A10339-20170417052054.ZIP" 555) STOR* 159 STOR|04/17/2017 22:48:37.567|[3974:slogin:69.20.59.14] WROTE: *Adding UM-A10339-20170417052054.ZIP to event tracking.* 160 EVENT|04/17/2017 22:48:37.567|Matched event dir:/ftp/xferin/UM-A10339-20170417052054.ZIP starts with:/xferin/ 161 CWD|04/17/2017 22:48:37.578|[FTP:3975:slogin:69.20.59.14] READ : *CWD /xferin* 162 CWD|04/17/2017 22:48:37.580|[FTP:3975:slogin:69.20.59.14] WROTE: *250 "/xferin/" CWD command successful.* 163 TYPE|04/17/2017 22:48:37.612|[FTP:3975:slogin:69.20.59.14] READ : *TYPE I* 164 TYPE|04/17/2017 22:48:37.612|[FTP:3975:slogin:69.20.59.14] WROTE: *200 Command ok : Binary type selected.* 165 PASV|04/17/2017 22:48:37.674|[FTP:3975:slogin:69.20.59.14] READ : *PASV * 1
1 Comment

The event has a checkbox for auto, Async and not async. Enable async. Thanks, Ben
Login to post a comment