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

asherber

I've written to the FTPShell people to see if there's some way to enable a more detailed log.

Aaron.
martin

I'm sorry, but I cannot help you unless you find out how to make server state its reasons for disconnecting WinSCP.
asherber

Okay, here are 3 log files: (1) 4.1.9 logging in; (2) 4.2.4. failing; (3) server log. The server logs are still pretty useless. Another interesting thing is that when I logged in with 4.1.9, I waited 10 seconds before disconnecting. For some reason, the key exchange error shows up in the server log at the end of this 10 seconds, as I was disconnecting.

WinSCP and FTPShell were all running on the same machine, WinXP SP2.

Thanks,
Aaron.


. 2009-10-31 12:46:08.953 --------------------------------------------------------------------------
. 2009-10-31 12:46:08.953 WinSCP Version 4.1.9 (Build 416) (OS 5.1.2600 Service Pack 2)
. 2009-10-31 12:46:08.953 Login time: Saturday, October 31, 2009 12:46:08 PM
. 2009-10-31 12:46:08.953 --------------------------------------------------------------------------
. 2009-10-31 12:46:08.953 Session name: asherber@localhost
. 2009-10-31 12:46:08.953 Host name: localhost (Port: 22)
. 2009-10-31 12:46:08.953 User name: asherber (Password: Yes, Key file: No)
. 2009-10-31 12:46:08.968 Tunnel: No
. 2009-10-31 12:46:08.968 Transfer Protocol: SFTP (SCP)
. 2009-10-31 12:46:08.968 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2009-10-31 12:46:08.968 Proxy: none
. 2009-10-31 12:46:08.968 SSH protocol version: 2; Compression: No
. 2009-10-31 12:46:08.968 Bypass authentication: No
. 2009-10-31 12:46:08.968 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2009-10-31 12:46:08.968 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2009-10-31 12:46:08.968 SSH Bugs: -,-,-,-,-,-,-,-
. 2009-10-31 12:46:08.968 SFTP Bugs: -,-
. 2009-10-31 12:46:08.968 Return code variable: Autodetect; Lookup user groups: Yes
. 2009-10-31 12:46:08.968 Shell: default, EOL: 0
. 2009-10-31 12:46:08.968 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2009-10-31 12:46:08.968 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2009-10-31 12:46:08.968 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-10-31 12:46:08.968 Cache directory changes: Yes, Permanent: Yes
. 2009-10-31 12:46:08.968 DST mode: 1
. 2009-10-31 12:46:08.968 --------------------------------------------------------------------------
. 2009-10-31 12:46:09.000 Looking up host "localhost"
. 2009-10-31 12:46:09.000 Connecting to 127.0.0.1 port 22
. 2009-10-31 12:46:09.171 Selecting events 63 for socket 696
. 2009-10-31 12:46:09.171 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.171 Looking for incoming data
. 2009-10-31 12:46:09.171 Looking for network events
. 2009-10-31 12:46:09.171 Detected network event
. 2009-10-31 12:46:09.171 Enumerating network events for socket 696
. 2009-10-31 12:46:09.171 Enumerated 19 network events making 19 cumulative events for socket 696
. 2009-10-31 12:46:09.171 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.171 Server version: SSH-2.0-FTPshellSSH_1.0
. 2009-10-31 12:46:09.171 We claim version: SSH-2.0-WinSCP_release_4.1.9
. 2009-10-31 12:46:09.187 SSPI: acquired credentials for: Aaron Sherber@LIGHTNING
. 2009-10-31 12:46:09.187 Constructed service principal name 'host/localhost'
. 2009-10-31 12:46:09.187 GSSKEX disabled: No credentials are available in the security package

. 2009-10-31 12:46:09.187 Using SSH protocol version 2
. 2009-10-31 12:46:09.187 Handling network write event on socket 696 with error 0
. 2009-10-31 12:46:09.187 Handling network connect event on socket 696 with error 0
. 2009-10-31 12:46:09.187 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.187 Looking for incoming data
. 2009-10-31 12:46:09.187 Looking for network events
. 2009-10-31 12:46:09.187 Detected network event
. 2009-10-31 12:46:09.187 Enumerating network events for socket 696
. 2009-10-31 12:46:09.187 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.187 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.187 Doing Diffie-Hellman group exchange
. 2009-10-31 12:46:09.187 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.187 Looking for incoming data
. 2009-10-31 12:46:09.187 Looking for network events
. 2009-10-31 12:46:09.187 Detected network event
. 2009-10-31 12:46:09.187 Enumerating network events for socket 696
. 2009-10-31 12:46:09.187 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.187 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.187 Doing Diffie-Hellman key exchange with hash SHA-1
. 2009-10-31 12:46:09.281 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.281 Looking for incoming data
. 2009-10-31 12:46:09.281 Looking for network events
. 2009-10-31 12:46:09.328 Detected network event
. 2009-10-31 12:46:09.328 Enumerating network events for socket 696
. 2009-10-31 12:46:09.328 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.328 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.421 Selecting events 63 for socket 696
. 2009-10-31 12:46:09.421 Host key fingerprint is:
. 2009-10-31 12:46:09.421 ssh-rsa 1024 f9:13:eb:fe:9e:2d:2e:53:41:87:1c:9f:12:a3:9e:02
. 2009-10-31 12:46:09.421 Initialised AES-256 SDCTR client->server encryption
. 2009-10-31 12:46:09.421 Initialised HMAC-SHA1 client->server MAC algorithm
. 2009-10-31 12:46:09.421 Initialised AES-256 SDCTR server->client encryption
. 2009-10-31 12:46:09.421 Initialised HMAC-SHA1 server->client MAC algorithm
. 2009-10-31 12:46:09.421 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.421 Looking for incoming data
. 2009-10-31 12:46:09.421 Looking for network events
. 2009-10-31 12:46:09.421 Detected network event
. 2009-10-31 12:46:09.421 Enumerating network events for socket 696
. 2009-10-31 12:46:09.421 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.421 Handling network read event on socket 696 with error 0
! 2009-10-31 12:46:09.421 Using username "asherber".
. 2009-10-31 12:46:09.421 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.421 Looking for incoming data
. 2009-10-31 12:46:09.421 Looking for network events
. 2009-10-31 12:46:09.421 Detected network event
. 2009-10-31 12:46:09.421 Enumerating network events for socket 696
. 2009-10-31 12:46:09.421 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.421 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.421 Prompt (6, SSH password, , &Password: )
. 2009-10-31 12:46:09.421 Using stored password.
. 2009-10-31 12:46:09.421 Sent password
. 2009-10-31 12:46:09.421 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.421 Looking for incoming data
. 2009-10-31 12:46:09.421 Looking for network events
. 2009-10-31 12:46:09.421 Detected network event
. 2009-10-31 12:46:09.421 Enumerating network events for socket 696
. 2009-10-31 12:46:09.421 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.421 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.421 Access granted
. 2009-10-31 12:46:09.421 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.421 Looking for incoming data
. 2009-10-31 12:46:09.421 Looking for network events
. 2009-10-31 12:46:09.421 Detected network event
. 2009-10-31 12:46:09.421 Enumerating network events for socket 696
. 2009-10-31 12:46:09.421 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.421 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.421 Opened channel for session
. 2009-10-31 12:46:09.421 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:09.421 Looking for incoming data
. 2009-10-31 12:46:09.421 Looking for network events
. 2009-10-31 12:46:09.421 Detected network event
. 2009-10-31 12:46:09.421 Enumerating network events for socket 696
. 2009-10-31 12:46:09.421 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.421 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.421 Started a shell/command
. 2009-10-31 12:46:09.437 --------------------------------------------------------------------------
. 2009-10-31 12:46:09.437 Using SFTP protocol.
. 2009-10-31 12:46:09.437 Doing startup conversation with host.
> 2009-10-31 12:46:09.437 Type: SSH_FXP_INIT, Size: 5, Number: -1
> 2009-10-31 12:46:09.437 01,00,00,00,05,
. 2009-10-31 12:46:09.437 Sent 9 bytes
. 2009-10-31 12:46:09.437 There are 0 bytes remaining in the send buffer
. 2009-10-31 12:46:09.437 Looking for network events
. 2009-10-31 12:46:09.437 Timeout waiting for network events
. 2009-10-31 12:46:09.437 Waiting for another 4 bytes
. 2009-10-31 12:46:09.437 Looking for incoming data
. 2009-10-31 12:46:09.437 Looking for network events
. 2009-10-31 12:46:09.453 Detected network event
. 2009-10-31 12:46:09.453 Enumerating network events for socket 696
. 2009-10-31 12:46:09.453 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.453 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.453 Received 9 bytes (0)
. 2009-10-31 12:46:09.453 Read 4 bytes (5 pending)
. 2009-10-31 12:46:09.453 Read 5 bytes (0 pending)
< 2009-10-31 12:46:09.453 Type: SSH_FXP_VERSION, Size: 5, Number: -1
< 2009-10-31 12:46:09.453 02,00,00,00,03,
. 2009-10-31 12:46:09.453 SFTP version 3 negotiated.
. 2009-10-31 12:46:09.453 We believe the server has signed timestamps bug
. 2009-10-31 12:46:09.453 We will use UTF-8 strings for status messages only
. 2009-10-31 12:46:09.468 Getting current directory name.
. 2009-10-31 12:46:09.468 Getting real path for '.'
> 2009-10-31 12:46:09.468 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
> 2009-10-31 12:46:09.468 10,00,00,00,10,00,00,00,01,2E,
. 2009-10-31 12:46:09.468 Sent 14 bytes
. 2009-10-31 12:46:09.468 There are 0 bytes remaining in the send buffer
. 2009-10-31 12:46:09.468 Looking for network events
. 2009-10-31 12:46:09.468 Timeout waiting for network events
. 2009-10-31 12:46:09.468 Waiting for another 4 bytes
. 2009-10-31 12:46:09.468 Looking for incoming data
. 2009-10-31 12:46:09.468 Looking for network events
. 2009-10-31 12:46:09.484 Detected network event
. 2009-10-31 12:46:09.484 Enumerating network events for socket 696
. 2009-10-31 12:46:09.484 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.484 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.484 Received 27 bytes (0)
. 2009-10-31 12:46:09.484 Read 4 bytes (23 pending)
. 2009-10-31 12:46:09.484 Read 23 bytes (0 pending)
< 2009-10-31 12:46:09.484 Type: SSH_FXP_NAME, Size: 23, Number: 16
< 2009-10-31 12:46:09.484 68,00,00,00,10,00,00,00,01,00,00,00,01,2F,00,00,00,01,2F,00,00,00,00,
. 2009-10-31 12:46:09.484 Real path is '/'
. 2009-10-31 12:46:09.484 Listing directory "/".
> 2009-10-31 12:46:09.484 Type: SSH_FXP_OPENDIR, Size: 10, Number: 267
> 2009-10-31 12:46:09.484 0B,00,00,01,0B,00,00,00,01,2F,
. 2009-10-31 12:46:09.484 Sent 14 bytes
. 2009-10-31 12:46:09.484 There are 0 bytes remaining in the send buffer
. 2009-10-31 12:46:09.484 Looking for network events
. 2009-10-31 12:46:09.484 Timeout waiting for network events
. 2009-10-31 12:46:09.484 Waiting for another 4 bytes
. 2009-10-31 12:46:09.484 Looking for incoming data
. 2009-10-31 12:46:09.484 Looking for network events
. 2009-10-31 12:46:09.500 Detected network event
. 2009-10-31 12:46:09.500 Enumerating network events for socket 696
. 2009-10-31 12:46:09.500 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.500 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.500 Received 17 bytes (0)
. 2009-10-31 12:46:09.500 Read 4 bytes (13 pending)
. 2009-10-31 12:46:09.500 Read 13 bytes (0 pending)
< 2009-10-31 12:46:09.500 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
< 2009-10-31 12:46:09.500 66,00,00,01,0B,00,00,00,04,0F,0F,0F,0F,
> 2009-10-31 12:46:09.500 Type: SSH_FXP_READDIR, Size: 13, Number: 524
> 2009-10-31 12:46:09.500 0C,00,00,02,0C,00,00,00,04,0F,0F,0F,0F,
. 2009-10-31 12:46:09.500 Sent 17 bytes
. 2009-10-31 12:46:09.500 There are 0 bytes remaining in the send buffer
. 2009-10-31 12:46:09.500 Looking for network events
. 2009-10-31 12:46:09.500 Timeout waiting for network events
. 2009-10-31 12:46:09.500 Waiting for another 4 bytes
. 2009-10-31 12:46:09.500 Looking for incoming data
. 2009-10-31 12:46:09.500 Looking for network events
. 2009-10-31 12:46:09.531 Detected network event
. 2009-10-31 12:46:09.531 Enumerating network events for socket 696
. 2009-10-31 12:46:09.531 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.531 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.531 Received 804 bytes (0)
. 2009-10-31 12:46:09.531 Read 4 bytes (800 pending)
. 2009-10-31 12:46:09.531 Read 800 bytes (0 pending)
< 2009-10-31 12:46:09.531 Type: SSH_FXP_NAME, Size: 800, Number: 524
< 2009-10-31 12:46:09.531 68,00,00,02,0C,00,00,00,07,00,00,00,0B,41,74,74,61,63,68,6D,65,6E,74,73,00,
< 2009-10-31 12:46:09.531 00,00,44,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,
< 2009-10-31 12:46:09.531 72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,20,20,30,20,46,65,62,20,20,
< 2009-10-31 12:46:09.531 31,30,20,20,30,32,3A,34,34,20,41,74,74,61,63,68,6D,65,6E,74,73,00,00,00,0D,
< 2009-10-31 12:46:09.531 00,00,00,00,00,00,02,00,00,00,41,80,49,91,30,67,49,91,30,67,00,00,00,09,44,
< 2009-10-31 12:46:09.531 6F,63,75,6D,65,6E,74,73,00,00,00,41,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,
< 2009-10-31 12:46:09.531 30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,
< 2009-10-31 12:46:09.531 20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,44,6F,63,75,6D,65,6E,
< 2009-10-31 12:46:09.531 74,73,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,9A,C6,43,3D,9A,
< 2009-10-31 12:46:09.531 C6,00,00,00,0E,46,6F,72,6D,44,65,73,69,67,6E,2E,72,65,67,00,00,00,49,2D,72,
< 2009-10-31 12:46:09.531 77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,
< 2009-10-31 12:46:09.531 61,73,68,65,72,62,65,72,20,20,20,20,20,32,35,34,20,4A,75,6E,20,20,32,37,20,
< 2009-10-31 12:46:09.531 20,31,38,3A,33,34,20,46,6F,72,6D,44,65,73,69,67,6E,2E,72,65,67,00,00,00,0D,
< 2009-10-31 12:46:09.531 00,00,00,00,00,00,00,FE,00,00,81,80,4A,46,9E,68,4A,46,9E,68,00,00,00,08,50,
< 2009-10-31 12:46:09.531 72,6F,6A,65,63,74,73,00,00,00,40,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,
< 2009-10-31 12:46:09.531 20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,20,
< 2009-10-31 12:46:09.531 20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,50,72,6F,6A,65,63,74,73,
< 2009-10-31 12:46:09.531 00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,A4,E3,43,3D,A4,E3,00,
< 2009-10-31 12:46:09.531 00,00,08,52,45,43,59,43,4C,45,52,00,00,00,40,64,72,77,2D,2D,2D,2D,2D,2D,2D,
< 2009-10-31 12:46:09.531 20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,
< 2009-10-31 12:46:09.531 20,20,20,20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,52,45,43,59,
< 2009-10-31 12:46:09.531 43,4C,45,52,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,B5,16,43,
< 2009-10-31 12:46:09.531 3D,B5,16,00,00,00,0A,53,75,62,76,65,72,73,69,6F,6E,00,00,00,43,64,72,77,2D,
< 2009-10-31 12:46:09.531 2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,
< 2009-10-31 12:46:09.531 68,65,72,62,65,72,20,20,20,20,20,30,20,4A,75,6E,20,20,31,38,20,20,31,36,3A,
< 2009-10-31 12:46:09.531 35,31,20,53,75,62,76,65,72,73,69,6F,6E,00,00,00,0D,00,00,00,00,00,00,02,00,
< 2009-10-31 12:46:09.531 00,00,41,80,4A,3A,A8,BF,4A,3A,A8,BF,00,00,00,19,53,79,73,74,65,6D,20,56,6F,
< 2009-10-31 12:46:09.531 6C,75,6D,65,20,49,6E,66,6F,72,6D,61,74,69,6F,6E,00,00,00,51,64,72,77,2D,2D,
< 2009-10-31 12:46:09.531 2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,
< 2009-10-31 12:46:09.531 65,72,62,65,72,20,20,20,20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,
< 2009-10-31 12:46:09.531 20,53,79,73,74,65,6D,20,56,6F,6C,75,6D,65,20,49,6E,66,6F,72,6D,61,74,69,6F,
< 2009-10-31 12:46:09.531 6E,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,95,C5,43,3D,95,C5,
> 2009-10-31 12:46:09.531 Type: SSH_FXP_READDIR, Size: 13, Number: 780
> 2009-10-31 12:46:09.531 0C,00,00,03,0C,00,00,00,04,0F,0F,0F,0F,
. 2009-10-31 12:46:09.531 Sent 17 bytes
. 2009-10-31 12:46:09.531 There are 0 bytes remaining in the send buffer
. 2009-10-31 12:46:09.531 Looking for network events
. 2009-10-31 12:46:09.531 Timeout waiting for network events
. 2009-10-31 12:46:09.531 Read file 'Attachments' from listing
. 2009-10-31 12:46:09.531 Read file 'Documents' from listing
. 2009-10-31 12:46:09.531 Read file 'FormDesign.reg' from listing
. 2009-10-31 12:46:09.531 Read file 'Projects' from listing
. 2009-10-31 12:46:09.531 Read file 'RECYCLER' from listing
. 2009-10-31 12:46:09.531 Read file 'Subversion' from listing
. 2009-10-31 12:46:09.531 Read file 'System Volume Information' from listing
. 2009-10-31 12:46:09.531 Waiting for another 4 bytes
. 2009-10-31 12:46:09.531 Looking for incoming data
. 2009-10-31 12:46:09.531 Looking for network events
. 2009-10-31 12:46:09.546 Detected network event
. 2009-10-31 12:46:09.546 Enumerating network events for socket 696
. 2009-10-31 12:46:09.546 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:09.546 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:09.546 Received 32 bytes (0)
. 2009-10-31 12:46:09.546 Read 4 bytes (28 pending)
. 2009-10-31 12:46:09.546 Read 28 bytes (0 pending)
< 2009-10-31 12:46:09.546 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2009-10-31 12:46:09.546 65,00,00,03,0C,00,00,00,01,00,00,00,0B,45,6E,64,20,6F,66,20,66,69,6C,65,00,
< 2009-10-31 12:46:09.546 00,00,00,
< 2009-10-31 12:46:09.546 Status/error code: 1
> 2009-10-31 12:46:09.546 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
> 2009-10-31 12:46:09.546 04,00,00,04,04,00,00,00,04,0F,0F,0F,0F,
. 2009-10-31 12:46:09.546 Sent 17 bytes
. 2009-10-31 12:46:09.546 There are 0 bytes remaining in the send buffer
. 2009-10-31 12:46:09.546 Looking for network events
. 2009-10-31 12:46:09.546 Timeout waiting for network events
. 2009-10-31 12:46:09.546 Startup conversation with host finished.
. 2009-10-31 12:46:10.156 Session upkeep
. 2009-10-31 12:46:10.156 Looking for network events
. 2009-10-31 12:46:10.156 Detected network event
. 2009-10-31 12:46:10.156 Enumerating network events for socket 696
. 2009-10-31 12:46:10.156 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-31 12:46:10.156 Handling network read event on socket 696 with error 0
. 2009-10-31 12:46:10.156 Received 28 bytes (0)
. 2009-10-31 12:46:10.656 Session upkeep
. 2009-10-31 12:46:10.656 Looking for network events
. 2009-10-31 12:46:10.656 Timeout waiting for network events
. 2009-10-31 12:46:11.156 Session upkeep
. 2009-10-31 12:46:11.156 Looking for network events
. 2009-10-31 12:46:11.156 Timeout waiting for network events
. 2009-10-31 12:46:11.656 Session upkeep
. 2009-10-31 12:46:11.656 Looking for network events
. 2009-10-31 12:46:11.656 Timeout waiting for network events
. 2009-10-31 12:46:12.156 Session upkeep
. 2009-10-31 12:46:12.156 Looking for network events
. 2009-10-31 12:46:12.156 Timeout waiting for network events
. 2009-10-31 12:46:12.656 Session upkeep
. 2009-10-31 12:46:12.656 Looking for network events
. 2009-10-31 12:46:12.656 Timeout waiting for network events
. 2009-10-31 12:46:13.156 Session upkeep
. 2009-10-31 12:46:13.156 Looking for network events
. 2009-10-31 12:46:13.156 Timeout waiting for network events
. 2009-10-31 12:46:13.656 Session upkeep
. 2009-10-31 12:46:13.656 Looking for network events
. 2009-10-31 12:46:13.656 Timeout waiting for network events
. 2009-10-31 12:46:14.156 Session upkeep
. 2009-10-31 12:46:14.156 Looking for network events
. 2009-10-31 12:46:14.156 Timeout waiting for network events
. 2009-10-31 12:46:14.656 Session upkeep
. 2009-10-31 12:46:14.656 Looking for network events
. 2009-10-31 12:46:14.656 Timeout waiting for network events
. 2009-10-31 12:46:15.156 Session upkeep
. 2009-10-31 12:46:15.156 Looking for network events
. 2009-10-31 12:46:15.156 Timeout waiting for network events
. 2009-10-31 12:46:15.656 Session upkeep
. 2009-10-31 12:46:15.656 Looking for network events
. 2009-10-31 12:46:15.656 Timeout waiting for network events
. 2009-10-31 12:46:16.156 Session upkeep
. 2009-10-31 12:46:16.156 Looking for network events
. 2009-10-31 12:46:16.156 Timeout waiting for network events
. 2009-10-31 12:46:16.656 Session upkeep
. 2009-10-31 12:46:16.656 Looking for network events
. 2009-10-31 12:46:16.656 Timeout waiting for network events
. 2009-10-31 12:46:17.156 Session upkeep
. 2009-10-31 12:46:17.156 Looking for network events
. 2009-10-31 12:46:17.156 Timeout waiting for network events
. 2009-10-31 12:46:17.656 Session upkeep
. 2009-10-31 12:46:17.656 Looking for network events
. 2009-10-31 12:46:17.656 Timeout waiting for network events
. 2009-10-31 12:46:18.156 Session upkeep
. 2009-10-31 12:46:18.156 Looking for network events
. 2009-10-31 12:46:18.156 Timeout waiting for network events
. 2009-10-31 12:46:18.656 Session upkeep
. 2009-10-31 12:46:18.656 Looking for network events
. 2009-10-31 12:46:18.656 Timeout waiting for network events
. 2009-10-31 12:46:19.156 Session upkeep
. 2009-10-31 12:46:19.156 Looking for network events
. 2009-10-31 12:46:19.156 Timeout waiting for network events
. 2009-10-31 12:46:19.656 Session upkeep
. 2009-10-31 12:46:19.656 Looking for network events
. 2009-10-31 12:46:19.656 Timeout waiting for network events
. 2009-10-31 12:46:20.156 Session upkeep
. 2009-10-31 12:46:20.156 Looking for network events
. 2009-10-31 12:46:20.156 Timeout waiting for network events
. 2009-10-31 12:46:20.515 Closing connection.
. 2009-10-31 12:46:20.515 Sending special code: 12
. 2009-10-31 12:46:20.515 Sent EOF message
. 2009-10-31 12:46:20.515 Selecting events 0 for socket 696



. 2009-10-31 12:46:39.375 --------------------------------------------------------------------------
. 2009-10-31 12:46:39.375 WinSCP Version 4.2.4 (Build 610) (OS 5.1.2600 Service Pack 2)
. 2009-10-31 12:46:39.375 Login time: Saturday, October 31, 2009 12:46:39 PM
. 2009-10-31 12:46:39.375 --------------------------------------------------------------------------
. 2009-10-31 12:46:39.375 Session name: asherber@localhost
. 2009-10-31 12:46:39.375 Host name: localhost (Port: 22)
. 2009-10-31 12:46:39.390 User name: asherber (Password: Yes, Key file: No)
. 2009-10-31 12:46:39.390 Tunnel: No
. 2009-10-31 12:46:39.390 Transfer Protocol: SFTP (SCP)
. 2009-10-31 12:46:39.390 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2009-10-31 12:46:39.390 Proxy: none
. 2009-10-31 12:46:39.390 SSH protocol version: 2; Compression: No
. 2009-10-31 12:46:39.390 Bypass authentication: No
. 2009-10-31 12:46:39.390 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2009-10-31 12:46:39.390 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2009-10-31 12:46:39.390 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2009-10-31 12:46:39.390 SFTP Bugs: -,-
. 2009-10-31 12:46:39.390 Return code variable: Autodetect; Lookup user groups: Yes
. 2009-10-31 12:46:39.390 Shell: default, EOL: 0
. 2009-10-31 12:46:39.390 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2009-10-31 12:46:39.390 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2009-10-31 12:46:39.390 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-10-31 12:46:39.390 Cache directory changes: Yes, Permanent: Yes
. 2009-10-31 12:46:39.390 DST mode: 1
. 2009-10-31 12:46:39.390 --------------------------------------------------------------------------
. 2009-10-31 12:46:39.406 Looking up host "localhost"
. 2009-10-31 12:46:39.421 Connecting to port 22
. 2009-10-31 12:46:39.468 Selecting events 63 for socket 688
. 2009-10-31 12:46:39.468 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.468 Looking for incoming data
. 2009-10-31 12:46:39.468 Looking for network events
. 2009-10-31 12:46:39.468 Detected network event
. 2009-10-31 12:46:39.468 Enumerating network events for socket 688
. 2009-10-31 12:46:39.468 Enumerated 19 network events making 19 cumulative events for socket 688
. 2009-10-31 12:46:39.468 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.468 Server version: SSH-2.0-FTPshellSSH_1.0
. 2009-10-31 12:46:39.468 Using SSH protocol version 2
. 2009-10-31 12:46:39.468 We claim version: SSH-2.0-WinSCP_release_4.2.4
. 2009-10-31 12:46:39.468 Handling network write event on socket 688 with error 0
. 2009-10-31 12:46:39.468 Handling network connect event on socket 688 with error 0
. 2009-10-31 12:46:39.468 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.468 Looking for incoming data
. 2009-10-31 12:46:39.468 Looking for network events
. 2009-10-31 12:46:39.468 Detected network event
. 2009-10-31 12:46:39.468 Enumerating network events for socket 688
. 2009-10-31 12:46:39.468 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.468 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.468 Doing Diffie-Hellman group exchange
. 2009-10-31 12:46:39.468 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.468 Looking for incoming data
. 2009-10-31 12:46:39.468 Looking for network events
. 2009-10-31 12:46:39.468 Detected network event
. 2009-10-31 12:46:39.468 Enumerating network events for socket 688
. 2009-10-31 12:46:39.468 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.468 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.468 Doing Diffie-Hellman key exchange with hash SHA-1
. 2009-10-31 12:46:39.562 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.562 Looking for incoming data
. 2009-10-31 12:46:39.562 Looking for network events
. 2009-10-31 12:46:39.609 Detected network event
. 2009-10-31 12:46:39.609 Enumerating network events for socket 688
. 2009-10-31 12:46:39.609 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.609 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.718 Selecting events 63 for socket 688
. 2009-10-31 12:46:39.718 Host key fingerprint is:
. 2009-10-31 12:46:39.718 ssh-rsa 1024 f9:13:eb:fe:9e:2d:2e:53:41:87:1c:9f:12:a3:9e:02
. 2009-10-31 12:46:39.718 Initialised AES-256 SDCTR client->server encryption
. 2009-10-31 12:46:39.718 Initialised HMAC-SHA1 client->server MAC algorithm
. 2009-10-31 12:46:39.718 Initialised AES-256 SDCTR server->client encryption
. 2009-10-31 12:46:39.718 Initialised HMAC-SHA1 server->client MAC algorithm
. 2009-10-31 12:46:39.718 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.718 Looking for incoming data
. 2009-10-31 12:46:39.718 Looking for network events
. 2009-10-31 12:46:39.718 Detected network event
. 2009-10-31 12:46:39.718 Enumerating network events for socket 688
. 2009-10-31 12:46:39.718 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.718 Handling network read event on socket 688 with error 0
! 2009-10-31 12:46:39.718 Using username "asherber".
. 2009-10-31 12:46:39.718 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.718 Looking for incoming data
. 2009-10-31 12:46:39.718 Looking for network events
. 2009-10-31 12:46:39.718 Detected network event
. 2009-10-31 12:46:39.718 Enumerating network events for socket 688
. 2009-10-31 12:46:39.718 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.718 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.718 Prompt (6, SSH password, , &Password: )
. 2009-10-31 12:46:39.718 Using stored password.
. 2009-10-31 12:46:39.718 Sent password
. 2009-10-31 12:46:39.718 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.718 Looking for incoming data
. 2009-10-31 12:46:39.718 Looking for network events
. 2009-10-31 12:46:39.718 Detected network event
. 2009-10-31 12:46:39.718 Enumerating network events for socket 688
. 2009-10-31 12:46:39.718 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.718 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.718 Access granted
. 2009-10-31 12:46:39.718 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.718 Looking for incoming data
. 2009-10-31 12:46:39.718 Looking for network events
. 2009-10-31 12:46:39.718 Detected network event
. 2009-10-31 12:46:39.718 Enumerating network events for socket 688
. 2009-10-31 12:46:39.718 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.718 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.718 Opened channel for session
. 2009-10-31 12:46:39.718 Waiting for the server to continue with the initialisation
. 2009-10-31 12:46:39.718 Looking for incoming data
. 2009-10-31 12:46:39.718 Looking for network events
. 2009-10-31 12:46:39.718 Detected network event
. 2009-10-31 12:46:39.718 Enumerating network events for socket 688
. 2009-10-31 12:46:39.718 Enumerated 1 network events making 1 cumulative events for socket 688
. 2009-10-31 12:46:39.718 Handling network read event on socket 688 with error 0
. 2009-10-31 12:46:39.718 Received disconnect message (protocol error)
. 2009-10-31 12:46:39.718 Disconnection message text: disconnecting
. 2009-10-31 12:46:39.718 Selecting events 0 for socket 688
. 2009-10-31 12:46:39.718 Server sent disconnect message
. 2009-10-31 12:46:39.718 type 2 (protocol error):
. 2009-10-31 12:46:39.718 "disconnecting"
* 2009-10-31 12:46:39.734 (ESshFatal) Server sent disconnect message
* 2009-10-31 12:46:39.734 type 2 (protocol error):
* 2009-10-31 12:46:39.734 "disconnecting"
* 2009-10-31 12:46:39.734 Authentication log (see session log for details):
* 2009-10-31 12:46:39.734 Using username "asherber".
* 2009-10-31 12:46:39.734
* 2009-10-31 12:46:39.734 Authentication failed.






10/31/2009 12:45:54 NOTE: FTP server started - listening on port 22
10/31/2009 12:46:9 NOTE: Connection from 127.0.0.1 logged into account asherber
10/31/2009 12:46:20 ERROR:An error occured during key exchange
10/31/2009 12:46:20 NOTE: Connection from 127.0.0.1 disconnected
10/31/2009 12:46:39 NOTE: Connection from 127.0.0.1 logged into account asherber
10/31/2009 12:46:39 ERROR:An error occured during key exchange
10/31/2009 12:46:39 NOTE: Connection from 127.0.0.1 disconnected
10/31/2009 12:46:46 NOTE: FTP server stopped
Guest

The FTPShell log shows disconnected in both cases because I disconnected after I connected!

The different host names are just because of how I have things set up here. The first log I posted was to an external server, and I obscured the host name. Then I tested locally; I've got 4.1.9 on my regular box and 4.2.4 on a virtual machine.

But I'm happy to run this again. I'll use the same local server, and I'll run the portable versions of WinSCP both from the same box.

Aaron.
martin

I'm bit confused as the server log claims that client was disconnected in both cases. Are you sure that the log snippets are for different versions of WinSCP? Also, why the IP addresses differ? Maybe because you use "localhost" for 4.1.9 and "FTPShell" for 4.2.4? Can you try the "localhost" with 4.2.4?
asherber

Here's the successful log from 4.1.9, followed by the (not very helpful) log from FTPShell. I notice that FTPShell records a key exchange error both for 4.1.9 and 4.2.4. I also notice that 4.2.4 doesn't list the GSS key algorithms which 4.1.9 does.

Thanks,
Aaron.

. 2009-10-28 08:25:44.390 --------------------------------------------------------------------------
. 2009-10-28 08:25:44.406 WinSCP Version 4.1.9 (Build 416) (OS 5.1.2600 Service Pack 2)
. 2009-10-28 08:25:44.406 Login time: Wednesday, October 28, 2009 8:25:44 AM
. 2009-10-28 08:25:44.406 --------------------------------------------------------------------------
. 2009-10-28 08:25:44.406 Session name: asherber@localhost
. 2009-10-28 08:25:44.406 Host name: localhost (Port: 22)
. 2009-10-28 08:25:44.406 User name: asherber (Password: Yes, Key file: No)
. 2009-10-28 08:25:44.406 Tunnel: No
. 2009-10-28 08:25:44.406 Transfer Protocol: SFTP (SCP)
. 2009-10-28 08:25:44.406 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2009-10-28 08:25:44.406 Proxy: none
. 2009-10-28 08:25:44.406 SSH protocol version: 2; Compression: No
. 2009-10-28 08:25:44.406 Bypass authentication: No
. 2009-10-28 08:25:44.406 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2009-10-28 08:25:44.406 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2009-10-28 08:25:44.406 SSH Bugs: -,-,-,-,-,-,-,-
. 2009-10-28 08:25:44.406 SFTP Bugs: -,-
. 2009-10-28 08:25:44.406 Return code variable: Autodetect; Lookup user groups: Yes
. 2009-10-28 08:25:44.406 Shell: default, EOL: 0
. 2009-10-28 08:25:44.406 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2009-10-28 08:25:44.406 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2009-10-28 08:25:44.406 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-10-28 08:25:44.406 Cache directory changes: Yes, Permanent: Yes
. 2009-10-28 08:25:44.406 DST mode: 1
. 2009-10-28 08:25:44.406 --------------------------------------------------------------------------
. 2009-10-28 08:25:44.468 Looking up host "localhost"
. 2009-10-28 08:25:44.500 Connecting to 127.0.0.1 port 22
. 2009-10-28 08:25:44.687 Selecting events 63 for socket 696
. 2009-10-28 08:25:44.703 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:44.703 Looking for incoming data
. 2009-10-28 08:25:44.703 Looking for network events
. 2009-10-28 08:25:44.703 Detected network event
. 2009-10-28 08:25:44.703 Enumerating network events for socket 696
. 2009-10-28 08:25:44.703 Enumerated 19 network events making 19 cumulative events for socket 696
. 2009-10-28 08:25:44.703 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:44.703 Server version: SSH-2.0-FTPshellSSH_1.0
. 2009-10-28 08:25:44.703 We claim version: SSH-2.0-WinSCP_release_4.1.9
. 2009-10-28 08:25:44.796 SSPI: acquired credentials for: Aaron Sherber@LIGHTNING
. 2009-10-28 08:25:44.796 Constructed service principal name 'host/localhost'
. 2009-10-28 08:25:44.828 GSSKEX disabled: No credentials are available in the security package

. 2009-10-28 08:25:44.859 Using SSH protocol version 2
. 2009-10-28 08:25:44.859 Handling network write event on socket 696 with error 0
. 2009-10-28 08:25:44.859 Handling network connect event on socket 696 with error 0
. 2009-10-28 08:25:44.859 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:44.859 Looking for incoming data
. 2009-10-28 08:25:44.859 Looking for network events
. 2009-10-28 08:25:44.859 Detected network event
. 2009-10-28 08:25:44.859 Enumerating network events for socket 696
. 2009-10-28 08:25:44.859 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:44.859 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:44.859 Doing Diffie-Hellman group exchange
. 2009-10-28 08:25:44.859 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:44.859 Looking for incoming data
. 2009-10-28 08:25:44.859 Looking for network events
. 2009-10-28 08:25:44.859 Detected network event
. 2009-10-28 08:25:44.859 Enumerating network events for socket 696
. 2009-10-28 08:25:44.859 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:44.859 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:44.859 Doing Diffie-Hellman key exchange with hash SHA-1
. 2009-10-28 08:25:44.953 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:44.953 Looking for incoming data
. 2009-10-28 08:25:44.953 Looking for network events
. 2009-10-28 08:25:45.015 Detected network event
. 2009-10-28 08:25:45.015 Enumerating network events for socket 696
. 2009-10-28 08:25:45.015 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.015 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.125 Selecting events 63 for socket 696
. 2009-10-28 08:25:45.125 Host key fingerprint is:
. 2009-10-28 08:25:45.125 ssh-rsa 1024 f9:13:eb:fe:9e:2d:2e:53:41:87:1c:9f:12:a3:9e:02
. 2009-10-28 08:25:45.125 Initialised AES-256 SDCTR client->server encryption
. 2009-10-28 08:25:45.125 Initialised HMAC-SHA1 client->server MAC algorithm
. 2009-10-28 08:25:45.125 Initialised AES-256 SDCTR server->client encryption
. 2009-10-28 08:25:45.125 Initialised HMAC-SHA1 server->client MAC algorithm
. 2009-10-28 08:25:45.125 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:45.125 Looking for incoming data
. 2009-10-28 08:25:45.125 Looking for network events
. 2009-10-28 08:25:45.125 Detected network event
. 2009-10-28 08:25:45.125 Enumerating network events for socket 696
. 2009-10-28 08:25:45.125 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.125 Handling network read event on socket 696 with error 0
! 2009-10-28 08:25:45.125 Using username "asherber".
. 2009-10-28 08:25:45.125 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:45.125 Looking for incoming data
. 2009-10-28 08:25:45.125 Looking for network events
. 2009-10-28 08:25:45.125 Detected network event
. 2009-10-28 08:25:45.125 Enumerating network events for socket 696
. 2009-10-28 08:25:45.125 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.125 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.125 Prompt (6, SSH password, , &Password: )
. 2009-10-28 08:25:45.125 Using stored password.
. 2009-10-28 08:25:45.140 Sent password
. 2009-10-28 08:25:45.140 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:45.140 Looking for incoming data
. 2009-10-28 08:25:45.140 Looking for network events
. 2009-10-28 08:25:45.140 Detected network event
. 2009-10-28 08:25:45.140 Enumerating network events for socket 696
. 2009-10-28 08:25:45.140 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.140 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.140 Access granted
. 2009-10-28 08:25:45.140 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:45.140 Looking for incoming data
. 2009-10-28 08:25:45.140 Looking for network events
. 2009-10-28 08:25:45.140 Detected network event
. 2009-10-28 08:25:45.140 Enumerating network events for socket 696
. 2009-10-28 08:25:45.140 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.140 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.140 Opened channel for session
. 2009-10-28 08:25:45.140 Waiting for the server to continue with the initialisation
. 2009-10-28 08:25:45.140 Looking for incoming data
. 2009-10-28 08:25:45.140 Looking for network events
. 2009-10-28 08:25:45.140 Detected network event
. 2009-10-28 08:25:45.140 Enumerating network events for socket 696
. 2009-10-28 08:25:45.140 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.140 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.140 Started a shell/command
. 2009-10-28 08:25:45.140 --------------------------------------------------------------------------
. 2009-10-28 08:25:45.140 Using SFTP protocol.
. 2009-10-28 08:25:45.140 Doing startup conversation with host.
> 2009-10-28 08:25:45.140 Type: SSH_FXP_INIT, Size: 5, Number: -1
> 2009-10-28 08:25:45.140 01,00,00,00,05,
. 2009-10-28 08:25:45.140 Sent 9 bytes
. 2009-10-28 08:25:45.140 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:25:45.140 Looking for network events
. 2009-10-28 08:25:45.140 Timeout waiting for network events
. 2009-10-28 08:25:45.140 Waiting for another 4 bytes
. 2009-10-28 08:25:45.140 Looking for incoming data
. 2009-10-28 08:25:45.140 Looking for network events
. 2009-10-28 08:25:45.171 Detected network event
. 2009-10-28 08:25:45.171 Enumerating network events for socket 696
. 2009-10-28 08:25:45.171 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.171 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.171 Received 9 bytes (0)
. 2009-10-28 08:25:45.171 Read 4 bytes (5 pending)
. 2009-10-28 08:25:45.171 Read 5 bytes (0 pending)
< 2009-10-28 08:25:45.171 Type: SSH_FXP_VERSION, Size: 5, Number: -1
< 2009-10-28 08:25:45.171 02,00,00,00,03,
. 2009-10-28 08:25:45.171 SFTP version 3 negotiated.
. 2009-10-28 08:25:45.171 We believe the server has signed timestamps bug
. 2009-10-28 08:25:45.171 We will use UTF-8 strings for status messages only
. 2009-10-28 08:25:45.171 Getting current directory name.
. 2009-10-28 08:25:45.171 Getting real path for '.'
> 2009-10-28 08:25:45.171 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
> 2009-10-28 08:25:45.171 10,00,00,00,10,00,00,00,01,2E,
. 2009-10-28 08:25:45.171 Sent 14 bytes
. 2009-10-28 08:25:45.171 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:25:45.171 Looking for network events
. 2009-10-28 08:25:45.171 Timeout waiting for network events
. 2009-10-28 08:25:45.171 Waiting for another 4 bytes
. 2009-10-28 08:25:45.171 Looking for incoming data
. 2009-10-28 08:25:45.171 Looking for network events
. 2009-10-28 08:25:45.203 Detected network event
. 2009-10-28 08:25:45.203 Enumerating network events for socket 696
. 2009-10-28 08:25:45.203 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.203 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.203 Received 27 bytes (0)
. 2009-10-28 08:25:45.203 Read 4 bytes (23 pending)
. 2009-10-28 08:25:45.203 Read 23 bytes (0 pending)
< 2009-10-28 08:25:45.203 Type: SSH_FXP_NAME, Size: 23, Number: 16
< 2009-10-28 08:25:45.203 68,00,00,00,10,00,00,00,01,00,00,00,01,2F,00,00,00,01,2F,00,00,00,00,
. 2009-10-28 08:25:45.203 Real path is '/'
. 2009-10-28 08:25:45.203 Listing directory "/".
> 2009-10-28 08:25:45.203 Type: SSH_FXP_OPENDIR, Size: 10, Number: 267
> 2009-10-28 08:25:45.203 0B,00,00,01,0B,00,00,00,01,2F,
. 2009-10-28 08:25:45.203 Sent 14 bytes
. 2009-10-28 08:25:45.203 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:25:45.203 Looking for network events
. 2009-10-28 08:25:45.203 Timeout waiting for network events
. 2009-10-28 08:25:45.203 Waiting for another 4 bytes
. 2009-10-28 08:25:45.203 Looking for incoming data
. 2009-10-28 08:25:45.203 Looking for network events
. 2009-10-28 08:25:45.234 Detected network event
. 2009-10-28 08:25:45.234 Enumerating network events for socket 696
. 2009-10-28 08:25:45.234 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.234 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.234 Received 17 bytes (0)
. 2009-10-28 08:25:45.234 Read 4 bytes (13 pending)
. 2009-10-28 08:25:45.234 Read 13 bytes (0 pending)
< 2009-10-28 08:25:45.234 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
< 2009-10-28 08:25:45.234 66,00,00,01,0B,00,00,00,04,0F,0F,0F,0F,
> 2009-10-28 08:25:45.234 Type: SSH_FXP_READDIR, Size: 13, Number: 524
> 2009-10-28 08:25:45.234 0C,00,00,02,0C,00,00,00,04,0F,0F,0F,0F,
. 2009-10-28 08:25:45.234 Sent 17 bytes
. 2009-10-28 08:25:45.234 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:25:45.234 Looking for network events
. 2009-10-28 08:25:45.234 Timeout waiting for network events
. 2009-10-28 08:25:45.234 Waiting for another 4 bytes
. 2009-10-28 08:25:45.234 Looking for incoming data
. 2009-10-28 08:25:45.234 Looking for network events
. 2009-10-28 08:25:45.265 Detected network event
. 2009-10-28 08:25:45.265 Enumerating network events for socket 696
. 2009-10-28 08:25:45.265 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.265 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.265 Received 804 bytes (0)
. 2009-10-28 08:25:45.265 Read 4 bytes (800 pending)
. 2009-10-28 08:25:45.265 Read 800 bytes (0 pending)
< 2009-10-28 08:25:45.265 Type: SSH_FXP_NAME, Size: 800, Number: 524
< 2009-10-28 08:25:45.265 68,00,00,02,0C,00,00,00,07,00,00,00,0B,41,74,74,61,63,68,6D,65,6E,74,73,00,
< 2009-10-28 08:25:45.265 00,00,44,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,
< 2009-10-28 08:25:45.265 72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,20,20,30,20,46,65,62,20,20,
< 2009-10-28 08:25:45.265 31,30,20,20,30,32,3A,34,34,20,41,74,74,61,63,68,6D,65,6E,74,73,00,00,00,0D,
< 2009-10-28 08:25:45.265 00,00,00,00,00,00,02,00,00,00,41,80,49,91,30,67,49,91,30,67,00,00,00,09,44,
< 2009-10-28 08:25:45.265 6F,63,75,6D,65,6E,74,73,00,00,00,41,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,
< 2009-10-28 08:25:45.265 30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,
< 2009-10-28 08:25:45.265 20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,44,6F,63,75,6D,65,6E,
< 2009-10-28 08:25:45.265 74,73,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,9A,C6,43,3D,9A,
< 2009-10-28 08:25:45.265 C6,00,00,00,0E,46,6F,72,6D,44,65,73,69,67,6E,2E,72,65,67,00,00,00,49,2D,72,
< 2009-10-28 08:25:45.265 77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,
< 2009-10-28 08:25:45.265 61,73,68,65,72,62,65,72,20,20,20,20,20,32,35,34,20,4A,75,6E,20,20,32,37,20,
< 2009-10-28 08:25:45.265 20,31,38,3A,33,34,20,46,6F,72,6D,44,65,73,69,67,6E,2E,72,65,67,00,00,00,0D,
< 2009-10-28 08:25:45.265 00,00,00,00,00,00,00,FE,00,00,81,80,4A,46,9E,68,4A,46,9E,68,00,00,00,08,50,
< 2009-10-28 08:25:45.265 72,6F,6A,65,63,74,73,00,00,00,40,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,
< 2009-10-28 08:25:45.265 20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,20,
< 2009-10-28 08:25:45.265 20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,50,72,6F,6A,65,63,74,73,
< 2009-10-28 08:25:45.265 00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,A4,E3,43,3D,A4,E3,00,
< 2009-10-28 08:25:45.265 00,00,08,52,45,43,59,43,4C,45,52,00,00,00,40,64,72,77,2D,2D,2D,2D,2D,2D,2D,
< 2009-10-28 08:25:45.265 20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,
< 2009-10-28 08:25:45.265 20,20,20,20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,52,45,43,59,
< 2009-10-28 08:25:45.265 43,4C,45,52,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,B5,16,43,
< 2009-10-28 08:25:45.265 3D,B5,16,00,00,00,0A,53,75,62,76,65,72,73,69,6F,6E,00,00,00,43,64,72,77,2D,
< 2009-10-28 08:25:45.265 2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,
< 2009-10-28 08:25:45.265 68,65,72,62,65,72,20,20,20,20,20,30,20,4A,75,6E,20,20,31,38,20,20,31,36,3A,
< 2009-10-28 08:25:45.265 35,31,20,53,75,62,76,65,72,73,69,6F,6E,00,00,00,0D,00,00,00,00,00,00,02,00,
< 2009-10-28 08:25:45.265 00,00,41,80,4A,3A,A8,BF,4A,3A,A8,BF,00,00,00,19,53,79,73,74,65,6D,20,56,6F,
< 2009-10-28 08:25:45.265 6C,75,6D,65,20,49,6E,66,6F,72,6D,61,74,69,6F,6E,00,00,00,51,64,72,77,2D,2D,
< 2009-10-28 08:25:45.265 2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,
< 2009-10-28 08:25:45.265 65,72,62,65,72,20,20,20,20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,
< 2009-10-28 08:25:45.265 20,53,79,73,74,65,6D,20,56,6F,6C,75,6D,65,20,49,6E,66,6F,72,6D,61,74,69,6F,
< 2009-10-28 08:25:45.265 6E,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,95,C5,43,3D,95,C5,
> 2009-10-28 08:25:45.265 Type: SSH_FXP_READDIR, Size: 13, Number: 780
> 2009-10-28 08:25:45.265 0C,00,00,03,0C,00,00,00,04,0F,0F,0F,0F,
. 2009-10-28 08:25:45.265 Sent 17 bytes
. 2009-10-28 08:25:45.265 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:25:45.265 Looking for network events
. 2009-10-28 08:25:45.265 Timeout waiting for network events
. 2009-10-28 08:25:45.265 Read file 'Attachments' from listing
. 2009-10-28 08:25:45.265 Read file 'Documents' from listing
. 2009-10-28 08:25:45.265 Read file 'FormDesign.reg' from listing
. 2009-10-28 08:25:45.265 Read file 'Projects' from listing
. 2009-10-28 08:25:45.265 Read file 'RECYCLER' from listing
. 2009-10-28 08:25:45.265 Read file 'Subversion' from listing
. 2009-10-28 08:25:45.265 Read file 'System Volume Information' from listing
. 2009-10-28 08:25:45.265 Waiting for another 4 bytes
. 2009-10-28 08:25:45.265 Looking for incoming data
. 2009-10-28 08:25:45.265 Looking for network events
. 2009-10-28 08:25:45.296 Detected network event
. 2009-10-28 08:25:45.296 Enumerating network events for socket 696
. 2009-10-28 08:25:45.296 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.296 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.296 Received 32 bytes (0)
. 2009-10-28 08:25:45.296 Read 4 bytes (28 pending)
. 2009-10-28 08:25:45.296 Read 28 bytes (0 pending)
< 2009-10-28 08:25:45.296 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2009-10-28 08:25:45.296 65,00,00,03,0C,00,00,00,01,00,00,00,0B,45,6E,64,20,6F,66,20,66,69,6C,65,00,
< 2009-10-28 08:25:45.296 00,00,00,
< 2009-10-28 08:25:45.296 Status/error code: 1
> 2009-10-28 08:25:45.296 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
> 2009-10-28 08:25:45.296 04,00,00,04,04,00,00,00,04,0F,0F,0F,0F,
. 2009-10-28 08:25:45.296 Sent 17 bytes
. 2009-10-28 08:25:45.296 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:25:45.296 Looking for network events
. 2009-10-28 08:25:45.296 Timeout waiting for network events
. 2009-10-28 08:25:45.296 Startup conversation with host finished.
. 2009-10-28 08:25:45.984 Session upkeep
. 2009-10-28 08:25:45.984 Looking for network events
. 2009-10-28 08:25:45.984 Detected network event
. 2009-10-28 08:25:45.984 Enumerating network events for socket 696
. 2009-10-28 08:25:45.984 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:25:45.984 Handling network read event on socket 696 with error 0
. 2009-10-28 08:25:45.984 Received 28 bytes (0)
. 2009-10-28 08:25:46.468 Session upkeep
. 2009-10-28 08:25:46.468 Looking for network events
. 2009-10-28 08:25:46.468 Timeout waiting for network events
. 2009-10-28 08:25:46.968 Session upkeep
. 2009-10-28 08:25:46.968 Looking for network events
. 2009-10-28 08:25:46.968 Timeout waiting for network events
. 2009-10-28 08:25:47.468 Session upkeep
. 2009-10-28 08:25:47.468 Looking for network events
. 2009-10-28 08:25:47.468 Timeout waiting for network events
. 2009-10-28 08:25:47.968 Session upkeep
. 2009-10-28 08:25:47.968 Looking for network events
. 2009-10-28 08:25:47.968 Timeout waiting for network events
. 2009-10-28 08:25:48.468 Session upkeep
. 2009-10-28 08:25:48.468 Looking for network events
. 2009-10-28 08:25:48.468 Timeout waiting for network events
. 2009-10-28 08:25:48.968 Session upkeep
. 2009-10-28 08:25:48.968 Looking for network events
. 2009-10-28 08:25:48.968 Timeout waiting for network events
. 2009-10-28 08:25:49.468 Session upkeep
. 2009-10-28 08:25:49.468 Looking for network events
. 2009-10-28 08:25:49.468 Timeout waiting for network events
. 2009-10-28 08:25:49.968 Session upkeep
. 2009-10-28 08:25:49.968 Looking for network events
. 2009-10-28 08:25:49.968 Timeout waiting for network events
. 2009-10-28 08:25:50.468 Session upkeep
. 2009-10-28 08:25:50.468 Looking for network events
. 2009-10-28 08:25:50.468 Timeout waiting for network events
. 2009-10-28 08:25:50.968 Session upkeep
. 2009-10-28 08:25:50.968 Looking for network events
. 2009-10-28 08:25:50.968 Timeout waiting for network events
. 2009-10-28 08:25:51.609 Closing connection.
. 2009-10-28 08:25:51.609 Sending special code: 12
. 2009-10-28 08:25:51.609 Sent EOF message
. 2009-10-28 08:25:51.609 Selecting events 0 for socket 696
. 2009-10-28 08:27:28.921 --------------------------------------------------------------------------
. 2009-10-28 08:27:28.921 WinSCP Version 4.1.9 (Build 416) (OS 5.1.2600 Service Pack 2)
. 2009-10-28 08:27:28.921 Login time: Wednesday, October 28, 2009 8:27:28 AM
. 2009-10-28 08:27:28.921 --------------------------------------------------------------------------
. 2009-10-28 08:27:28.921 Session name: asherber@localhost
. 2009-10-28 08:27:28.921 Host name: localhost (Port: 22)
. 2009-10-28 08:27:28.921 User name: asherber (Password: Yes, Key file: No)
. 2009-10-28 08:27:28.921 Tunnel: No
. 2009-10-28 08:27:28.921 Transfer Protocol: SFTP (SCP)
. 2009-10-28 08:27:28.921 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2009-10-28 08:27:28.921 Proxy: none
. 2009-10-28 08:27:28.921 SSH protocol version: 2; Compression: No
. 2009-10-28 08:27:28.921 Bypass authentication: No
. 2009-10-28 08:27:28.921 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2009-10-28 08:27:28.921 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2009-10-28 08:27:28.921 SSH Bugs: -,-,-,-,-,-,-,-
. 2009-10-28 08:27:28.921 SFTP Bugs: -,-
. 2009-10-28 08:27:28.921 Return code variable: Autodetect; Lookup user groups: Yes
. 2009-10-28 08:27:28.921 Shell: default, EOL: 0
. 2009-10-28 08:27:28.921 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2009-10-28 08:27:28.921 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2009-10-28 08:27:28.921 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-10-28 08:27:28.937 Cache directory changes: Yes, Permanent: Yes
. 2009-10-28 08:27:28.937 DST mode: 1
. 2009-10-28 08:27:28.937 --------------------------------------------------------------------------
. 2009-10-28 08:27:28.968 Looking up host "localhost"
. 2009-10-28 08:27:28.968 Connecting to 127.0.0.1 port 22
. 2009-10-28 08:27:29.140 Selecting events 63 for socket 696
. 2009-10-28 08:27:29.140 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.140 Looking for incoming data
. 2009-10-28 08:27:29.140 Looking for network events
. 2009-10-28 08:27:29.140 Detected network event
. 2009-10-28 08:27:29.140 Enumerating network events for socket 696
. 2009-10-28 08:27:29.140 Enumerated 19 network events making 19 cumulative events for socket 696
. 2009-10-28 08:27:29.140 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.140 Server version: SSH-2.0-FTPshellSSH_1.0
. 2009-10-28 08:27:29.140 We claim version: SSH-2.0-WinSCP_release_4.1.9
. 2009-10-28 08:27:29.140 SSPI: acquired credentials for: Aaron Sherber@LIGHTNING
. 2009-10-28 08:27:29.156 Constructed service principal name 'host/localhost'
. 2009-10-28 08:27:29.156 GSSKEX disabled: No credentials are available in the security package

. 2009-10-28 08:27:29.156 Using SSH protocol version 2
. 2009-10-28 08:27:29.156 Handling network write event on socket 696 with error 0
. 2009-10-28 08:27:29.156 Handling network connect event on socket 696 with error 0
. 2009-10-28 08:27:29.156 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.156 Looking for incoming data
. 2009-10-28 08:27:29.156 Looking for network events
. 2009-10-28 08:27:29.156 Detected network event
. 2009-10-28 08:27:29.156 Enumerating network events for socket 696
. 2009-10-28 08:27:29.156 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.156 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.156 Doing Diffie-Hellman group exchange
. 2009-10-28 08:27:29.156 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.156 Looking for incoming data
. 2009-10-28 08:27:29.156 Looking for network events
. 2009-10-28 08:27:29.156 Detected network event
. 2009-10-28 08:27:29.156 Enumerating network events for socket 696
. 2009-10-28 08:27:29.156 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.156 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.156 Doing Diffie-Hellman key exchange with hash SHA-1
. 2009-10-28 08:27:29.234 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.234 Looking for incoming data
. 2009-10-28 08:27:29.234 Looking for network events
. 2009-10-28 08:27:29.296 Detected network event
. 2009-10-28 08:27:29.296 Enumerating network events for socket 696
. 2009-10-28 08:27:29.296 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.296 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.406 Selecting events 63 for socket 696
. 2009-10-28 08:27:29.406 Host key fingerprint is:
. 2009-10-28 08:27:29.406 ssh-rsa 1024 f9:13:eb:fe:9e:2d:2e:53:41:87:1c:9f:12:a3:9e:02
. 2009-10-28 08:27:29.406 Initialised AES-256 SDCTR client->server encryption
. 2009-10-28 08:27:29.406 Initialised HMAC-SHA1 client->server MAC algorithm
. 2009-10-28 08:27:29.406 Initialised AES-256 SDCTR server->client encryption
. 2009-10-28 08:27:29.406 Initialised HMAC-SHA1 server->client MAC algorithm
. 2009-10-28 08:27:29.406 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.406 Looking for incoming data
. 2009-10-28 08:27:29.406 Looking for network events
. 2009-10-28 08:27:29.406 Detected network event
. 2009-10-28 08:27:29.406 Enumerating network events for socket 696
. 2009-10-28 08:27:29.406 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.406 Handling network read event on socket 696 with error 0
! 2009-10-28 08:27:29.406 Using username "asherber".
. 2009-10-28 08:27:29.406 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.406 Looking for incoming data
. 2009-10-28 08:27:29.406 Looking for network events
. 2009-10-28 08:27:29.406 Detected network event
. 2009-10-28 08:27:29.406 Enumerating network events for socket 696
. 2009-10-28 08:27:29.406 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.406 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.406 Prompt (6, SSH password, , &Password: )
. 2009-10-28 08:27:29.406 Using stored password.
. 2009-10-28 08:27:29.406 Sent password
. 2009-10-28 08:27:29.406 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.406 Looking for incoming data
. 2009-10-28 08:27:29.406 Looking for network events
. 2009-10-28 08:27:29.406 Detected network event
. 2009-10-28 08:27:29.406 Enumerating network events for socket 696
. 2009-10-28 08:27:29.406 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.406 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.406 Access granted
. 2009-10-28 08:27:29.406 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.406 Looking for incoming data
. 2009-10-28 08:27:29.406 Looking for network events
. 2009-10-28 08:27:29.406 Detected network event
. 2009-10-28 08:27:29.406 Enumerating network events for socket 696
. 2009-10-28 08:27:29.406 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.406 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.406 Opened channel for session
. 2009-10-28 08:27:29.406 Waiting for the server to continue with the initialisation
. 2009-10-28 08:27:29.406 Looking for incoming data
. 2009-10-28 08:27:29.406 Looking for network events
. 2009-10-28 08:27:29.406 Detected network event
. 2009-10-28 08:27:29.406 Enumerating network events for socket 696
. 2009-10-28 08:27:29.406 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.406 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.406 Started a shell/command
. 2009-10-28 08:27:29.406 --------------------------------------------------------------------------
. 2009-10-28 08:27:29.406 Using SFTP protocol.
. 2009-10-28 08:27:29.421 Doing startup conversation with host.
> 2009-10-28 08:27:29.421 Type: SSH_FXP_INIT, Size: 5, Number: -1
> 2009-10-28 08:27:29.421 01,00,00,00,05,
. 2009-10-28 08:27:29.421 Sent 9 bytes
. 2009-10-28 08:27:29.421 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:27:29.421 Looking for network events
. 2009-10-28 08:27:29.421 Timeout waiting for network events
. 2009-10-28 08:27:29.421 Waiting for another 4 bytes
. 2009-10-28 08:27:29.421 Looking for incoming data
. 2009-10-28 08:27:29.421 Looking for network events
. 2009-10-28 08:27:29.453 Detected network event
. 2009-10-28 08:27:29.453 Enumerating network events for socket 696
. 2009-10-28 08:27:29.453 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.453 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.453 Received 9 bytes (0)
. 2009-10-28 08:27:29.453 Read 4 bytes (5 pending)
. 2009-10-28 08:27:29.453 Read 5 bytes (0 pending)
< 2009-10-28 08:27:29.453 Type: SSH_FXP_VERSION, Size: 5, Number: -1
< 2009-10-28 08:27:29.453 02,00,00,00,03,
. 2009-10-28 08:27:29.453 SFTP version 3 negotiated.
. 2009-10-28 08:27:29.453 We believe the server has signed timestamps bug
. 2009-10-28 08:27:29.453 We will use UTF-8 strings for status messages only
. 2009-10-28 08:27:29.453 Getting current directory name.
. 2009-10-28 08:27:29.453 Getting real path for '.'
> 2009-10-28 08:27:29.453 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
> 2009-10-28 08:27:29.453 10,00,00,00,10,00,00,00,01,2E,
. 2009-10-28 08:27:29.453 Sent 14 bytes
. 2009-10-28 08:27:29.453 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:27:29.453 Looking for network events
. 2009-10-28 08:27:29.453 Timeout waiting for network events
. 2009-10-28 08:27:29.453 Waiting for another 4 bytes
. 2009-10-28 08:27:29.453 Looking for incoming data
. 2009-10-28 08:27:29.453 Looking for network events
. 2009-10-28 08:27:29.484 Detected network event
. 2009-10-28 08:27:29.484 Enumerating network events for socket 696
. 2009-10-28 08:27:29.484 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.484 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.484 Received 27 bytes (0)
. 2009-10-28 08:27:29.484 Read 4 bytes (23 pending)
. 2009-10-28 08:27:29.484 Read 23 bytes (0 pending)
< 2009-10-28 08:27:29.484 Type: SSH_FXP_NAME, Size: 23, Number: 16
< 2009-10-28 08:27:29.484 68,00,00,00,10,00,00,00,01,00,00,00,01,2F,00,00,00,01,2F,00,00,00,00,
. 2009-10-28 08:27:29.484 Real path is '/'
. 2009-10-28 08:27:29.484 Listing directory "/".
> 2009-10-28 08:27:29.484 Type: SSH_FXP_OPENDIR, Size: 10, Number: 267
> 2009-10-28 08:27:29.484 0B,00,00,01,0B,00,00,00,01,2F,
. 2009-10-28 08:27:29.484 Sent 14 bytes
. 2009-10-28 08:27:29.484 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:27:29.484 Looking for network events
. 2009-10-28 08:27:29.484 Timeout waiting for network events
. 2009-10-28 08:27:29.484 Waiting for another 4 bytes
. 2009-10-28 08:27:29.484 Looking for incoming data
. 2009-10-28 08:27:29.484 Looking for network events
. 2009-10-28 08:27:29.515 Detected network event
. 2009-10-28 08:27:29.515 Enumerating network events for socket 696
. 2009-10-28 08:27:29.515 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.515 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.515 Received 17 bytes (0)
. 2009-10-28 08:27:29.515 Read 4 bytes (13 pending)
. 2009-10-28 08:27:29.515 Read 13 bytes (0 pending)
< 2009-10-28 08:27:29.515 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
< 2009-10-28 08:27:29.515 66,00,00,01,0B,00,00,00,04,0F,0F,0F,0F,
> 2009-10-28 08:27:29.515 Type: SSH_FXP_READDIR, Size: 13, Number: 524
> 2009-10-28 08:27:29.515 0C,00,00,02,0C,00,00,00,04,0F,0F,0F,0F,
. 2009-10-28 08:27:29.515 Sent 17 bytes
. 2009-10-28 08:27:29.515 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:27:29.515 Looking for network events
. 2009-10-28 08:27:29.515 Timeout waiting for network events
. 2009-10-28 08:27:29.515 Waiting for another 4 bytes
. 2009-10-28 08:27:29.515 Looking for incoming data
. 2009-10-28 08:27:29.515 Looking for network events
. 2009-10-28 08:27:29.546 Detected network event
. 2009-10-28 08:27:29.546 Enumerating network events for socket 696
. 2009-10-28 08:27:29.546 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.546 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.546 Received 804 bytes (0)
. 2009-10-28 08:27:29.546 Read 4 bytes (800 pending)
. 2009-10-28 08:27:29.546 Read 800 bytes (0 pending)
< 2009-10-28 08:27:29.546 Type: SSH_FXP_NAME, Size: 800, Number: 524
< 2009-10-28 08:27:29.546 68,00,00,02,0C,00,00,00,07,00,00,00,0B,41,74,74,61,63,68,6D,65,6E,74,73,00,
< 2009-10-28 08:27:29.546 00,00,44,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,
< 2009-10-28 08:27:29.546 72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,20,20,30,20,46,65,62,20,20,
< 2009-10-28 08:27:29.546 31,30,20,20,30,32,3A,34,34,20,41,74,74,61,63,68,6D,65,6E,74,73,00,00,00,0D,
< 2009-10-28 08:27:29.546 00,00,00,00,00,00,02,00,00,00,41,80,49,91,30,67,49,91,30,67,00,00,00,09,44,
< 2009-10-28 08:27:29.546 6F,63,75,6D,65,6E,74,73,00,00,00,41,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,
< 2009-10-28 08:27:29.546 30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,
< 2009-10-28 08:27:29.546 20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,44,6F,63,75,6D,65,6E,
< 2009-10-28 08:27:29.546 74,73,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,9A,C6,43,3D,9A,
< 2009-10-28 08:27:29.546 C6,00,00,00,0E,46,6F,72,6D,44,65,73,69,67,6E,2E,72,65,67,00,00,00,49,2D,72,
< 2009-10-28 08:27:29.546 77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,
< 2009-10-28 08:27:29.546 61,73,68,65,72,62,65,72,20,20,20,20,20,32,35,34,20,4A,75,6E,20,20,32,37,20,
< 2009-10-28 08:27:29.546 20,31,38,3A,33,34,20,46,6F,72,6D,44,65,73,69,67,6E,2E,72,65,67,00,00,00,0D,
< 2009-10-28 08:27:29.546 00,00,00,00,00,00,00,FE,00,00,81,80,4A,46,9E,68,4A,46,9E,68,00,00,00,08,50,
< 2009-10-28 08:27:29.546 72,6F,6A,65,63,74,73,00,00,00,40,64,72,77,2D,2D,2D,2D,2D,2D,2D,20,20,20,30,
< 2009-10-28 08:27:29.546 20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,20,20,20,20,
< 2009-10-28 08:27:29.546 20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,50,72,6F,6A,65,63,74,73,
< 2009-10-28 08:27:29.546 00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,A4,E3,43,3D,A4,E3,00,
< 2009-10-28 08:27:29.546 00,00,08,52,45,43,59,43,4C,45,52,00,00,00,40,64,72,77,2D,2D,2D,2D,2D,2D,2D,
< 2009-10-28 08:27:29.546 20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,65,72,62,65,72,
< 2009-10-28 08:27:29.546 20,20,20,20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,20,52,45,43,59,
< 2009-10-28 08:27:29.546 43,4C,45,52,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,B5,16,43,
< 2009-10-28 08:27:29.546 3D,B5,16,00,00,00,0A,53,75,62,76,65,72,73,69,6F,6E,00,00,00,43,64,72,77,2D,
< 2009-10-28 08:27:29.546 2D,2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,
< 2009-10-28 08:27:29.546 68,65,72,62,65,72,20,20,20,20,20,30,20,4A,75,6E,20,20,31,38,20,20,31,36,3A,
< 2009-10-28 08:27:29.546 35,31,20,53,75,62,76,65,72,73,69,6F,6E,00,00,00,0D,00,00,00,00,00,00,02,00,
< 2009-10-28 08:27:29.546 00,00,41,80,4A,3A,A8,BF,4A,3A,A8,BF,00,00,00,19,53,79,73,74,65,6D,20,56,6F,
< 2009-10-28 08:27:29.546 6C,75,6D,65,20,49,6E,66,6F,72,6D,61,74,69,6F,6E,00,00,00,51,64,72,77,2D,2D,
< 2009-10-28 08:27:29.546 2D,2D,2D,2D,2D,20,20,20,30,20,61,73,68,65,72,62,65,72,20,20,20,20,61,73,68,
< 2009-10-28 08:27:29.546 65,72,62,65,72,20,20,20,20,20,30,20,53,65,70,20,20,33,30,20,20,32,30,30,35,
< 2009-10-28 08:27:29.546 20,53,79,73,74,65,6D,20,56,6F,6C,75,6D,65,20,49,6E,66,6F,72,6D,61,74,69,6F,
< 2009-10-28 08:27:29.546 6E,00,00,00,0D,00,00,00,00,00,00,02,00,00,00,41,80,43,3D,95,C5,43,3D,95,C5,
> 2009-10-28 08:27:29.546 Type: SSH_FXP_READDIR, Size: 13, Number: 780
> 2009-10-28 08:27:29.546 0C,00,00,03,0C,00,00,00,04,0F,0F,0F,0F,
. 2009-10-28 08:27:29.546 Sent 17 bytes
. 2009-10-28 08:27:29.546 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:27:29.546 Looking for network events
. 2009-10-28 08:27:29.546 Timeout waiting for network events
. 2009-10-28 08:27:29.546 Read file 'Attachments' from listing
. 2009-10-28 08:27:29.546 Read file 'Documents' from listing
. 2009-10-28 08:27:29.546 Read file 'FormDesign.reg' from listing
. 2009-10-28 08:27:29.546 Read file 'Projects' from listing
. 2009-10-28 08:27:29.546 Read file 'RECYCLER' from listing
. 2009-10-28 08:27:29.546 Read file 'Subversion' from listing
. 2009-10-28 08:27:29.546 Read file 'System Volume Information' from listing
. 2009-10-28 08:27:29.546 Waiting for another 4 bytes
. 2009-10-28 08:27:29.546 Looking for incoming data
. 2009-10-28 08:27:29.546 Looking for network events
. 2009-10-28 08:27:29.578 Detected network event
. 2009-10-28 08:27:29.578 Enumerating network events for socket 696
. 2009-10-28 08:27:29.578 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:29.578 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:29.578 Received 32 bytes (0)
. 2009-10-28 08:27:29.578 Read 4 bytes (28 pending)
. 2009-10-28 08:27:29.578 Read 28 bytes (0 pending)
< 2009-10-28 08:27:29.578 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2009-10-28 08:27:29.578 65,00,00,03,0C,00,00,00,01,00,00,00,0B,45,6E,64,20,6F,66,20,66,69,6C,65,00,
< 2009-10-28 08:27:29.578 00,00,00,
< 2009-10-28 08:27:29.578 Status/error code: 1
> 2009-10-28 08:27:29.578 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
> 2009-10-28 08:27:29.578 04,00,00,04,04,00,00,00,04,0F,0F,0F,0F,
. 2009-10-28 08:27:29.578 Sent 17 bytes
. 2009-10-28 08:27:29.578 There are 0 bytes remaining in the send buffer
. 2009-10-28 08:27:29.578 Looking for network events
. 2009-10-28 08:27:29.578 Timeout waiting for network events
. 2009-10-28 08:27:29.578 Startup conversation with host finished.
. 2009-10-28 08:27:30.218 Session upkeep
. 2009-10-28 08:27:30.218 Looking for network events
. 2009-10-28 08:27:30.218 Detected network event
. 2009-10-28 08:27:30.218 Enumerating network events for socket 696
. 2009-10-28 08:27:30.218 Enumerated 1 network events making 1 cumulative events for socket 696
. 2009-10-28 08:27:30.218 Handling network read event on socket 696 with error 0
. 2009-10-28 08:27:30.218 Received 28 bytes (0)
. 2009-10-28 08:27:30.718 Session upkeep
. 2009-10-28 08:27:30.718 Looking for network events
. 2009-10-28 08:27:30.718 Timeout waiting for network events
. 2009-10-28 08:27:31.218 Session upkeep
. 2009-10-28 08:27:31.218 Looking for network events
. 2009-10-28 08:27:31.218 Timeout waiting for network events
. 2009-10-28 08:27:31.390 Closing connection.
. 2009-10-28 08:27:31.390 Sending special code: 12
. 2009-10-28 08:27:31.390 Sent EOF message
. 2009-10-28 08:27:31.390 Selecting events 0 for socket 696


FTPSHELL LOG
---- WINSCP 4.1.9 ------
10/28/2009 8:25:11 NOTE: FTP server started - listening on port 22
10/28/2009 8:25:45 NOTE: Connection from 127.0.0.1 logged into account asherber
10/28/2009 8:25:51 ERROR:An error occured during key exchange
10/28/2009 8:25:51 NOTE: Connection from 127.0.0.1 disconnected
---- WINSCP 4.2.4 ----
10/28/2009 8:30:41 NOTE: FTP server started - listening on port 22
10/28/2009 8:31:47 NOTE: Connection from 192.168.0.6 logged into account asherber
10/28/2009 8:31:47 ERROR:An error occured during key exchange
10/28/2009 8:31:47 NOTE: Connection from 192.168.0.6 disconnected
martin

Re: 4.2.4 fails login to FTPShell server

Can you post a log file for WinSCP 4.1.9 as well? Server's log file might be even more useful.
asherber

4.2.4 fails login to FTPShell server

Hi,

Using WinSCP 4.2.4 on WinXP, am unable to log in to FTPShell SFTP server v4.22. This works in 4.1.9.

Log file is below. I can provide access to a server for testing if needed.

Thanks,
Aaron.

. 2009-10-27 18:09:10.928 --------------------------------------------------------------------------
. 2009-10-27 18:09:10.928 WinSCP Version 4.2.4 (Build 610) (OS 5.1.2600 Service Pack 2)
. 2009-10-27 18:09:10.928 Login time: Tuesday, October 27, 2009 6:09:10 PM
. 2009-10-27 18:09:10.928 --------------------------------------------------------------------------
. 2009-10-27 18:09:10.928 Session name: FTPShell
. 2009-10-27 18:09:10.928 Host name: FTPShell (Port: 22)
. 2009-10-27 18:09:10.928 User name: aissupdate (Password: Yes, Key file: No)
. 2009-10-27 18:09:10.928 Tunnel: No
. 2009-10-27 18:09:10.928 Transfer Protocol: SFTP
. 2009-10-27 18:09:10.928 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2009-10-27 18:09:10.928 Proxy: none
. 2009-10-27 18:09:10.928 SSH protocol version: 2; Compression: No
. 2009-10-27 18:09:10.928 Bypass authentication: No
. 2009-10-27 18:09:10.928 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2009-10-27 18:09:10.928 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2009-10-27 18:09:10.928 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2009-10-27 18:09:10.928 SFTP Bugs: -,-
. 2009-10-27 18:09:10.928 Return code variable: Autodetect; Lookup user groups: Yes
. 2009-10-27 18:09:10.928 Shell: default, EOL: 0
. 2009-10-27 18:09:10.928 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2009-10-27 18:09:10.928 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2009-10-27 18:09:10.928 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-10-27 18:09:10.928 Cache directory changes: Yes, Permanent: Yes
. 2009-10-27 18:09:10.928 DST mode: 1
. 2009-10-27 18:09:10.928 --------------------------------------------------------------------------
. 2009-10-27 18:09:10.959 Looking up host "FTPShell"
. 2009-10-27 18:09:11.037 Connecting to port 22
. 2009-10-27 18:09:11.334 Selecting events 63 for socket 672
. 2009-10-27 18:09:11.334 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:11.334 Looking for incoming data
. 2009-10-27 18:09:11.334 Looking for network events
. 2009-10-27 18:09:11.334 Detected network event
. 2009-10-27 18:09:11.334 Enumerating network events for socket 672
. 2009-10-27 18:09:11.334 Enumerated 18 network events making 18 cumulative events for socket 672
. 2009-10-27 18:09:11.334 Handling network write event on socket 672 with error 0
. 2009-10-27 18:09:11.334 Handling network connect event on socket 672 with error 0
. 2009-10-27 18:09:11.334 Looking for network events
. 2009-10-27 18:09:11.397 Detected network event
. 2009-10-27 18:09:11.397 Enumerating network events for socket 672
. 2009-10-27 18:09:11.397 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:11.397 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:11.397 Server version: SSH-2.0-FTPshellSSH_1.0
. 2009-10-27 18:09:11.397 Using SSH protocol version 2
. 2009-10-27 18:09:11.397 We claim version: SSH-2.0-WinSCP_release_4.2.4
. 2009-10-27 18:09:11.397 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:11.397 Looking for incoming data
. 2009-10-27 18:09:11.397 Looking for network events
. 2009-10-27 18:09:11.475 Detected network event
. 2009-10-27 18:09:11.475 Enumerating network events for socket 672
. 2009-10-27 18:09:11.475 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:11.475 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:11.475 Doing Diffie-Hellman group exchange
. 2009-10-27 18:09:11.475 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:11.475 Looking for incoming data
. 2009-10-27 18:09:11.475 Looking for network events
. 2009-10-27 18:09:11.741 Detected network event
. 2009-10-27 18:09:11.756 Enumerating network events for socket 672
. 2009-10-27 18:09:11.756 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:11.756 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:11.756 Doing Diffie-Hellman key exchange with hash SHA-1
. 2009-10-27 18:09:11.834 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:11.834 Looking for incoming data
. 2009-10-27 18:09:11.834 Looking for network events
. 2009-10-27 18:09:12.006 Detected network event
. 2009-10-27 18:09:12.006 Enumerating network events for socket 672
. 2009-10-27 18:09:12.006 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:12.022 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:12.116 Selecting events 63 for socket 672
. 2009-10-27 18:09:12.116 Host key fingerprint is:
. 2009-10-27 18:09:12.116 ssh-rsa 1024 f9:13:eb:fe:9e:2d:2e:53:41:87:1c:9f:12:a3:9e:02
. 2009-10-27 18:09:12.116 Initialised AES-256 SDCTR client->server encryption
. 2009-10-27 18:09:12.116 Initialised HMAC-SHA1 client->server MAC algorithm
. 2009-10-27 18:09:12.116 Initialised AES-256 SDCTR server->client encryption
. 2009-10-27 18:09:12.116 Initialised HMAC-SHA1 server->client MAC algorithm
. 2009-10-27 18:09:12.116 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:12.116 Looking for incoming data
. 2009-10-27 18:09:12.116 Looking for network events
. 2009-10-27 18:09:12.412 Detected network event
. 2009-10-27 18:09:12.412 Enumerating network events for socket 672
. 2009-10-27 18:09:12.412 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:12.412 Handling network read event on socket 672 with error 0
! 2009-10-27 18:09:12.412 Using username "aissupdate".
. 2009-10-27 18:09:12.412 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:12.412 Looking for incoming data
. 2009-10-27 18:09:12.412 Looking for network events
. 2009-10-27 18:09:12.475 Detected network event
. 2009-10-27 18:09:12.475 Enumerating network events for socket 672
. 2009-10-27 18:09:12.475 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:12.475 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:12.475 Prompt (6, SSH password, , &Password: )
. 2009-10-27 18:09:12.475 Using stored password.
. 2009-10-27 18:09:12.475 Sent password
. 2009-10-27 18:09:12.475 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:12.475 Looking for incoming data
. 2009-10-27 18:09:12.475 Looking for network events
. 2009-10-27 18:09:12.553 Detected network event
. 2009-10-27 18:09:12.553 Enumerating network events for socket 672
. 2009-10-27 18:09:12.553 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:12.553 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:12.553 Access granted
. 2009-10-27 18:09:12.553 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:12.553 Looking for incoming data
. 2009-10-27 18:09:12.553 Looking for network events
. 2009-10-27 18:09:12.631 Detected network event
. 2009-10-27 18:09:12.631 Enumerating network events for socket 672
. 2009-10-27 18:09:12.631 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:12.631 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:12.631 Opened channel for session
. 2009-10-27 18:09:12.631 Waiting for the server to continue with the initialisation
. 2009-10-27 18:09:12.631 Looking for incoming data
. 2009-10-27 18:09:12.631 Looking for network events
. 2009-10-27 18:09:12.694 Detected network event
. 2009-10-27 18:09:12.694 Enumerating network events for socket 672
. 2009-10-27 18:09:12.694 Enumerated 1 network events making 1 cumulative events for socket 672
. 2009-10-27 18:09:12.694 Handling network read event on socket 672 with error 0
. 2009-10-27 18:09:12.694 Received disconnect message (protocol error)
. 2009-10-27 18:09:12.694 Disconnection message text: disconnecting
. 2009-10-27 18:09:12.694 Selecting events 0 for socket 672
. 2009-10-27 18:09:12.694 Server sent disconnect message
. 2009-10-27 18:09:12.694 type 2 (protocol error):
. 2009-10-27 18:09:12.694 "disconnecting"
* 2009-10-27 18:09:12.709 (ESshFatal) Server sent disconnect message
* 2009-10-27 18:09:12.709 type 2 (protocol error):
* 2009-10-27 18:09:12.709 "disconnecting"
* 2009-10-27 18:09:12.709 Authentication log (see session log for details):
* 2009-10-27 18:09:12.709 Using username "aissupdate".
* 2009-10-27 18:09:12.709
* 2009-10-27 18:09:12.709 Authentication failed.