For details on "Sending special code: 12", see
https://winscp.net/eng/docs/message_special_code

ok thanks for letting me know. Did the log I e-mailed appear to be in debug level 1?

lravens19 wrote:

How do I change the error log to debug level 1? I highlighted the stored session, clicked edit, then choose logging and selected "text Log" and under logging options I change the Logging level to Debug and specified a file to log to and clicked save and saved the stored session. Is that all I need to do?

Logging setting is global, not per-session. So you so not need to edit/save session at all.

How do I change the error log to debug level 1? I highlighted the stored session, clicked edit, then choose logging and selected "text Log" and under logging options I change the Logging level to Debug and specified a file to log to and clicked save and saved the stored session. Is that all I need to do?

Can you please email me a log file at debug 1 level?

Winscp: 4.3.4 (I was running 4.3.3 and the error was occuring so I upgraded to 4.3.4 and the problem still persists)
OS: Windows Server 2000 SP4
Transfer Protocol: SFTP
Using Scripting/automation method



I'm trying to determine if the error is in my script or if its on the clients FTP server. I'm trying to transfer two files with this script:
"echo option batch abort > D:\FileTransfers\medasset1.txt
echo open medassets >> D:\FileTransfers\medasset1.txt
echo put SJH_MEDASSETS_WALK_%year%%month%%day%.txt.pgp >> D:\FileTransfers\medasset1.txt
echo put SJH_MEDASSETS_DCHG_%year%%month%%day%.txt.pgp >> D:\FileTransfers\medasset1.txt
echo synchronize local D:\FileTransfers\Medassetswalk\synch\ / -permissions=777 >> D:\FileTransfers\medasset1.txt
echo bye >> D:\FileTransfers\medasset1.txt

rem run SFTP
c:\winnt\winscp.exe /log=D:\FileTransfers\WalkDCHGftplog%year%%month%%day%.txt /console /script=D:\FileTransfers\medasset1.txt "

The first file it tries to transfer it disconnects and reconnects several times and finally transfers the file but then never gets to the second file. Is the issue on my side and I need to specify more options than option batch abort? Or is the issue on the client ftp server side?

Here is my log below:


. 2011-09-15 09:30:54.777 --------------------------------------------------------------------------
. 2011-09-15 09:30:54.777 WinSCP Version 4.3.4 (Build 1428) (OS 5.0.2195 Service Pack 4)
. 2011-09-15 09:30:54.777 Configuration: c:\winnt\WinSCP.ini
. 2011-09-15 09:30:54.777 Login time: Thursday, September 15, 2011 09:30:54 AM
. 2011-09-15 09:30:54.777 --------------------------------------------------------------------------
. 2011-09-15 09:30:54.777 Session name: medassets (Stored session)
. 2011-09-15 09:30:54.777 Host name: sftp.medassets.com (Port: 22)
. 2011-09-15 09:30:54.777 User name: xxxxxxxx (Password: Yes, Key file: No)
. 2011-09-15 09:30:54.793 Tunnel: No
. 2011-09-15 09:30:54.793 Transfer Protocol: SFTP (SCP)
. 2011-09-15 09:30:54.793 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2011-09-15 09:30:54.793 Proxy: none
. 2011-09-15 09:30:54.793 SSH protocol version: 2; Compression: No
. 2011-09-15 09:30:54.793 Bypass authentication: No
. 2011-09-15 09:30:54.793 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2011-09-15 09:30:54.793 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2011-09-15 09:30:54.793 SSH Bugs: A,-,-,-,-,-,-,-,-
. 2011-09-15 09:30:54.793 SFTP Bugs: -,-
. 2011-09-15 09:30:54.793 Return code variable: Autodetect; Lookup user groups: Yes
. 2011-09-15 09:30:54.793 Shell: default
. 2011-09-15 09:30:54.793 EOL: 0, UTF: 2
. 2011-09-15 09:30:54.793 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2011-09-15 09:30:54.793 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2011-09-15 09:30:54.793 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2011-09-15 09:30:54.793 Cache directory changes: Yes, Permanent: Yes
. 2011-09-15 09:30:54.793 DST mode: 1
. 2011-09-15 09:30:54.793 --------------------------------------------------------------------------
. 2011-09-15 09:30:54.793 Looking up host "sftp.medassets.com"
. 2011-09-15 09:30:54.856 Connecting to 204.11.6.138 port 22
. 2011-09-15 09:30:55.199 Server version: SSH-2.0-1.36_sshlib GlobalSCAPE
. 2011-09-15 09:30:55.199 We believe remote version has SSH-1 ignore bug
. 2011-09-15 09:30:55.199 We believe remote version ignores SSH-2 maximum packet size
. 2011-09-15 09:30:55.199 We believe remote version has SSH-2 ignore bug
. 2011-09-15 09:30:55.199 Using SSH protocol version 2
. 2011-09-15 09:30:55.199 We claim version: SSH-2.0-WinSCP_release_4.3.4
. 2011-09-15 09:30:55.324 Doing Diffie-Hellman group exchange
. 2011-09-15 09:30:55.590 Doing Diffie-Hellman key exchange with hash SHA-1
. 2011-09-15 09:30:55.777 Host key fingerprint is:
. 2011-09-15 09:30:55.777 ssh-dss 1024 ce:6e:57:32:18:31:c0:a4:5d:76:23:8d:c6:1c:de:1b
. 2011-09-15 09:30:55.777 Initialised AES-128 CBC client->server encryption
. 2011-09-15 09:30:55.777 Initialised HMAC-SHA1 client->server MAC algorithm
. 2011-09-15 09:30:55.777 Initialised AES-128 CBC server->client encryption
. 2011-09-15 09:30:55.777 Initialised HMAC-SHA1 server->client MAC algorithm
! 2011-09-15 09:30:56.090 Using username "xxxxxxxx".
. 2011-09-15 09:30:56.246 Prompt (7, SSH password, , &Password: )
. 2011-09-15 09:30:56.246 Using stored password.
. 2011-09-15 09:30:56.262 Sent password
. 2011-09-15 09:30:56.387 Access granted
. 2011-09-15 09:30:56.496 Opened channel for session
. 2011-09-15 09:30:56.731 Started a shell/command
. 2011-09-15 09:30:56.746 --------------------------------------------------------------------------
. 2011-09-15 09:30:56.746 Using SFTP protocol.
. 2011-09-15 09:30:56.762 Doing startup conversation with host.
> 2011-09-15 09:30:56.762 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2011-09-15 09:30:56.856 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2011-09-15 09:30:56.856 SFTP version 3 negotiated.
. 2011-09-15 09:30:56.856 We believe the server has signed timestamps bug
. 2011-09-15 09:30:56.856 We will use UTF-8 strings for status messages only
. 2011-09-15 09:30:56.856 Getting current directory name.
. 2011-09-15 09:30:56.856 Getting real path for '.'
> 2011-09-15 09:30:56.856 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2011-09-15 09:30:56.934 Type: SSH_FXP_NAME, Size: 23, Number: 16
. 2011-09-15 09:30:56.934 Real path is '/'
. 2011-09-15 09:30:56.934 Startup conversation with host finished.
< 2011-09-15 09:30:56.934 Script: Active session: [1] medassets
> 2011-09-15 09:30:56.934 Script: put SJH_MEDASSETS_WALK_20110915.txt.pgp
. 2011-09-15 09:30:56.934 Copying 1 files/directories to remote directory "/"
. 2011-09-15 09:30:56.934 PrTime: Yes; PrRO: Yes; Rght: rwxrwxrwx; PrR: Yes (Yes); FnCs: N; RIC: 01; Resume: N (102400); CalcS: No; Mask:
. 2011-09-15 09:30:56.934 TM: M; ClAr: No; CPS: 0; ExclM(No):
. 2011-09-15 09:30:56.934 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2011-09-15 09:30:56.934 File: "SJH_MEDASSETS_WALK_20110915.txt.pgp"
. 2011-09-15 09:30:56.981 Copying "SJH_MEDASSETS_WALK_20110915.txt.pgp" to remote directory started.
. 2011-09-15 09:30:56.981 Binary transfer mode selected.
. 2011-09-15 09:30:56.981 Opening remote file.
> 2011-09-15 09:30:56.981 Type: SSH_FXP_OPEN, Size: 61, Number: 259
< 2011-09-15 09:30:57.074 Type: SSH_FXP_HANDLE, Size: 10, Number: 259
> 2011-09-15 09:30:57.074 Type: SSH_FXP_WRITE, Size: 4118, Number: 774
. 2011-09-15 09:31:09.121 65 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2011-09-15 09:31:09.121 Type: SSH_FXP_CLOSE, Size: 10, Number: 9732
> 2011-09-15 09:31:09.121 Type: SSH_FXP_SETSTAT, Size: 61, Number: 521
< 2011-09-15 09:31:09.153 Type: SSH_FXP_STATUS, Size: 21, Number: 8710
< 2011-09-15 09:31:10.075 Type: SSH_FXP_STATUS, Size: 21, Number: 8966
< 2011-09-15 09:31:10.793 Type: SSH_FXP_STATUS, Size: 21, Number: 9222
. 2011-09-15 09:31:26.075 Waiting for data timed out, asking user what to do.
. 2011-09-15 09:31:26.075 Asking user:
. 2011-09-15 09:31:26.075 Host is not communicating for 15 seconds.
. 2011-09-15 09:31:26.075
. 2011-09-15 09:31:26.075 Wait for another 15 seconds? ()
. 2011-09-15 09:31:26.106 Attempt to close connection due to fatal exception:
* 2011-09-15 09:31:26.106 Terminated by user.
. 2011-09-15 09:31:26.106 Closing connection.
. 2011-09-15 09:31:26.106 Sending special code: 12
. 2011-09-15 09:31:26.106 Sent EOF message
. 2011-09-15 09:31:26.106 Fatal error while disposing the SFTP queue.
. 2011-09-15 09:31:26.137 Connection was lost, asking what to do.
. 2011-09-15 09:31:26.137 Asking user:
. 2011-09-15 09:31:26.137 Terminated by user. ()
< 2011-09-15 09:31:31.606 Script: Searching for host...
. 2011-09-15 09:31:31.606 Looking up host "sftp.medassets.com"
. 2011-09-15 09:31:31.606 Connecting to 204.11.6.138 port 22
< 2011-09-15 09:31:31.669 Script: Connecting to host...
. 2011-09-15 09:31:32.341 Server version: SSH-2.0-1.36_sshlib GlobalSCAPE
. 2011-09-15 09:31:32.341 We believe remote version has SSH-1 ignore bug
. 2011-09-15 09:31:32.341 We believe remote version ignores SSH-2 maximum packet size
. 2011-09-15 09:31:32.341 We believe remote version has SSH-2 ignore bug
. 2011-09-15 09:31:32.341 Using SSH protocol version 2
. 2011-09-15 09:31:32.341 We claim version: SSH-2.0-WinSCP_release_4.3.4
. 2011-09-15 09:31:32.403 Doing Diffie-Hellman group exchange
. 2011-09-15 09:31:32.653 Doing Diffie-Hellman key exchange with hash SHA-1
< 2011-09-15 09:31:32.825 Script: Authenticating...
. 2011-09-15 09:31:32.856 Host key fingerprint is:
. 2011-09-15 09:31:32.856 ssh-dss 1024 ce:6e:57:32:18:31:c0:a4:5d:76:23:8d:c6:1c:de:1b
. 2011-09-15 09:31:32.856 Initialised AES-128 CBC client->server encryption
. 2011-09-15 09:31:32.856 Initialised HMAC-SHA1 client->server MAC algorithm
. 2011-09-15 09:31:32.856 Initialised AES-128 CBC server->client encryption
. 2011-09-15 09:31:32.856 Initialised HMAC-SHA1 server->client MAC algorithm
! 2011-09-15 09:31:33.169 Using username "xxxxxxxx".
< 2011-09-15 09:31:33.169 Script: Using username "xxxxxxxx".
. 2011-09-15 09:31:33.559 Prompt (7, SSH password, , &Password: )
. 2011-09-15 09:31:33.559 Using stored password.
< 2011-09-15 09:31:33.559 Script: Authenticating with pre-entered password.
. 2011-09-15 09:31:33.575 Sent password
. 2011-09-15 09:31:35.341 Access granted
. 2011-09-15 09:31:35.403 Opened channel for session
. 2011-09-15 09:31:35.684 Started a shell/command
< 2011-09-15 09:31:35.684 Script: Authenticated.
. 2011-09-15 09:31:35.684 Doing startup conversation with host.
< 2011-09-15 09:31:35.684 Script: Starting the session...
> 2011-09-15 09:31:35.684 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2011-09-15 09:31:35.763 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2011-09-15 09:31:35.763 SFTP version 3 negotiated.
. 2011-09-15 09:31:35.763 We believe the server has signed timestamps bug
. 2011-09-15 09:31:35.763 We will use UTF-8 strings for status messages only
< 2011-09-15 09:31:35.763 Script: Reading remote directory...
. 2011-09-15 09:31:35.763 Changing directory to "/".
. 2011-09-15 09:31:35.763 Getting real path for '/'
> 2011-09-15 09:31:35.763 Type: SSH_FXP_REALPATH, Size: 10, Number: 10000
< 2011-09-15 09:31:36.091 Type: SSH_FXP_NAME, Size: 23, Number: 10000
. 2011-09-15 09:31:36.091 Real path is '/'
. 2011-09-15 09:31:36.091 Trying to open directory "/".
> 2011-09-15 09:31:36.091 Type: SSH_FXP_LSTAT, Size: 10, Number: 10247
< 2011-09-15 09:31:36.231 Type: SSH_FXP_ATTRS, Size: 21, Number: 10247
. 2011-09-15 09:31:36.231 Getting current directory name.
. 2011-09-15 09:31:36.231 Startup conversation with host finished.
< 2011-09-15 09:31:36.231 Script: Session started.
. 2011-09-15 09:31:36.231 File: "SJH_MEDASSETS_WALK_20110915.txt.pgp"
. 2011-09-15 09:31:36.263 Copying "SJH_MEDASSETS_WALK_20110915.txt.pgp" to remote directory started.
. 2011-09-15 09:31:36.263 Binary transfer mode selected.
. 2011-09-15 09:31:36.263 Opening remote file.
> 2011-09-15 09:31:36.263 Type: SSH_FXP_OPEN, Size: 61, Number: 10499
< 2011-09-15 09:31:36.888 Type: SSH_FXP_HANDLE, Size: 10, Number: 10499
> 2011-09-15 09:31:36.888 Type: SSH_FXP_WRITE, Size: 4118, Number: 11014
. 2011-09-15 09:31:53.700 87 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2011-09-15 09:31:53.700 Type: SSH_FXP_CLOSE, Size: 10, Number: 22788
> 2011-09-15 09:31:53.700 Type: SSH_FXP_SETSTAT, Size: 61, Number: 10761
< 2011-09-15 09:31:53.732 Type: SSH_FXP_STATUS, Size: 21, Number: 21766
< 2011-09-15 09:31:53.935 Type: SSH_FXP_STATUS, Size: 21, Number: 22022
< 2011-09-15 09:31:54.138 Type: SSH_FXP_STATUS, Size: 21, Number: 22278
< 2011-09-15 09:31:54.622 Type: SSH_FXP_STATUS, Size: 21, Number: 22534
< 2011-09-15 09:31:54.622 Type: SSH_FXP_STATUS, Size: 21, Number: 22788
< 2011-09-15 09:31:54.622 Status code: 0
< 2011-09-15 09:31:54.622 Type: SSH_FXP_STATUS, Size: 21, Number: 10761
< 2011-09-15 09:31:54.622 Status code: 0
. 2011-09-15 09:31:54.622 Script: Failed
. 2011-09-15 09:31:54.622 Closing connection.
. 2011-09-15 09:31:54.622 Sending special code: 12
. 2011-09-15 09:31:54.638 Sent EOF message