Error message on log file

2012-04-05 10:07:18 INFO Connectoin to sFTP succeeded
2012-04-05 10:07:18 INFO ------Async Upload through WINSCP starts-------
2012-04-05 10:07:18 FATAL Error while uploading data to Akamai: Cannot access a closed file.
2012-04-05 10:07:19 FATAL Error while uploading data to Akamai: Error parsing session log file


[2012-04-05 10:12:49Z] [000d] Exception: WinSCP.SessionLocalException: Element "group" not found in the log file
[2012-04-05 10:12:49Z] [000b] Exception: WinSCP.SessionLocalException: Error parsing session log file ---> System.Xml.XmlException: Unexpected end of file has occurred. The following elements are not closed: group, session. Line 6, position 1.
at System.Xml.XmlTextReaderImpl.Throw(Exception e)
at System.Xml.XmlTextReaderImpl.Throw(String res, String arg)
at System.Xml.XmlTextReaderImpl.ThrowUnclosedElements()
at System.Xml.XmlTextReaderImpl.ParseElementContent()
at System.Xml.XmlTextReaderImpl.Read()
at WinSCP.SessionLogReader.DoRead()
--- End of inner exception stack trace ---
[2012-04-05 10:12:49Z] [000d] Session.PutFiles leaving
[2012-04-05 10:12:49Z] [000b] Session.PutFiles leaving
[2012-04-05 10:12:49Z] [0009] Session.PutFiles leaving


Any idea on why does this error happen? I am trying to upload simultaneous files using delegates..

Please help

The Session class is not thread safe! So if you want simultaneous transfers, make sure you create a separate Session for every thread.
See https://winscp.net/eng/docs/library_session#remarks

yeah, kinda figured that out already and in process of changing the implementation
Thanks

Hi

The implementation was changed and I have now separate session objects being created for each upload

Now, for example If I have 36 files to upload, it processes like 18-20 of them and for the rest, throws the following error -

2012-04-06 14:02:26 FATAL Connected to sFTP failed: Connection has been unexpectedly closed. Server sent command exit status 0.

Can you help me by explaining for status 0 here is?

-Chirag

Please post a full log file showing the problem.

Thanks for responding - the full log is attached

c10c wrote:

Thanks for responding - the full log is attached

Ok, please check server log file to see, why it forcibly closes your session.

The differences between a successful session and unsuccessful are mentioned below -

Successful -
-----------------
! 2012-04-09 13:57:42.387 Authenticating with public key "rsa-key-20120217"
. 2012-04-09 13:57:42.409 Sent public key signature
. 2012-04-09 13:57:42.583 Remote debug message: Agent forwarding disabled.
. 2012-04-09 13:57:42.947 Remote debug message: X11 forwarding disabled.
. 2012-04-09 13:57:42.947 Remote debug message: Forced command: /home/sshacs/acssshsink acetrax_test
. 2012-04-09 13:57:42.947 Access granted
. 2012-04-09 13:57:43.115 Opened channel for session
. 2012-04-09 13:57:43.508 Started a shell/command
. 2012-04-09 13:57:43.508 --------------------------------------------------------------------------
. 2012-04-09 13:57:43.508 Using SFTP protocol.
. 2012-04-09 13:57:43.508 Doing startup conversation with host.
> 2012-04-09 13:57:43.509 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2012-04-09 13:57:43.893 Type: SSH_FXP_VERSION, Size: 38, Number: -1
. 2012-04-09 13:57:43.893 SFTP version 3 negotiated.
-----------------------

Unsuccessful
! 2012-04-09 13:58:22.964 Authenticating with public key "rsa-key-20120217"
. 2012-04-09 13:58:22.996 Sent public key signature
. 2012-04-09 13:58:23.173 Remote debug message: Agent forwarding disabled.
. 2012-04-09 13:58:23.548 Remote debug message: X11 forwarding disabled.
. 2012-04-09 13:58:23.548 Remote debug message: Forced command: /home/sshacs/acssshsink acetrax_test
. 2012-04-09 13:58:23.548 Access granted
. 2012-04-09 13:58:23.721 Opened channel for session
. 2012-04-09 13:58:24.114 Started a shell/command
. 2012-04-09 13:58:24.124 --------------------------------------------------------------------------
. 2012-04-09 13:58:24.124 Using SFTP protocol.
. 2012-04-09 13:58:24.124 Doing startup conversation with host.
> 2012-04-09 13:58:24.128 Type: SSH_FXP_INIT, Size: 5, Number: -1
. 2012-04-09 13:58:25.111 Server sent command exit status 0

As my sessions are ASync - there is no difference in the settings done through the implementation

Should i be setting another property?

This SSH_FXP_VERSION is not authenticated properly. Should something be updated on my local machine?

Please suggest

Please check server log, not client (WinSCP) log.

This is the logs i have. Any idea where i should look for the server logs?
I had set the WINSCP client log path but no server log path was set? Are they created automatically?
Thanks

c10c wrote:

This is the logs i have. Any idea where i should look for the server logs?
I had set the WINSCP client log path but no server log path was set? Are they created automatically?
Thanks

Server log has nothing to do with WinSCP. Every SSH server is different. So I cannot help here.

ahh, i get what you meant. you wanted me to check the server which i am connecting for their logs.. there is no problem from WINSCP side is what you meant
Thanks

c10c wrote:

ahh, i get what you meant. you wanted me to check the server which i am connecting for their logs.. there is no problem from WINSCP side is what you meant

Correct.