simultaneous connections in PASV mode

[support2]

Below I'm attaching log files when using 2 simultaneous clients transferring a total of 6 files, both for the case of using passive mode and active mode. Looking at the time stamps from the first STOR command until the end, the passive mode takes about 1:46 minutes while the active mode takes only 1:08 minutes. When running with a single connection, both modes take the same amount of 1:46 minutes. When using more than 2 connections, only the time for the active mode decreases while it is impossible to speed up the transfer time for the passive mode. I've run similar experiments with other servers with the same results.

PASSIVE MODE:
======================
DEBUG [FTPClient] 19 Apr 2012 11:19:30.344 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20passive/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp passive\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [FTPClient] 19 Apr 2012 11:19:30.347 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20passive/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp passive\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [FileTransferClient] 19 Apr 2012 11:19:52.248 : Configured client
DEBUG [FTPClient] 19 Apr 2012 11:19:52.248 : Connecting to /63.225.118.8:21
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.501 : 220-FileZilla Server version 0.9.40 beta
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.501 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.501 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [FileTransferClient] 19 Apr 2012 11:19:52.502 : Client connected
DEBUG [FileTransferClient] 19 Apr 2012 11:19:52.502 : Logging in
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.502 : ---> USER Curious
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.583 : 331 Password required for curious
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.584 : ---> PASS ********
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.663 : 230 Logged on
DEBUG [FileTransferClient] 19 Apr 2012 11:19:52.664 : Logged in
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.664 : ---> TYPE I
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.743 : 200 Type set to I
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.744 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.823 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.827 : ---> SYST
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.907 : 215 UNIX emulated by FileZilla
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.911 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.992 : 257 "/" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:19:52.993 : setupDirDetails() returning: /
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:52.993 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.072 : 227 Entering Passive Mode (63,225,118,8,213,235)
INFO [FTPControlSocket] 19 Apr 2012 11:19:53.073 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.214 : ---> LIST
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.296 : 150 Connection accepted
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.297 : 226 Transfer OK
DEBUG [FTPFileFactory] 19 Apr 2012 11:19:53.298 : Confirmed format UNIX
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.301 : ---> MKD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.382 : 257 "/timing_test" created successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.382 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.462 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.463 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.543 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:19:53.543 : setupDirDetails() returning: /timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.543 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.623 : 227 Entering Passive Mode (63,225,118,8,213,236)
INFO [FTPControlSocket] 19 Apr 2012 11:19:53.623 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.766 : ---> LIST
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.847 : 150 Connection accepted
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.848 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.849 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.929 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:19:53.930 : setupDirDetails() returning: /timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:53.930 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.010 : 227 Entering Passive Mode (63,225,118,8,213,237)
INFO [FTPControlSocket] 19 Apr 2012 11:19:54.010 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.154 : ---> LIST
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.235 : 150 Connection accepted
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.236 : 226 Transfer OK
DEBUG [FileTransferClient] 19 Apr 2012 11:19:54.238 : Configured client
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.239 : ---> CWD /
DEBUG [FTPClient] 19 Apr 2012 11:19:54.239 : Connecting to /63.225.118.8:21
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.323 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.324 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.404 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.405 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.407 : 220-FileZilla Server version 0.9.40 beta
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.408 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.408 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [FileTransferClient] 19 Apr 2012 11:19:54.408 : Client connected
DEBUG [FileTransferClient] 19 Apr 2012 11:19:54.408 : Logging in
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.408 : ---> USER Curious
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.486 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:19:54.486 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.486 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.487 : 331 Password required for curious
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.487 : ---> PASS ********
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.567 : 227 Entering Passive Mode (63,225,118,8,213,238)
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.568 : 230 Logged on
INFO [FTPControlSocket] 19 Apr 2012 11:19:54.568 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FileTransferClient] 19 Apr 2012 11:19:54.568 : Logged in
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.568 : ---> TYPE I
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.647 : 200 Type set to I
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.647 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.709 : ---> STOR IMGP2705.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.727 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.727 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.789 : 150 Connection accepted
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.806 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.807 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.886 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:19:54.886 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.886 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:54.966 : 227 Entering Passive Mode (63,225,118,8,213,239)
INFO [FTPControlSocket] 19 Apr 2012 11:19:54.966 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:55.109 : ---> STOR IMGP2704.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:19:55.191 : 150 Connection accepted
DEBUG [FTPClient] 19 Apr 2012 11:20:26.091 : Transferred 22856290 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.186 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.186 : ---> RNFR IMGP2705.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.267 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.267 : ---> RNTO IMGP2705.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.348 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.349 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.429 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.429 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.511 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.511 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.591 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:20:26.591 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.591 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.671 : 227 Entering Passive Mode (63,225,118,8,213,241)
INFO [FTPControlSocket] 19 Apr 2012 11:20:26.671 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.814 : ---> STOR IMGP2706.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:26.895 : 150 Connection accepted
DEBUG [FTPClient] 19 Apr 2012 11:20:28.118 : Transferred 23938054 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.201 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.202 : ---> RNFR IMGP2704.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.281 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.282 : ---> RNTO IMGP2704.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.361 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.361 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.441 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.441 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.521 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.521 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.600 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:20:28.600 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.600 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.680 : 227 Entering Passive Mode (63,225,118,8,213,242)
INFO [FTPControlSocket] 19 Apr 2012 11:20:28.680 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.822 : ---> STOR IMGP2707.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:28.902 : 150 Connection accepted
DEBUG [FTPClient] 19 Apr 2012 11:20:57.617 : Transferred 22158220 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.698 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.698 : ---> RNFR IMGP2706.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.780 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.780 : ---> RNTO IMGP2706.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.861 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.862 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.942 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:57.942 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:58.023 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:58.023 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:58.103 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:20:58.104 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:58.104 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:58.184 : 227 Entering Passive Mode (63,225,118,8,213,243)
INFO [FTPControlSocket] 19 Apr 2012 11:20:58.184 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:58.328 : ---> STOR IMGP2708.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:20:58.409 : 150 Connection accepted
DEBUG [FTPClient] 19 Apr 2012 11:21:02.392 : Transferred 24562100 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.472 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.472 : ---> RNFR IMGP2707.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.552 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.552 : ---> RNTO IMGP2707.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.632 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.632 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.712 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.712 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.792 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.792 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.872 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:21:02.872 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.872 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:02.952 : 227 Entering Passive Mode (63,225,118,8,213,244)
INFO [FTPControlSocket] 19 Apr 2012 11:21:02.952 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:03.093 : ---> STOR IMGP2709.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:03.173 : 150 Connection accepted
DEBUG [FTPClient] 19 Apr 2012 11:21:30.357 : Transferred 22846430 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.451 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.451 : ---> RNFR IMGP2708.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.534 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.534 : ---> RNTO IMGP2708.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.618 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.619 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.712 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.712 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.792 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.792 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.872 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:21:30.872 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.872 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:30.953 : 227 Entering Passive Mode (63,225,118,8,213,245)
INFO [FTPControlSocket] 19 Apr 2012 11:21:30.953 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:31.096 : ---> STOR session.settings.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:31.176 : 150 Connection accepted
DEBUG [FTPClient] 19 Apr 2012 11:21:31.177 : Transferred 2240 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:31.260 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:31.260 : ---> RNFR session.settings.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:31.340 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:31.341 : ---> RNTO session.settings
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:31.422 : 250 file renamed successfully
DEBUG [FTPClient] 19 Apr 2012 11:21:36.153 : Transferred 23352766 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.233 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.234 : ---> RNFR IMGP2709.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.313 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.313 : ---> RNTO IMGP2709.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.393 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.619 : ---> PASV
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.700 : 227 Entering Passive Mode (63,225,118,8,213,246)
INFO [FTPControlSocket] 19 Apr 2012 11:21:36.700 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.842 : ---> STOR upload_done
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:36.922 : 150 Connection accepted
DEBUG [FTPOutputStream] 19 Apr 2012 11:21:36.923 : Transferred 0 bytes from remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:21:37.004 : 226 Transfer OK

ACTIVE MODE
=====================
DEBUG [FTPClient] 19 Apr 2012 11:21:52.439 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20active/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp active\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [FTPClient] 19 Apr 2012 11:21:52.441 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20active/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp active\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [FileTransferClient] 19 Apr 2012 11:22:28.335 : Configured client
DEBUG [FTPClient] 19 Apr 2012 11:22:28.336 : Connecting to /63.225.118.8:21
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.567 : 220-FileZilla Server version 0.9.40 beta
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.567 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.568 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [FileTransferClient] 19 Apr 2012 11:22:28.568 : Client connected
DEBUG [FileTransferClient] 19 Apr 2012 11:22:28.568 : Logging in
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.568 : ---> USER Curious
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.650 : 331 Password required for curious
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.650 : ---> PASS ********
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.731 : 230 Logged on
DEBUG [FileTransferClient] 19 Apr 2012 11:22:28.732 : Logged in
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.732 : ---> TYPE I
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.813 : 200 Type set to I
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.813 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.894 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.898 : ---> SYST
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.979 : 215 UNIX emulated by FileZilla
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:28.983 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.065 : 257 "/" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:22:29.065 : setupDirDetails() returning: /
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.067 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.068 : ---> PORT 192,168,1,101,218,33
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.149 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.149 : ---> LIST
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.232 : 150 Opening data channel for directory list.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.232 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.377 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.378 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.378 : close() succeeded
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.379 : 226 Transfer OK
DEBUG [FTPFileFactory] 19 Apr 2012 11:22:29.380 : Confirmed format UNIX
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.383 : ---> MKD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.463 : 257 "/timing_test" created successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.463 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.545 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.546 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.628 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:22:29.628 : setupDirDetails() returning: /timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.629 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.629 : ---> PORT 192,168,1,101,218,34
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.711 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.711 : ---> LIST
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.792 : 150 Opening data channel for directory list.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.793 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.872 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.872 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:29.872 : close() succeeded
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.873 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.874 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.955 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:22:29.955 : setupDirDetails() returning: /timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.956 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:29.957 : ---> PORT 192,168,1,101,218,35
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.038 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.051 : ---> LIST
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.133 : 150 Opening data channel for directory list.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:30.133 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:30.215 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:30.215 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:30.216 : close() succeeded
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.216 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.218 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.299 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.299 : ---> CWD timing_test
DEBUG [FileTransferClient] 19 Apr 2012 11:22:30.299 : Configured client
DEBUG [FTPClient] 19 Apr 2012 11:22:30.299 : Connecting to /63.225.118.8:21
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.380 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.381 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.463 : 220-FileZilla Server version 0.9.40 beta
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.464 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.464 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [FileTransferClient] 19 Apr 2012 11:22:30.464 : Client connected
DEBUG [FileTransferClient] 19 Apr 2012 11:22:30.464 : Logging in
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.464 : 257 "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.464 : ---> USER Curious
DEBUG [FTPClient] 19 Apr 2012 11:22:30.464 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.466 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.466 : ---> PORT 192,168,1,101,218,37
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.545 : 331 Password required for curious
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.545 : ---> PASS ********
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.547 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.547 : ---> STOR IMGP2705.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.625 : 230 Logged on
DEBUG [FileTransferClient] 19 Apr 2012 11:22:30.625 : Logged in
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.625 : ---> TYPE I
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.628 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:30.628 : Calling accept()
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.705 : 200 Type set to I
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.705 : ---> CWD /
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:30.708 : accept() succeeded
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.785 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.785 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.865 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.865 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.944 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:22:30.945 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.946 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:30.946 : ---> PORT 192,168,1,101,218,38
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:31.027 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:31.046 : ---> STOR IMGP2704.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:31.129 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:31.129 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:31.215 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:51.529 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:51.530 : close() succeeded
DEBUG [FTPClient] 19 Apr 2012 11:22:51.530 : Transferred 22856290 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.632 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.632 : ---> RNFR IMGP2705.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.712 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.712 : ---> RNTO IMGP2705.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.802 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.803 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.901 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:51.901 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.001 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.001 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.081 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:22:52.081 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.083 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.083 : ---> PORT 192,168,1,101,218,41
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.164 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.164 : ---> STOR IMGP2706.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:52.245 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:52.246 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:52.354 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:53.667 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:53.668 : close() succeeded
DEBUG [FTPClient] 19 Apr 2012 11:22:53.668 : Transferred 23938054 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.751 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.751 : ---> RNFR IMGP2704.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.832 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.832 : ---> RNTO IMGP2704.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.912 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.912 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.994 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:53.994 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.082 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.083 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.177 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:22:54.177 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.178 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.179 : ---> PORT 192,168,1,101,218,42
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.259 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.259 : ---> STOR IMGP2707.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:22:54.340 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:54.340 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:22:54.420 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:11.690 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:11.691 : close() succeeded
DEBUG [FTPClient] 19 Apr 2012 11:23:11.691 : Transferred 22158220 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:11.773 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:11.773 : ---> RNFR IMGP2706.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:11.854 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:11.854 : ---> RNTO IMGP2706.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:11.948 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:11.949 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.046 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.046 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.143 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.144 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.242 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:23:12.242 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.244 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.244 : ---> PORT 192,168,1,101,218,43
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.342 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.342 : ---> STOR IMGP2708.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:12.441 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:12.441 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:12.519 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:16.045 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:16.045 : close() succeeded
DEBUG [FTPClient] 19 Apr 2012 11:23:16.045 : Transferred 24562100 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.126 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.126 : ---> RNFR IMGP2707.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.206 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.207 : ---> RNTO IMGP2707.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.292 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.293 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.388 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.388 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.485 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.485 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.582 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:23:16.582 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.584 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.584 : ---> PORT 192,168,1,101,218,44
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.665 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.665 : ---> STOR IMGP2709.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:16.774 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:16.774 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:16.870 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:32.860 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:32.860 : close() succeeded
DEBUG [FTPClient] 19 Apr 2012 11:23:32.861 : Transferred 22846430 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:32.952 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:32.952 : ---> RNFR IMGP2708.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.032 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.032 : ---> RNTO IMGP2708.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.113 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.114 : ---> CWD /
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.195 : 250 CWD successful. "/" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.195 : ---> CWD timing_test
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.302 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.302 : ---> PWD
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.399 : 257 "/timing_test" is current directory.
DEBUG [FTPClient] 19 Apr 2012 11:23:33.399 : Attempt #1
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.401 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.401 : ---> PORT 192,168,1,101,218,45
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.501 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.501 : ---> STOR session.settings.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.598 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:33.598 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:33.680 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:33.682 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:33.683 : close() succeeded
DEBUG [FTPClient] 19 Apr 2012 11:23:33.683 : Transferred 2240 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.794 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.794 : ---> RNFR session.settings.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.890 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.891 : ---> RNTO session.settings
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:33.985 : 250 file renamed successfully
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:37.481 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:37.482 : close() succeeded
DEBUG [FTPClient] 19 Apr 2012 11:23:37.482 : Transferred 23352766 bytes to remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.562 : 226 Transfer OK
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.562 : ---> RNFR IMGP2709.DNG.tmp
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.642 : 350 File exists, ready for destination name.
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.642 : ---> RNTO IMGP2709.DNG
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.724 : 250 file renamed successfully
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.916 : ListenOnAllInterfaces=true
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.916 : ---> PORT 192,168,1,101,218,46
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.998 : 200 Port command successful
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:37.998 : ---> STOR upload_done
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:38.079 : 150 Opening data channel for file transfer.
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:38.079 : Calling accept()
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:38.160 : accept() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:38.160 : closeChild() succeeded
DEBUG [FTPActiveDataSocket] 19 Apr 2012 11:23:38.161 : close() succeeded
DEBUG [FTPOutputStream] 19 Apr 2012 11:23:38.161 : Transferred 0 bytes from remote host
DEBUG [FTPControlSocket] 19 Apr 2012 11:23:38.244 : 226 Transfer OK

[support2]

It might help to log thread names in the logging - it's hard to figure out what is happening. I assume you have each connection in a separate thread.

Yes, each connection is in a separate thread. Below please find the log files with thread information.


PASSIVE MODE
===============
DEBUG [AWT-EventQueue-0_FTPClient] 25 Apr 2012 10:09:39.555 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20passive%20thread/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp passive thread\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [AWT-EventQueue-0_FTPClient] 25 Apr 2012 10:09:39.557 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20passive%20thread/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp passive thread\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:10:03.093 : Configured client
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:10:03.094 : Connecting to /63.225.118.8:21
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.272 : 220-FileZilla Server version 0.9.40 beta
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.273 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.273 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:10:03.274 : Client connected
DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:10:03.274 : Logging in
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.274 : ---> USER Curious
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.354 : 331 Password required for curious
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.354 : ---> PASS ********
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.436 : 230 Logged on
DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:10:03.436 : Logged in
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.436 : ---> TYPE I
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.517 : 200 Type set to I
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.517 : ---> CWD /
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.598 : 250 CWD successful. "/" is current directory.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.621 : ---> SYST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.702 : 215 UNIX emulated by FileZilla
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.706 : ---> PWD
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.787 : 257 "/" is current directory.
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:10:03.787 : setupDirDetails() returning: /
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.787 : ---> PASV
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.868 : 227 Entering Passive Mode (63,225,118,8,204,114)
INFO [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.868 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:03.998 : ---> LIST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.080 : 150 Connection accepted
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.081 : 226 Transfer OK
DEBUG [pool-3-thread-1_FTPFileFactory] 25 Apr 2012 10:10:04.082 : Confirmed format UNIX
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.100 : ---> MKD timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.181 : 257 "/timing_test" created successfully
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.181 : ---> CWD timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.264 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.265 : ---> PWD
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.345 : 257 "/timing_test" is current directory.
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:10:04.345 : setupDirDetails() returning: /timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.345 : ---> PASV
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.428 : 227 Entering Passive Mode (63,225,118,8,204,115)
INFO [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.428 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.556 : ---> LIST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.638 : 150 Connection accepted
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.639 : 226 Transfer OK
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.640 : ---> PWD
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.722 : 257 "/timing_test" is current directory.
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:10:04.722 : setupDirDetails() returning: /timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.722 : ---> PASV
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.803 : 227 Entering Passive Mode (63,225,118,8,204,116)
INFO [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.803 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:04.929 : ---> LIST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.010 : 150 Connection accepted
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.011 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:10:05.013 : Configured client
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.013 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-1_FTPClient] 25 Apr 2012 10:10:05.014 : Connecting to /63.225.118.8:21
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.094 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.094 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.175 : 220-FileZilla Server version 0.9.40 beta
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.175 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.175 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.175 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:10:05.176 : Client connected
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:10:05.176 : Logging in
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.176 : ---> USER Curious
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.176 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.256 : 331 Password required for curious
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.256 : ---> PASS ********
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.257 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-2_FTPClient] 25 Apr 2012 10:10:05.257 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.258 : ---> PASV
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.336 : 230 Logged on
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:10:05.336 : Logged in
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.336 : ---> TYPE I
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.338 : 227 Entering Passive Mode (63,225,118,8,204,117)
INFO [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.338 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.418 : 200 Type set to I
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.418 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.462 : ---> STOR IMGP2705.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.498 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.498 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:05.544 : 150 Connection accepted
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.579 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.579 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.659 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FTPClient] 25 Apr 2012 10:10:05.659 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.660 : ---> PASV
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.741 : 227 Entering Passive Mode (63,225,118,8,204,118)
INFO [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.741 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.865 : ---> STOR IMGP2704.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:05.945 : 150 Connection accepted
DEBUG [SwingWorker-pool-5-thread-2_FTPClient] 25 Apr 2012 10:10:36.776 : Transferred 22856290 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:36.855 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:36.856 : ---> RNFR IMGP2705.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:36.937 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:36.937 : ---> RNTO IMGP2705.DNG
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:10:37.018 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.019 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.099 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.099 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.179 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.179 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.261 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-3_FTPClient] 25 Apr 2012 10:10:37.261 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.262 : ---> PASV
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.343 : 227 Entering Passive Mode (63,225,118,8,204,122)
INFO [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.343 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.468 : ---> STOR IMGP2706.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:10:37.550 : 150 Connection accepted
DEBUG [SwingWorker-pool-5-thread-1_FTPClient] 25 Apr 2012 10:10:38.728 : Transferred 23938054 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:38.808 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:38.809 : ---> RNFR IMGP2704.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:38.888 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:38.889 : ---> RNTO IMGP2704.DNG
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:10:38.970 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:38.971 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.051 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.052 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.133 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.134 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.214 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-4_FTPClient] 25 Apr 2012 10:10:39.214 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.215 : ---> PASV
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.296 : 227 Entering Passive Mode (63,225,118,8,204,123)
INFO [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.297 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.417 : ---> STOR IMGP2707.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:10:39.496 : 150 Connection accepted
DEBUG [SwingWorker-pool-5-thread-3_FTPClient] 25 Apr 2012 10:11:10.014 : Transferred 22158220 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:11:10.094 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:11:10.094 : ---> RNFR IMGP2706.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:11:10.174 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:11:10.174 : ---> RNTO IMGP2706.DNG
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:11:10.254 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.255 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.334 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.334 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.416 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.416 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.496 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-5_FTPClient] 25 Apr 2012 10:11:10.496 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.496 : ---> PASV
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.577 : 227 Entering Passive Mode (63,225,118,8,204,127)
INFO [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.577 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.703 : ---> STOR IMGP2708.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:10.783 : 150 Connection accepted
DEBUG [SwingWorker-pool-5-thread-4_FTPClient] 25 Apr 2012 10:11:15.636 : Transferred 24562100 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:11:15.715 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:11:15.715 : ---> RNFR IMGP2707.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:11:15.795 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:11:15.795 : ---> RNTO IMGP2707.DNG
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:11:15.875 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:15.876 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:15.955 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:15.955 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.044 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.045 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.127 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-6_FTPClient] 25 Apr 2012 10:11:16.127 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.128 : ---> PASV
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.209 : 227 Entering Passive Mode (63,225,118,8,204,128)
INFO [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.209 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.337 : ---> STOR IMGP2709.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:16.418 : 150 Connection accepted
DEBUG [SwingWorker-pool-5-thread-5_FTPClient] 25 Apr 2012 10:11:43.260 : Transferred 22846430 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:43.353 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:43.353 : ---> RNFR IMGP2708.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:43.435 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:43.435 : ---> RNTO IMGP2708.DNG
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:11:43.516 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.517 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.600 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.600 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.681 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.682 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.763 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-7_FTPClient] 25 Apr 2012 10:11:43.763 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.764 : ---> PASV
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.845 : 227 Entering Passive Mode (63,225,118,8,204,132)
INFO [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.845 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:43.971 : ---> STOR session.settings.tmp
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:44.051 : 150 Connection accepted
DEBUG [SwingWorker-pool-5-thread-7_FTPClient] 25 Apr 2012 10:11:44.053 : Transferred 2240 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:44.411 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:44.411 : ---> RNFR session.settings.tmp
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:44.508 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:44.508 : ---> RNTO session.settings
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:11:44.587 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-6_FTPClient] 25 Apr 2012 10:11:49.313 : Transferred 23352766 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:49.392 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:49.392 : ---> RNFR IMGP2709.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:49.472 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:49.472 : ---> RNTO IMGP2709.DNG
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:11:49.553 : 250 file renamed successfully
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:11:49.817 : ---> PASV
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:11:49.898 : 227 Entering Passive Mode (63,225,118,8,204,133)
INFO [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:11:49.899 : Substituting server supplied IP (63.225.118. with remote host IP (63.225.118.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:11:50.023 : ---> STOR upload_done
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:11:50.104 : 150 Connection accepted
DEBUG [pool-3-thread-1_FTPOutputStream] 25 Apr 2012 10:11:50.105 : Transferred 0 bytes from remote host
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:11:50.186 : 226 Transfer OK


ACTIVE MODE
==============
DEBUG [AWT-EventQueue-0_FTPClient] 25 Apr 2012 10:03:01.372 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20active%20thread/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp active thread\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [AWT-EventQueue-0_FTPClient] 25 Apr 2012 10:03:01.374 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/Iconiq%20Studios/Software/Iconiq%20Tagger/ftp%20active%20thread/lib/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH: C:\Users\Curious\Documents\Iconiq Studios\Software\Iconiq Tagger\ftp active thread\Iconiq_Tagger.jar
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:04:02.217 : Configured client
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:04:02.218 : Connecting to /63.225.118.8:21
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.529 : 220-FileZilla Server version 0.9.40 beta
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.529 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.529 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:04:02.530 : Client connected
DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:04:02.530 : Logging in
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.530 : ---> USER Curious
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.610 : 331 Password required for curious
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.610 : ---> PASS ********
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.692 : 230 Logged on
DEBUG [pool-3-thread-1_FileTransferClient] 25 Apr 2012 10:04:02.692 : Logged in
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.692 : ---> TYPE I
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.773 : 200 Type set to I
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.774 : ---> CWD /
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.855 : 250 CWD successful. "/" is current directory.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.858 : ---> SYST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.939 : 215 UNIX emulated by FileZilla
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:02.943 : ---> PWD
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.024 : 257 "/" is current directory.
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:04:03.024 : setupDirDetails() returning: /
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.025 : ListenOnAllInterfaces=true
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.026 : ---> PORT 192,168,1,101,159,9
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.108 : 200 Port command successful
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.108 : ---> LIST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.190 : 150 Opening data channel for directory list.
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:03.190 : Calling accept()
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:03.341 : accept() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:03.352 : closeChild() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:03.352 : close() succeeded
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.352 : 226 Transfer OK
DEBUG [pool-3-thread-1_FTPFileFactory] 25 Apr 2012 10:04:03.354 : Confirmed format UNIX
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.368 : ---> MKD timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.449 : 257 "/timing_test" created successfully
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.449 : ---> CWD timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.530 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.531 : ---> PWD
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.612 : 257 "/timing_test" is current directory.
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:04:03.612 : setupDirDetails() returning: /timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.613 : ListenOnAllInterfaces=true
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.614 : ---> PORT 192,168,1,101,159,10
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.695 : 200 Port command successful
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.695 : ---> LIST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:03.776 : 150 Opening data channel for directory list.
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:03.777 : Calling accept()
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:03.857 : accept() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:04.155 : closeChild() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:04.155 : close() succeeded
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.156 : 226 Transfer OK
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.157 : ---> PWD
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.237 : 257 "/timing_test" is current directory.
DEBUG [pool-3-thread-1_FTPClient] 25 Apr 2012 10:04:04.237 : setupDirDetails() returning: /timing_test
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.238 : ListenOnAllInterfaces=true
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.239 : ---> PORT 192,168,1,101,159,11
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.320 : 200 Port command successful
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.320 : ---> LIST
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.400 : 150 Opening data channel for directory list.
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:04.401 : Calling accept()
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:04.481 : accept() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:04.482 : closeChild() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:04.482 : close() succeeded
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.482 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:04:04.485 : Configured client
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.485 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-1_FTPClient] 25 Apr 2012 10:04:04.485 : Connecting to /63.225.118.8:21
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.565 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.565 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.644 : 220-FileZilla Server version 0.9.40 beta
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.645 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.645 : 220-written by Tim Kosse (Tim.Kosse@gmx.de)
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.645 : 220 Please visit http://sourceforge.net/projects/filezilla/
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:04:04.645 : Client connected
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:04:04.645 : Logging in
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.645 : ---> USER Curious
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.646 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.724 : 331 Password required for curious
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.724 : ---> PASS ********
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.726 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-2_FTPClient] 25 Apr 2012 10:04:04.726 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.728 : ListenOnAllInterfaces=true
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.729 : ---> PORT 192,168,1,101,159,13
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.804 : 230 Logged on
DEBUG [SwingWorker-pool-5-thread-1_FileTransferClient] 25 Apr 2012 10:04:04.804 : Logged in
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.804 : ---> TYPE I
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.809 : 200 Port command successful
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.809 : ---> STOR IMGP2705.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.884 : 200 Type set to I
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.884 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:04.889 : 150 Opening data channel for file transfer.
DEBUG [SwingWorker-pool-5-thread-2_FTPActiveDataSocket] 25 Apr 2012 10:04:04.890 : Calling accept()
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.963 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:04.963 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-2_FTPActiveDataSocket] 25 Apr 2012 10:04:04.968 : accept() succeeded
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.041 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.042 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.119 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-1_FTPClient] 25 Apr 2012 10:04:05.119 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.121 : ListenOnAllInterfaces=true
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.121 : ---> PORT 192,168,1,101,159,14
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.199 : 200 Port command successful
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.199 : ---> STOR IMGP2704.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:05.276 : 150 Opening data channel for file transfer.
DEBUG [SwingWorker-pool-5-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:05.277 : Calling accept()
DEBUG [SwingWorker-pool-5-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:05.360 : accept() succeeded
DEBUG [SwingWorker-pool-5-thread-2_FTPActiveDataSocket] 25 Apr 2012 10:04:24.102 : closeChild() succeeded
DEBUG [SwingWorker-pool-5-thread-2_FTPActiveDataSocket] 25 Apr 2012 10:04:24.103 : close() succeeded
DEBUG [SwingWorker-pool-5-thread-2_FTPClient] 25 Apr 2012 10:04:24.103 : Transferred 22856290 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:24.196 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:24.196 : ---> RNFR IMGP2705.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:24.282 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:24.282 : ---> RNTO IMGP2705.DNG
DEBUG [SwingWorker-pool-5-thread-2_FTPControlSocket] 25 Apr 2012 10:04:24.361 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.362 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.442 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.442 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.522 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.522 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.609 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-3_FTPClient] 25 Apr 2012 10:04:24.609 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.611 : ListenOnAllInterfaces=true
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.611 : ---> PORT 192,168,1,101,159,15
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.692 : 200 Port command successful
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.692 : ---> STOR IMGP2706.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:24.771 : 150 Opening data channel for file transfer.
DEBUG [SwingWorker-pool-5-thread-3_FTPActiveDataSocket] 25 Apr 2012 10:04:24.771 : Calling accept()
DEBUG [SwingWorker-pool-5-thread-3_FTPActiveDataSocket] 25 Apr 2012 10:04:24.852 : accept() succeeded
DEBUG [SwingWorker-pool-5-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:25.584 : closeChild() succeeded
DEBUG [SwingWorker-pool-5-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:04:25.584 : close() succeeded
DEBUG [SwingWorker-pool-5-thread-1_FTPClient] 25 Apr 2012 10:04:25.584 : Transferred 23938054 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:25.663 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:25.663 : ---> RNFR IMGP2704.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:25.753 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:25.753 : ---> RNTO IMGP2704.DNG
DEBUG [SwingWorker-pool-5-thread-1_FTPControlSocket] 25 Apr 2012 10:04:25.833 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:25.834 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:25.912 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:25.912 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:25.989 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:25.990 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:26.068 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-4_FTPClient] 25 Apr 2012 10:04:26.068 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:26.070 : ListenOnAllInterfaces=true
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:26.070 : ---> PORT 192,168,1,101,159,16
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:26.148 : 200 Port command successful
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:26.148 : ---> STOR IMGP2707.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:26.227 : 150 Opening data channel for file transfer.
DEBUG [SwingWorker-pool-5-thread-4_FTPActiveDataSocket] 25 Apr 2012 10:04:26.227 : Calling accept()
DEBUG [SwingWorker-pool-5-thread-4_FTPActiveDataSocket] 25 Apr 2012 10:04:26.311 : accept() succeeded
DEBUG [SwingWorker-pool-5-thread-3_FTPActiveDataSocket] 25 Apr 2012 10:04:43.634 : closeChild() succeeded
DEBUG [SwingWorker-pool-5-thread-3_FTPActiveDataSocket] 25 Apr 2012 10:04:43.634 : close() succeeded
DEBUG [SwingWorker-pool-5-thread-3_FTPClient] 25 Apr 2012 10:04:43.635 : Transferred 22158220 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:43.715 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:43.715 : ---> RNFR IMGP2706.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:43.794 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:43.794 : ---> RNTO IMGP2706.DNG
DEBUG [SwingWorker-pool-5-thread-3_FTPControlSocket] 25 Apr 2012 10:04:43.883 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:43.884 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:43.980 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:43.980 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.077 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.077 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.175 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-5_FTPClient] 25 Apr 2012 10:04:44.175 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.178 : ListenOnAllInterfaces=true
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.178 : ---> PORT 192,168,1,101,159,17
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.272 : 200 Port command successful
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.272 : ---> STOR IMGP2708.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:04:44.352 : 150 Opening data channel for file transfer.
DEBUG [SwingWorker-pool-5-thread-5_FTPActiveDataSocket] 25 Apr 2012 10:04:44.352 : Calling accept()
DEBUG [SwingWorker-pool-5-thread-5_FTPActiveDataSocket] 25 Apr 2012 10:04:44.431 : accept() succeeded
DEBUG [SwingWorker-pool-5-thread-4_FTPActiveDataSocket] 25 Apr 2012 10:04:47.030 : closeChild() succeeded
DEBUG [SwingWorker-pool-5-thread-4_FTPActiveDataSocket] 25 Apr 2012 10:04:47.031 : close() succeeded
DEBUG [SwingWorker-pool-5-thread-4_FTPClient] 25 Apr 2012 10:04:47.031 : Transferred 24562100 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:47.110 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:47.110 : ---> RNFR IMGP2707.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:47.189 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:47.189 : ---> RNTO IMGP2707.DNG
DEBUG [SwingWorker-pool-5-thread-4_FTPControlSocket] 25 Apr 2012 10:04:47.267 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.268 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.345 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.346 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.423 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.423 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.501 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-6_FTPClient] 25 Apr 2012 10:04:47.501 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.503 : ListenOnAllInterfaces=true
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.503 : ---> PORT 192,168,1,101,159,18
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.582 : 200 Port command successful
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.582 : ---> STOR IMGP2709.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:04:47.674 : 150 Opening data channel for file transfer.
DEBUG [SwingWorker-pool-5-thread-6_FTPActiveDataSocket] 25 Apr 2012 10:04:47.674 : Calling accept()
DEBUG [SwingWorker-pool-5-thread-6_FTPActiveDataSocket] 25 Apr 2012 10:04:47.752 : accept() succeeded
DEBUG [SwingWorker-pool-5-thread-5_FTPActiveDataSocket] 25 Apr 2012 10:05:03.712 : closeChild() succeeded
DEBUG [SwingWorker-pool-5-thread-5_FTPActiveDataSocket] 25 Apr 2012 10:05:03.712 : close() succeeded
DEBUG [SwingWorker-pool-5-thread-5_FTPClient] 25 Apr 2012 10:05:03.712 : Transferred 22846430 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:05:03.807 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:05:03.807 : ---> RNFR IMGP2708.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:05:03.905 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:05:03.905 : ---> RNTO IMGP2708.DNG
DEBUG [SwingWorker-pool-5-thread-5_FTPControlSocket] 25 Apr 2012 10:05:03.984 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:03.985 : ---> CWD /
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.081 : 250 CWD successful. "/" is current directory.
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.081 : ---> CWD timing_test
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.161 : 250 CWD successful. "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.161 : ---> PWD
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.241 : 257 "/timing_test" is current directory.
DEBUG [SwingWorker-pool-5-thread-7_FTPClient] 25 Apr 2012 10:05:04.241 : Attempt #1
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.243 : ListenOnAllInterfaces=true
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.243 : ---> PORT 192,168,1,101,159,19
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.323 : 200 Port command successful
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.323 : ---> STOR session.settings.tmp
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.403 : 150 Opening data channel for file transfer.
DEBUG [SwingWorker-pool-5-thread-7_FTPActiveDataSocket] 25 Apr 2012 10:05:04.403 : Calling accept()
DEBUG [SwingWorker-pool-5-thread-7_FTPActiveDataSocket] 25 Apr 2012 10:05:04.481 : accept() succeeded
DEBUG [SwingWorker-pool-5-thread-7_FTPActiveDataSocket] 25 Apr 2012 10:05:04.482 : closeChild() succeeded
DEBUG [SwingWorker-pool-5-thread-7_FTPActiveDataSocket] 25 Apr 2012 10:05:04.482 : close() succeeded
DEBUG [SwingWorker-pool-5-thread-7_FTPClient] 25 Apr 2012 10:05:04.482 : Transferred 2240 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.590 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.590 : ---> RNFR session.settings.tmp
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.687 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.688 : ---> RNTO session.settings
DEBUG [SwingWorker-pool-5-thread-7_FTPControlSocket] 25 Apr 2012 10:05:04.781 : 250 file renamed successfully
DEBUG [SwingWorker-pool-5-thread-6_FTPActiveDataSocket] 25 Apr 2012 10:05:07.341 : closeChild() succeeded
DEBUG [SwingWorker-pool-5-thread-6_FTPActiveDataSocket] 25 Apr 2012 10:05:07.341 : close() succeeded
DEBUG [SwingWorker-pool-5-thread-6_FTPClient] 25 Apr 2012 10:05:07.341 : Transferred 23352766 bytes to remote host
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:05:07.420 : 226 Transfer OK
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:05:07.421 : ---> RNFR IMGP2709.DNG.tmp
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:05:07.499 : 350 File exists, ready for destination name.
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:05:07.499 : ---> RNTO IMGP2709.DNG
DEBUG [SwingWorker-pool-5-thread-6_FTPControlSocket] 25 Apr 2012 10:05:07.579 : 250 file renamed successfully
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:05:07.587 : ListenOnAllInterfaces=true
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:05:07.587 : ---> PORT 192,168,1,101,159,20
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:05:07.666 : 200 Port command successful
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:05:07.666 : ---> STOR upload_done
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:05:07.747 : 150 Opening data channel for file transfer.
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:05:07.747 : Calling accept()
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:05:07.825 : accept() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:05:07.826 : closeChild() succeeded
DEBUG [pool-3-thread-1_FTPActiveDataSocket] 25 Apr 2012 10:05:07.826 : close() succeeded
DEBUG [pool-3-thread-1_FTPOutputStream] 25 Apr 2012 10:05:07.826 : Transferred 0 bytes from remote host
DEBUG [pool-3-thread-1_FTPControlSocket] 25 Apr 2012 10:05:07.906 : 226 Transfer OK

BTW, it does not require a fancy connection pool to duplicate this overall behavior:
You can take any app that FTPs data using a single connection and simply run two separate instances of it at the same time. If the app uses passive mode, the completion times of both instances will add up. However, when the app uses active mode both instances will complete in the same time it takes for a single instance to complete.

[support2]

This might be a limitation in the FTP server used because the source IP address is the same or something like that.

This issue seems to be running deeper than that:
I have tested this with various servers of which all of them let me boost the transmission speed by using multiple simultaneous connections in passive mode using a FileZilla client.

If you're having difficulties replicating the behavior I'm describing, I'm wondering if you could provide me with Java code of some simple vanilla-type mini-app that uses edtFTPj in active mode and runs just as fast when launched as a single instance as when launched as two separate instances. If your app behaves the same with my servers, I should be able to track down which API calls of your app make it behave differently from my app. And in case your app experiences the same problems with my servers, I will know to look for a configuration setting on my servers. Thx.

Oops, I meant to request a sample app that uses passive mode, of course ...

[EDT Support]

Since I'm using rather large files to transfer (about 20 MB), I've increased the value 10000 to 300000 for the ftp buffer size and the progress monitor. In order to flush out the problem, the only change I needed to do to your sample code was to have the second thread start slightly delayed. After all, with a connection pool, it becomes highly unlikely for all threads to start at the same time. So, I've inserted the following code between the two calls to "new Thread() {..."
try {
Thread.sleep(200);
}
catch (Exception e) {}

There seems to be some type of race condition going on, since I can find delays at which the problem disappears (e.g. for delay = 0). In my example, both files have about the same size of 22 MB and take about 45 seconds each to transfer to the server. However, with just the "right" delay between the 2 concurrent threads, they take a total of 83 seconds to upload instead of the expected 45 seconds.

run:
INFO [TestConcurrent] 9 May 2012 16:06:40.966 : Starting upload: /public_html/Curious/ftp/IMGP2704.DNG
DEBUG [FTPClient] 9 May 2012 16:06:40.983 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/NetBeansProjects/DebugFTP/src/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH:

C:\Users\Curious\Documents\NetBeansProjects\DebugFTP\src\edtftpj.jar;C:\Users\Curious\Documents\NetBeansProjects\Debu

gFTP\build\classes;C:\Users\Curious\Documents\NetBeansProjects\DebugFTP\src
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [FTPClient] 9 May 2012 16:06:41.089 : Connecting to ftp.somename.com/67.20.76.108:21
INFO [TestConcurrent] 9 May 2012 16:06:41.167 : Starting upload: /public_html/Curious/ftp/IMGP2705.DNG
DEBUG [FTPClient] 9 May 2012 16:06:41.167 : Class: com.enterprisedt.net.ftp.FTPClient
Location: file:/C:/Users/Curious/Documents/NetBeansProjects/DebugFTP/src/edtftpj.jar
Version: 2.3.0
Build timestamp: 26-Aug-2011 13:52:07 EST
Java version: 1.6.0_26
CLASSPATH:

C:\Users\Curious\Documents\NetBeansProjects\DebugFTP\src\edtftpj.jar;C:\Users\Curious\Documents\NetBeansProjects\Debu

gFTP\build\classes;C:\Users\Curious\Documents\NetBeansProjects\DebugFTP\src
OS name: Windows Vista
OS arch: x86
OS version: 6.0

DEBUG [FTPClient] 9 May 2012 16:06:41.167 : Connecting to ftp.somename.com/67.20.76.108:21
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.340 : 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.341 : 220-You are user number 1 of 1000 allowed.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.341 : 220-Local time is now 14:07. Server port: 21.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.341 : 220-This is a private system - No anonymous login
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.341 : 220-IPv6 connections are also welcome on this server.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.341 : 220 You will be disconnected after 15 minutes of inactivity.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.342 : ---> USER somename
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.391 : 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.391 : 220-You are user number 2 of 1000 allowed.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.391 : 220-Local time is now 14:07. Server port: 21.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.392 : 220-This is a private system - No anonymous login
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.392 : 220-IPv6 connections are also welcome on this server.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.392 : 220 You will be disconnected after 15 minutes of inactivity.
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.392 : ---> USER somename
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.452 : 331 User somename OK. Password required
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.452 : ---> PASS ********
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.502 : 331 User somename OK. Password required
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.502 : ---> PASS ********
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.653 : 230 OK. Current restricted directory is /
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.653 : ---> PWD
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.723 : 230 OK. Current restricted directory is /
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.723 : ---> PWD
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.764 : 257 "/" is your current location
DEBUG [FTPClient] 9 May 2012 16:06:41.764 : Attempt #1
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.764 : ---> PASV
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.833 : 257 "/" is your current location
DEBUG [FTPClient] 9 May 2012 16:06:41.834 : Attempt #1
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.834 : ---> PASV
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.874 : 227 Entering Passive Mode (67,20,76,108,199,1)
INFO [FTPControlSocket] 9 May 2012 16:06:41.874 : Substituting server supplied IP (67.20.76.108) with remote host IP

(67.20.76.108)
DEBUG [FTPControlSocket] 9 May 2012 16:06:41.948 : 227 Entering Passive Mode (67,20,76,108,148,97)
INFO [FTPControlSocket] 9 May 2012 16:06:41.948 : Substituting server supplied IP (67.20.76.108) with remote host IP

(67.20.76.108)
DEBUG [FTPControlSocket] 9 May 2012 16:06:42.053 : ---> STOR /public_html/Curious/ftp/IMGP2704.DNG
DEBUG [FTPControlSocket] 9 May 2012 16:06:42.130 : ---> STOR /public_html/Curious/ftp/IMGP2705.DNG
DEBUG [FTPControlSocket] 9 May 2012 16:06:42.171 : 150 Accepted data connection
DEBUG [FTPControlSocket] 9 May 2012 16:06:42.249 : 150 Accepted data connection
INFO [ProgressMonitor] 9 May 2012 16:06:48.281 : /public_html/Curious/ftp/IMGP2705.DNG 3009643 bytes
INFO [ProgressMonitor] 9 May 2012 16:06:53.650 : /public_html/Curious/ftp/IMGP2704.DNG 3008390 bytes
INFO [ProgressMonitor] 9 May 2012 16:06:53.959 : /public_html/Curious/ftp/IMGP2705.DNG 6019788 bytes
INFO [ProgressMonitor] 9 May 2012 16:06:59.662 : /public_html/Curious/ftp/IMGP2705.DNG 9029854 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:04.019 : /public_html/Curious/ftp/IMGP2704.DNG 6017329 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:05.349 : /public_html/Curious/ftp/IMGP2705.DNG 12040227 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:11.042 : /public_html/Curious/ftp/IMGP2705.DNG 15050171 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:14.727 : /public_html/Curious/ftp/IMGP2704.DNG 9027052 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:16.730 : /public_html/Curious/ftp/IMGP2705.DNG 18059689 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:22.413 : /public_html/Curious/ftp/IMGP2705.DNG 21067692 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:25.785 : /public_html/Curious/ftp/IMGP2704.DNG 12036521 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:26.161 : /public_html/Curious/ftp/IMGP2705.DNG 22935622 bytes
DEBUG [FTPClient] 9 May 2012 16:07:26.161 : Transferred 22935622 bytes to remote host
DEBUG [FTPControlSocket] 9 May 2012 16:07:26.279 : 226-File successfully transferred
DEBUG [FTPControlSocket] 9 May 2012 16:07:26.279 : 226 44.034 seconds (measured here), 506.89 Kbytes per second
DEBUG [FTPControlSocket] 9 May 2012 16:07:26.279 : ---> QUIT
DEBUG [FTPControlSocket] 9 May 2012 16:07:26.389 : 221-Goodbye. You uploaded 22321 and downloaded 0 kbytes.
DEBUG [FTPControlSocket] 9 May 2012 16:07:26.389 : 221 Logout.
INFO [TestConcurrent] 9 May 2012 16:07:26.390 : Finished upload: /public_html/Curious/ftp/IMGP2705.DNG
INFO [ProgressMonitor] 9 May 2012 16:07:34.960 : /public_html/Curious/ftp/IMGP2704.DNG 15046136 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:44.339 : /public_html/Curious/ftp/IMGP2704.DNG 18055138 bytes
INFO [ProgressMonitor] 9 May 2012 16:07:54.093 : /public_html/Curious/ftp/IMGP2704.DNG 21063149 bytes
INFO [ProgressMonitor] 9 May 2012 16:08:05.312 : /public_html/Curious/ftp/IMGP2704.DNG 24015550 bytes
DEBUG [FTPClient] 9 May 2012 16:08:05.312 : Transferred 24015550 bytes to remote host
DEBUG [FTPControlSocket] 9 May 2012 16:08:05.423 : 226-File successfully transferred
DEBUG [FTPControlSocket] 9 May 2012 16:08:05.423 : 226 83.258 seconds (measured here), 280.78 Kbytes per second
DEBUG [FTPControlSocket] 9 May 2012 16:08:05.424 : ---> QUIT
DEBUG [FTPControlSocket] 9 May 2012 16:08:05.532 : 221-Goodbye. You uploaded 23377 and downloaded 0 kbytes.
DEBUG [FTPControlSocket] 9 May 2012 16:08:05.532 : 221 Logout.
INFO [TestConcurrent] 9 May 2012 16:08:05.533 : Finished upload: /public_html/Curious/ftp/IMGP2704.DNG
BUILD SUCCESSFUL (total time: 1 minute 24 seconds)

Have you been able to replicate my symptoms?

[EDT Support]