Post a reply

Before posting, please read how to report bug or request support effectively.

Bug reports without an attached log file are usually useless.

Options
Add an Attachment

If you do not want to add an Attachment to your Post, please leave the Fields blank.

(maximum 10 MB; please compress large files; only common media, archive, text and programming file formats are allowed)

Options

Topic review

vikrantislav

also getting invalid memory access in script session

DOS window which started the scripted session reports invalid memory access error. here is a sanitized version of the relevant portions of log file. error appears to happen when issuing the "close" command. script works fine with winscp versions 4.2.4 and 4.2.6. have not tried version 4.2.7.

. 2010-08-13 09:25:37.251 --------------------------------------------------------------------------

. 2010-08-13 09:25:37.251 WinSCP Version 4.2.8 (Build 818) (OS 5.2.3790 Service Pack 2)
. 2010-08-13 09:25:37.251 Login time: Friday, August 13, 2010 9:25:37 AM
. 2010-08-13 09:25:37.251 --------------------------------------------------------------------------
. 2010-08-13 09:25:37.251 Session name: asdf-at-asdf
. 2010-08-13 09:25:37.251 Host name: ftp.remotehost.com (Port: 21)
. 2010-08-13 09:25:37.251 User name: asdf (Password: Yes, Key file: No)
. 2010-08-13 09:25:37.251 Tunnel: No
. 2010-08-13 09:25:37.251 Transfer Protocol: FTP
. 2010-08-13 09:25:37.251 Ping type: C, Ping interval: 15 sec; Timeout: 35 sec
. 2010-08-13 09:25:37.251 Proxy: none
. 2010-08-13 09:25:37.251 FTP: FTPS: None; Passive: Yes [Force IP: No]
. 2010-08-13 09:25:37.251 Local directory: d:\a\b\DownloadTemp\, Remote directory: home, Update: No, Cache: Yes
. 2010-08-13 09:25:37.251 Cache directory changes: Yes, Permanent: No
. 2010-08-13 09:25:37.251 DST mode: 1
. 2010-08-13 09:25:37.251 --------------------------------------------------------------------------
. 2010-08-13 09:25:37.313 Connecting to ftp.asdf.com ...
. 2010-08-13 09:25:37.360 Connected with ftp.asdf.com. Waiting for welcome message...
< 2010-08-13 09:25:37.392 220 ftp.asdf.com X2 WS_FTP Server 7.5(18296975)
> 2010-08-13 09:25:37.392 USER asdf
< 2010-08-13 09:25:37.438 331 Enter password
> 2010-08-13 09:25:37.438 PASS ********
< 2010-08-13 09:25:37.954 230-User logged in
< 2010-08-13 09:25:37.954  You have logged onto asdf's secure FTP server.
< 2010-08-13 09:25:37.954 230 User logged in
> 2010-08-13 09:25:37.954 SYST
< 2010-08-13 09:25:37.985 215 UNIX
> 2010-08-13 09:25:37.985 FEAT
< 2010-08-13 09:25:38.017 211-Extensions supported
< 2010-08-13 09:25:38.017     SIZE
< 2010-08-13 09:25:38.017     XMD5
< 2010-08-13 09:25:38.017     XSHA1
< 2010-08-13 09:25:38.017     XSHA256
< 2010-08-13 09:25:38.017     XSHA512
< 2010-08-13 09:25:38.017     XQUOTA
< 2010-08-13 09:25:38.017     LANG EN, ES, FR, GE
< 2010-08-13 09:25:38.017     MDTM
< 2010-08-13 09:25:38.017     MLST size*;type*;perm*;create*;modify*;
< 2010-08-13 09:25:38.017     REST STREAM
< 2010-08-13 09:25:38.017     TVFS
< 2010-08-13 09:25:38.017     UTF8
< 2010-08-13 09:25:38.017     AUTH SSL;TLS-P;
< 2010-08-13 09:25:38.017     PBSZ
< 2010-08-13 09:25:38.017     PROT C;P;
< 2010-08-13 09:25:38.017 211 end
. 2010-08-13 09:25:38.017 Connected
. 2010-08-13 09:25:38.017 Got reply 1 to the command 1
. 2010-08-13 09:25:38.017 --------------------------------------------------------------------------
. 2010-08-13 09:25:38.017 Using FTP protocol.
. 2010-08-13 09:25:38.017 Doing startup conversation with host.
> 2010-08-13 09:25:38.017 PWD
< 2010-08-13 09:25:38.048 257 "/asdf" is current directory
. 2010-08-13 09:25:38.048 Got reply 1 to the command 16
. 2010-08-13 09:25:38.048 Getting current directory name.
. 2010-08-13 09:25:38.048 Startup conversation with host finished.
<snip>
> 2010-08-13 09:25:40.860 LIST -a
< 2010-08-13 09:25:41.032 125 Transferring directory
< 2010-08-13 09:25:41.032 226 Transfer completed
. 2010-08-13 09:25:41.032 drwxr-x---  2 asdf      System            0 Aug 13 14:25 .
. 2010-08-13 09:25:41.032 drwxr-x---  2 asdf      System            0 Aug 13 03:25 ..
. 2010-08-13 09:25:41.032 drwxr-x---  2 asdf      System            0 Jul 13  2010 6-28-10
. 2010-08-13 09:25:41.032 drwxr-x---  2 asdf      System            0 Aug 13 14:25 old
. 2010-08-13 09:25:41.032 Directory listing successful
. 2010-08-13 09:25:41.032 Got reply 1 to the command 2
. 2010-08-13 09:25:41.032 Session upkeep
. 2010-08-13 09:25:41.032 Session upkeep
> 2010-08-13 09:25:41.032 Script: close
. 2010-08-13 09:25:41.032 Got reply 1004 to the command 2
. 2010-08-13 09:25:41.032 Disconnected from server
martin

Re: I had the same error

malopez wrote:

I just upgraded the WinSCP from 4.2.7 to 4.2.8 compilation 818. I did not any other changes. This morning when I check the log files I watch the "violation memory access". However, the files were correctly sent to the NAS Server.

I have sent you an email.
malopez

I had the same error

I have a Windows script that send a backup file to a remote NAS Server. This script is scheduled to execute at night.

I just upgraded the WinSCP from 4.2.7 to 4.2.8 compilation 818. I did not any other changes. This morning when I check the log files I watch the "violation memory access". However, the files were correctly sent to the NAS Server.

Environment:
Source Server.: Windows 2003 Server SE sp2 + winSCP 4.2.8
Remote Server.: FreeNAS 0.7.1 Shere.

An extract of windows script is:
SOME NOT IMPORTANT CODE. ASSIGN VALUES TO THE VARIABLES 

-----------------------------------------------------------
   REM -- WE GENERATE THE TEMPORAL SCRIPT TO PASS TO WINSCP
   
   Echo option batch on                   >%ArchivoTemporal%
   Echo option confirm off                >>%ArchivoTemporal%
   IF "%TransmisionAscii%" EQU "ASCII" (
      Echo option transfer ascii            >>%ArchivoTemporal%
   ) ELSE (
      Echo option transfer binary            >>%ArchivoTemporal%
   )
   Echo open sftp://xxx@xxx.xxx.xxx.xxx %HostKEY%         >>%ArchivoTemporal%
   Echo put %fileSource% %RemotePath%/%RemoteFileName%      >>%ArchivoTemporal%
   
   Echo close                        >>%ArchivoTemporal%
   Echo exit                        >>%ArchivoTemporal%

   REM -- CALLING TO WINSCP...
   
   CALL WINSCP.COM /script=%ArchivoTemporal% >>%archivoLOG% 2>&1
   IF %errorlevel% EQU 1 GOTO ERROR_TRANSFERENCIA
   Echo ********************************************************************************* >>%archivoLOG% 2>&1
   Echo * %date%_%time% Transferencia correcta!! Proceso %0 Terminado *>>%archivoLOG% 2>&1
   Echo ********************************************************************************* >>%archivoLOG% 2>&1
   DEL /Q %ArchivoTemporal%   >>%archivoLOG% 2>&1
   GOTO FIN


:ERROR_TRANSFERENCIA
   Set MensajeError=¡¡ATENCION¡¡ ERROR EN LA TRANSFERENCIA DE ARCHIVOS. ERRORLEVEL=%ERRORLEVEL%. PROCESO %0 ABORTADO
--------------------------------------------------------------------------------------------------------
CODE FOR SENDING AN EMAIL ALERT TO THE ADMINISTRATOR.
--------------------------------------------------------------------------------------------------------
:FIN


The WinSCP script is:
option batch on

option confirm off                
option transfer binary            
open sftp://xxx@xxx.xxx.xxx.xxx -hostkey="X:\...\xxx"   
put X:\...\xxx.zip /mnt/.../.../xxx.zip   
close
exit


The log file of my Windows script
---

batch           on       
confirm         off       
transfer        binary   
Buscando servidor...                  /*Searching server...   
Conectando al servidor...               /*Connecting server...
Autenticando...                     /*Logging...
Usando el nombre de usuario "xxxx".            /*Using user "xxxx"
Autentificando con clave publica "imported-openssh-key".   /*Authenticating with public key "imported-openssh-key"
Autenticado.                     /*Authentification successful
Iniciando la sesion...                  /*Starting session
Leyendo directorio remoto...               /*Reading remote directory...
Sesion iniciada.                  /*Session Started
Sesion activa: [1] xxxx@xxx.xxx.xxx.xxx            /*Active session: [1] xxxx@xxx.xxx.xxx.xxx
X:\...\xxx.zip |     267355 KiB | 3450,8 KiB/s | binary | 100%
No hay sesion.                     /*No session
Violacion de acceso a memoria               /*Memory Access violation
* 06/08/2010_10:01:53,47 ¡¡ATENCION¡¡ ERROR EN LA TRANSFERENCIA DE ARCHIVOS. ERRORLEVEL=1. PROCESO ... ABORTADO /*Message sent by my script. errorlevel=1


The script ends with an errorlevel=1 due to WinSCP return code

The WinSCP log file is similar to the others:
. 2010-08-06 10:07:17.834 --------------------------------------------------------------------------

. 2010-08-06 10:07:17.834 WinSCP Version 4.2.8 (compilacion 818) (OS 5.2.3790 Service Pack 2)
. 2010-08-06 10:07:17.834 Login time: viernes, 06 de agosto de 2010 10:07:17
. 2010-08-06 10:07:17.834 --------------------------------------------------------------------------
. 2010-08-06 10:07:17.834 Session name: xxxx@xxx.xxx.xxx.xxx
. 2010-08-06 10:07:17.834 Host name: xxx.xxx.xxx.xxx (Port: 22)
. 2010-08-06 10:07:17.834 User name: xxxx (Password: No, Key file: Yes)
. 2010-08-06 10:07:17.834 Tunnel: No
. 2010-08-06 10:07:17.834 Transfer Protocol: SFTP
. 2010-08-06 10:07:17.834 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2010-08-06 10:07:17.834 Proxy: none
. 2010-08-06 10:07:17.834 SSH protocol version: 2; Compression: No
. 2010-08-06 10:07:17.834 Bypass authentication: No
. 2010-08-06 10:07:17.834 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2010-08-06 10:07:17.834 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2010-08-06 10:07:17.834 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2010-08-06 10:07:17.834 SFTP Bugs: -,-
. 2010-08-06 10:07:17.834 Return code variable: Autodetect; Lookup user groups: Yes
. 2010-08-06 10:07:17.834 Shell: default
. 2010-08-06 10:07:17.834 EOL: 0, UTF: 2
. 2010-08-06 10:07:17.834 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2010-08-06 10:07:17.834 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2010-08-06 10:07:17.834 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2010-08-06 10:07:17.834 Cache directory changes: Yes, Permanent: Yes
. 2010-08-06 10:07:17.834 DST mode: 1
. 2010-08-06 10:07:17.834 --------------------------------------------------------------------------
. 2010-08-06 10:07:17.834 Looking up host "xxx.xxx.xxx.xxx"
. 2010-08-06 10:07:17.834 Connecting to xxx.xxx.xxx.xxx port 22
. 2010-08-06 10:07:17.849 Server version: SSH-2.0-OpenSSH_5.2p1-hpn13v6 FreeBSD-openssh-portable-overwrite-base-5.2.p1_2,1
. 2010-08-06 10:07:17.849 We believe remote version has SSH-2 ignore bug
. 2010-08-06 10:07:17.849 Using SSH protocol version 2
. 2010-08-06 10:07:17.849 We claim version: SSH-2.0-WinSCP_release_4.2.8
. 2010-08-06 10:07:17.849 Doing Diffie-Hellman group exchange
. 2010-08-06 10:07:17.943 Doing Diffie-Hellman key exchange with hash SHA-1
. 2010-08-06 10:07:18.209 Host key fingerprint is:
. 2010-08-06 10:07:18.209 ssh-dss 2048 xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx
. 2010-08-06 10:07:18.209 Initialised AES-256 SDCTR client->server encryption
. 2010-08-06 10:07:18.209 Initialised HMAC-SHA1 client->server MAC algorithm
. 2010-08-06 10:07:18.209 Initialised AES-256 SDCTR server->client encryption
. 2010-08-06 10:07:18.209 Initialised HMAC-SHA1 server->client MAC algorithm
. 2010-08-06 10:07:18.302 Reading private key file "xxxx.ppk"
! 2010-08-06 10:07:18.302 Using username "xxxx".
. 2010-08-06 10:07:18.334 Offered public key
. 2010-08-06 10:07:18.334 Offer of public key accepted
! 2010-08-06 10:07:18.334 Authenticating with public key "imported-openssh-key"
. 2010-08-06 10:07:18.959 Access granted
. 2010-08-06 10:07:18.974 Opened channel for session
. 2010-08-06 10:07:19.068 Started a shell/command
. 2010-08-06 10:07:19.068 --------------------------------------------------------------------------
. 2010-08-06 10:07:19.068 Using SFTP protocol.
. 2010-08-06 10:07:19.084 Doing startup conversation with host.
> 2010-08-06 10:07:19.084 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2010-08-06 10:07:19.084 Type: SSH_FXP_VERSION, Size: 95, Number: -1
. 2010-08-06 10:07:19.084 SFTP version 3 negotiated.
. 2010-08-06 10:07:19.084 Unknown server extension posix-rename@openssh.com="1"
. 2010-08-06 10:07:19.084 Unknown server extension statvfs@openssh.com="2"
. 2010-08-06 10:07:19.084 Unknown server extension fstatvfs@openssh.com="2"
. 2010-08-06 10:07:19.084 We believe the server has signed timestamps bug
. 2010-08-06 10:07:19.084 We will use UTF-8 strings for status messages only
. 2010-08-06 10:07:19.084 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2010-08-06 10:07:19.084 Getting current directory name.
. 2010-08-06 10:07:19.084 Getting real path for '.'
> 2010-08-06 10:07:19.084 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2010-08-06 10:07:19.084 Type: SSH_FXP_NAME, Size: 29, Number: 16
. 2010-08-06 10:07:19.084 Real path is '/mnt'
. 2010-08-06 10:07:19.084 Startup conversation with host finished.
< 2010-08-06 10:07:19.084 Script: Sesion activa: [1] xxxx@xxx.xxx.xxx.xxx
> 2010-08-06 10:07:19.084 Script: put X:\...\xxx.zip.md5 /mnt/.../.../xxx.zip.md5   
. 2010-08-06 10:07:19.084 Copying 1 files/directories to remote directory "/mnt/.../.../"
. 2010-08-06 10:07:19.084   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 01; Resume: S (102400); CalcS: No; Mask: Evaluacion.bkf.zip.md5
. 2010-08-06 10:07:19.084   TM: A; ClAr: No; CPS: 0; ExclM(No):
. 2010-08-06 10:07:19.084   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2010-08-06 10:07:19.084 File: "X:\...\xxx.zip.md5"
. 2010-08-06 10:07:19.084 Copying "X:\...\xxx.zip.md5" to remote directory started.
. 2010-08-06 10:07:19.084 Ascii transfer mode selected.
. 2010-08-06 10:07:19.084 Opening remote file.
> 2010-08-06 10:07:19.084 Type: SSH_FXP_OPEN, Size: 69, Number: 259
< 2010-08-06 10:07:19.084 Type: SSH_FXP_HANDLE, Size: 13, Number: 259
> 2010-08-06 10:07:19.084 Type: SSH_FXP_WRITE, Size: 58, Number: 774
> 2010-08-06 10:07:19.084 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
> 2010-08-06 10:07:19.084 Type: SSH_FXP_SETSTAT, Size: 65, Number: 521
< 2010-08-06 10:07:19.084 Type: SSH_FXP_STATUS, Size: 24, Number: 774
< 2010-08-06 10:07:19.084 Status code: 0
< 2010-08-06 10:07:19.084 Type: SSH_FXP_STATUS, Size: 24, Number: 1028
< 2010-08-06 10:07:19.084 Status code: 0
< 2010-08-06 10:07:19.084 Type: SSH_FXP_STATUS, Size: 24, Number: 521
< 2010-08-06 10:07:19.084 Status code: 0
> 2010-08-06 10:07:19.084 Script: close                        
. 2010-08-06 10:07:19.084 Closing connection.
. 2010-08-06 10:07:19.099 Sending special code: 12
. 2010-08-06 10:07:19.099 Sent EOF message


Thanks in advance, and congratulations... WinSCP is really a great software. :wink:
martin

Alternate wrote:

I have an identical problem. All runs file, but on closing connection it error 12.

What error do you mean? There's no error in the log.
Alternate

I have an identical problem. All runs file, but on closing connection it error 12.



> 2010-07-28 12:58:57.609 Type: SSH_FXP_SETSTAT, Size: 53, Number: 1033
. 2010-07-28 12:58:57.609 Sent 57 bytes
. 2010-07-28 12:58:57.609 There are 0 bytes remaining in the send buffer
. 2010-07-28 12:58:57.609 Detected network event
. 2010-07-28 12:58:57.609 Received 28 bytes (0)
. 2010-07-28 12:58:57.609 Read 4 bytes (24 pending)
. 2010-07-28 12:58:57.609 Read 24 bytes (0 pending)
< 2010-07-28 12:58:57.609 Type: SSH_FXP_STATUS, Size: 24, Number: 1033
< 2010-07-28 12:58:57.609 Status code: 0
. 2010-07-28 12:58:57.625 Session upkeep
> 2010-07-28 12:58:57.625 Script: close
. 2010-07-28 12:58:57.625 Closing connection.
. 2010-07-28 12:58:57.625 Sending special code: 12
. 2010-07-28 12:58:57.625 Sent EOF message
toper

Re: Invalid access to memory

Okay, email sent.
martin

Re: Invalid access to memory

Can you send me an email, so I can send you back a debug version of WinSCP to track the problem? Please include link back to this topic in your email. Also note in this topic that you have sent the email. Thanks.

You will find my address (if you log in) in my forum profile.
t0per

Invalid access to memory

Hello

I have encountered an 'Invalid access to memory' error when running the sftp session.
The error is displayed on 'exit' command - see last line of the script below.
The problem is that script exists with code 12 so when I automate it, it is reported as a failure. When I check the results, all seems to be fine.


The environment is as follows:

windows 2008 64 bit
winscp 4.2.8 build 818

the executed batch file:
SETLOCAL EnableDelayedExpansion

pushd E:\BIASDW_ETL\config\brel_sftp
winscp.com /console /script=winscp_script.txt /log=sftp.log /parameter %date:-=%


the winscp_script.txt file:
option batch on

option confirm off

open sftp://xxxQ@xxx.xxx.xxx.xxx:22 -privatekey=PRIVATE.ppk -hostkey="ssh-dss 1024 xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx"

cd /Usr/xxx/IN
lcd \\host01\download

get file1_%1%.csv file1.csv
get file2_%1%.csv file2.csv
get file3_%1%.csv file3.csv
get file4_%1%.csv file4.csv

close
exit


The log file:
. 2010-07-21 15:03:24.358 --------------------------------------------------------------------------

. 2010-07-21 15:03:24.358 WinSCP Version 4.2.8 (Build 818) (OS 6.0.6002 Service Pack 2)
. 2010-07-21 15:03:24.358 Login time: 21 lipiec 2010 15:03:24
. 2010-07-21 15:03:24.358 --------------------------------------------------------------------------
. 2010-07-21 15:03:24.359 Session name: xxxxxxxxxxx
. 2010-07-21 15:03:24.359 Host name: xxxxxxxxxx (Port: 22)
. 2010-07-21 15:03:24.359 User name: xxxxxxxxxx (Password: No, Key file: Yes)
. 2010-07-21 15:03:24.359 Tunnel: No
. 2010-07-21 15:03:24.359 Transfer Protocol: SFTP
. 2010-07-21 15:03:24.359 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2010-07-21 15:03:24.359 Proxy: none
. 2010-07-21 15:03:24.359 SSH protocol version: 2; Compression: No
. 2010-07-21 15:03:24.359 Bypass authentication: No
. 2010-07-21 15:03:24.359 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2010-07-21 15:03:24.359 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2010-07-21 15:03:24.359 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2010-07-21 15:03:24.359 SFTP Bugs: -,-
. 2010-07-21 15:03:24.359 Return code variable: Autodetect; Lookup user groups: Yes
. 2010-07-21 15:03:24.359 Shell: default
. 2010-07-21 15:03:24.359 EOL: 0, UTF: 2
. 2010-07-21 15:03:24.359 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2010-07-21 15:03:24.359 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2010-07-21 15:03:24.359 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2010-07-21 15:03:24.359 Cache directory changes: Yes, Permanent: Yes
. 2010-07-21 15:03:24.359 DST mode: 1
. 2010-07-21 15:03:24.359 --------------------------------------------------------------------------
. 2010-07-21 15:03:24.359 Looking up host "xxxxxxxxxx"
. 2010-07-21 15:03:24.359 Connecting to xxxxxxxxxx port 22
. 2010-07-21 15:03:24.363 Server version: SSH-2.0-1.36_sshlib GlobalSCAPE
. 2010-07-21 15:03:24.363 We believe remote version ignores SSH-2 maximum packet size
. 2010-07-21 15:03:24.363 We believe remote version has SSH-2 ignore bug
. 2010-07-21 15:03:24.363 Using SSH protocol version 2
. 2010-07-21 15:03:24.363 We claim version: SSH-2.0-WinSCP_release_4.2.8
. 2010-07-21 15:03:24.364 Doing Diffie-Hellman group exchange
. 2010-07-21 15:03:24.548 Doing Diffie-Hellman key exchange with hash SHA-1
. 2010-07-21 15:03:24.732 Host key fingerprint is:
. 2010-07-21 15:03:24.732 ssh-dss 1024 xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
. 2010-07-21 15:03:24.732 Initialised AES-256 CBC client->server encryption
. 2010-07-21 15:03:24.732 Initialised HMAC-SHA1 client->server MAC algorithm
. 2010-07-21 15:03:24.732 Initialised AES-256 CBC server->client encryption
. 2010-07-21 15:03:24.732 Initialised HMAC-SHA1 server->client MAC algorithm
. 2010-07-21 15:03:24.861 Reading private key file "PRIVATE.ppk"
! 2010-07-21 15:03:24.861 Using username "xxx".
! 2010-07-21 15:03:24.862 Further authentication required
. 2010-07-21 15:03:24.862 Further authentication required
. 2010-07-21 15:03:24.863 Offered public key
. 2010-07-21 15:03:24.864 Offer of public key accepted
! 2010-07-21 15:03:24.864 Authenticating with public key "rsa-key-20100714"
. 2010-07-21 15:03:24.926 Access granted
. 2010-07-21 15:03:24.927 Opened channel for session
. 2010-07-21 15:03:24.970 Started a shell/command
. 2010-07-21 15:03:24.970 --------------------------------------------------------------------------
. 2010-07-21 15:03:24.970 Using SFTP protocol.
. 2010-07-21 15:03:24.970 Doing startup conversation with host.
> 2010-07-21 15:03:24.971 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2010-07-21 15:03:24.972 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2010-07-21 15:03:24.972 SFTP version 3 negotiated.
. 2010-07-21 15:03:24.972 We believe the server has signed timestamps bug
. 2010-07-21 15:03:24.972 We will use UTF-8 strings for status messages only
. 2010-07-21 15:03:24.972 Getting current directory name.
. 2010-07-21 15:03:24.972 Getting real path for '.'
> 2010-07-21 15:03:24.972 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2010-07-21 15:03:24.973 Type: SSH_FXP_NAME, Size: 47, Number: 16
. 2010-07-21 15:03:24.973 Real path is '/Usr/xxxxxx'
. 2010-07-21 15:03:24.973 Startup conversation with host finished.
< 2010-07-21 15:03:24.973 Script: Active session: [1] xxx@xxxxxxxxxxxxxxx
> 2010-07-21 15:03:24.973 Script: cd /Usr/xxx/IN
. 2010-07-21 15:03:24.974 Cached directory change via "/Usr/xxxx/IN" to "/Usr/xxxxxx/IN".
. 2010-07-21 15:03:24.974 Getting current directory name.
< 2010-07-21 15:03:24.974 Script: /Usr/xxx/IN
> 2010-07-21 15:03:24.974 Script: lcd \\host01\download
< 2010-07-21 15:03:25.031 Script: \\host01\download
> 2010-07-21 15:03:25.031 Script: get file1_20100721.csv file1.csv
. 2010-07-21 15:03:25.031 Listing file "file1_20100721.csv".
> 2010-07-21 15:03:25.031 Type: SSH_FXP_LSTAT, Size: 56, Number: 263
< 2010-07-21 15:03:25.033 Type: SSH_FXP_ATTRS, Size: 29, Number: 263
. 2010-07-21 15:03:25.033 File: "/Usr/xxx/IN/file2_20100721.csv"
. 2010-07-21 15:03:25.034 Copying "/Usr/xxx/IN/file2_20100721.csv" to local directory started.
. 2010-07-21 15:03:25.034 Binary transfer mode selected.
. 2010-07-21 15:03:25.035 Checking existence of partially transfered file.
. 2010-07-21 15:03:25.051 Confirming overwriting of file.
. 2010-07-21 15:03:25.054 Opening remote file.
> 2010-07-21 15:03:25.054 Type: SSH_FXP_OPEN, Size: 64, Number: 515
< 2010-07-21 15:03:25.056 Type: SSH_FXP_HANDLE, Size: 10, Number: 515
> 2010-07-21 15:03:25.056 Type: SSH_FXP_FSTAT, Size: 10, Number: 776
> 2010-07-21 15:03:25.056 Type: SSH_FXP_READ, Size: 22, Number: 1029
. 2010-07-21 15:03:25.058 3 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
< 2010-07-21 15:03:25.058 Type: SSH_FXP_ATTRS, Size: 29, Number: 776
. 2010-07-21 15:03:25.058 Storing reserved response
< 2010-07-21 15:03:25.554 Status code: 1
. 2010-07-21 15:03:25.554 137 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2010-07-21 15:03:25.554 Type: SSH_FXP_CLOSE, Size: 10, Number: 19460
< 2010-07-21 15:03:25.554 Type: SSH_FXP_STATUS, Size: 22, Number: 18693
< 2010-07-21 15:03:25.556 Type: SSH_FXP_STATUS, Size: 22, Number: 18949
< 2010-07-21 15:03:25.558 Type: SSH_FXP_STATUS, Size: 22, Number: 19205
> 2010-07-21 15:03:25.600 Script: get file2_20100721.csv file2.csv
. 2010-07-21 15:03:25.600 Listing file "file2_20100721.csv".
> 2010-07-21 15:03:25.600 Type: SSH_FXP_LSTAT, Size: 56, Number: 19719
< 2010-07-21 15:03:25.600 Type: SSH_FXP_STATUS, Size: 21, Number: 19460
. 2010-07-21 15:03:25.600 Discarding reserved response
< 2010-07-21 15:03:25.601 Type: SSH_FXP_ATTRS, Size: 29, Number: 19719
. 2010-07-21 15:03:25.602 File: "/Usr/xxx/IN/file2_20100721.csv"
. 2010-07-21 15:03:25.602 Copying "/Usr/xxx/IN/file2_20100721.csv" to local directory started.
. 2010-07-21 15:03:25.602 Binary transfer mode selected.
. 2010-07-21 15:03:25.604 Checking existence of partially transfered file.
. 2010-07-21 15:03:25.619 Confirming overwriting of file.
. 2010-07-21 15:03:25.622 Opening remote file.
> 2010-07-21 15:03:25.622 Type: SSH_FXP_OPEN, Size: 64, Number: 19971
< 2010-07-21 15:03:25.625 Type: SSH_FXP_HANDLE, Size: 10, Number: 19971
> 2010-07-21 15:03:25.625 Type: SSH_FXP_FSTAT, Size: 10, Number: 20232
> 2010-07-21 15:03:25.625 Type: SSH_FXP_READ, Size: 22, Number: 20485
. 2010-07-21 15:03:25.629 3 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
< 2010-07-21 15:03:25.629 Type: SSH_FXP_ATTRS, Size: 29, Number: 20232
. 2010-07-21 15:03:25.629 Storing reserved response
< 2010-07-21 15:03:25.672 Status code: 1
. 2010-07-21 15:03:25.672 23 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2010-07-21 15:03:25.672 Type: SSH_FXP_CLOSE, Size: 10, Number: 24324
< 2010-07-21 15:03:25.672 Type: SSH_FXP_STATUS, Size: 22, Number: 23557
< 2010-07-21 15:03:25.672 Type: SSH_FXP_STATUS, Size: 22, Number: 23813
< 2010-07-21 15:03:25.674 Type: SSH_FXP_STATUS, Size: 22, Number: 24069
> 2010-07-21 15:03:25.713 Script: get file3_20100721.csv file3.csv
. 2010-07-21 15:03:25.713 Listing file "file3_20100721.csv".
> 2010-07-21 15:03:25.713 Type: SSH_FXP_LSTAT, Size: 54, Number: 24583
< 2010-07-21 15:03:25.713 Type: SSH_FXP_STATUS, Size: 21, Number: 24324
. 2010-07-21 15:03:25.713 Discarding reserved response
< 2010-07-21 15:03:25.715 Type: SSH_FXP_ATTRS, Size: 29, Number: 24583
. 2010-07-21 15:03:25.715 File: "/Usr/xxx/IN/file3_20100721.csv"
. 2010-07-21 15:03:25.715 Copying "/Usr/xxx/IN/file3_20100721.csv" to local directory started.
. 2010-07-21 15:03:25.716 Binary transfer mode selected.
. 2010-07-21 15:03:25.718 Checking existence of partially transfered file.
. 2010-07-21 15:03:25.735 Confirming overwriting of file.
. 2010-07-21 15:03:25.737 Opening remote file.
> 2010-07-21 15:03:25.737 Type: SSH_FXP_OPEN, Size: 62, Number: 24835
< 2010-07-21 15:03:25.738 Type: SSH_FXP_HANDLE, Size: 10, Number: 24835
> 2010-07-21 15:03:25.738 Type: SSH_FXP_FSTAT, Size: 10, Number: 25096
> 2010-07-21 15:03:25.738 Type: SSH_FXP_READ, Size: 22, Number: 25349
. 2010-07-21 15:03:25.739 3 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
< 2010-07-21 15:03:25.739 Type: SSH_FXP_ATTRS, Size: 29, Number: 25096
. 2010-07-21 15:03:25.739 Storing reserved response
< 2010-07-21 15:03:25.985 Status code: 1
. 2010-07-21 15:03:25.985 73 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2010-07-21 15:03:25.985 Type: SSH_FXP_CLOSE, Size: 10, Number: 35588
< 2010-07-21 15:03:25.985 Type: SSH_FXP_STATUS, Size: 22, Number: 34821
< 2010-07-21 15:03:25.988 Type: SSH_FXP_STATUS, Size: 22, Number: 35077
< 2010-07-21 15:03:25.990 Type: SSH_FXP_STATUS, Size: 22, Number: 35333
> 2010-07-21 15:03:26.030 Script: get file4_20100721.csv file4.csv
. 2010-07-21 15:03:26.030 Listing file "file4_20100721.csv".
> 2010-07-21 15:03:26.030 Type: SSH_FXP_LSTAT, Size: 49, Number: 35847
< 2010-07-21 15:03:26.030 Type: SSH_FXP_STATUS, Size: 21, Number: 35588
. 2010-07-21 15:03:26.030 Discarding reserved response
< 2010-07-21 15:03:26.032 Type: SSH_FXP_ATTRS, Size: 29, Number: 35847
. 2010-07-21 15:03:26.032 File: "/Usr/xxx/IN/file4_20100721.csv"
. 2010-07-21 15:03:26.032 Copying "/Usr/xxx/IN/file4_20100721.csv" to local directory started.
. 2010-07-21 15:03:26.032 Binary transfer mode selected.
. 2010-07-21 15:03:26.035 Checking existence of partially transfered file.
. 2010-07-21 15:03:26.051 Confirming overwriting of file.
. 2010-07-21 15:03:26.205 Opening remote file.
> 2010-07-21 15:03:26.205 Type: SSH_FXP_OPEN, Size: 57, Number: 36099
< 2010-07-21 15:03:26.206 Type: SSH_FXP_HANDLE, Size: 10, Number: 36099
> 2010-07-21 15:03:26.206 Type: SSH_FXP_FSTAT, Size: 10, Number: 36360
> 2010-07-21 15:03:26.206 Type: SSH_FXP_READ, Size: 22, Number: 36613
. 2010-07-21 15:03:26.207 3 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
< 2010-07-21 15:03:26.207 Type: SSH_FXP_ATTRS, Size: 29, Number: 36360
. 2010-07-21 15:03:26.207 Storing reserved response
< 2010-07-21 15:03:26.487 Status code: 1
. 2010-07-21 15:03:26.487 79 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2010-07-21 15:03:26.487 Type: SSH_FXP_CLOSE, Size: 10, Number: 47620
< 2010-07-21 15:03:26.487 Type: SSH_FXP_STATUS, Size: 22, Number: 46853
< 2010-07-21 15:03:26.487 Type: SSH_FXP_STATUS, Size: 22, Number: 47109
< 2010-07-21 15:03:26.491 Type: SSH_FXP_STATUS, Size: 22, Number: 47365
> 2010-07-21 15:03:26.528 Script: close
. 2010-07-21 15:03:26.528 Closing connection.
. 2010-07-21 15:03:26.528 Sending special code: 12
. 2010-07-21 15:03:26.528 Sent EOF message