Forum » Scripting / Automation »

Timeout while waiting on server to respond when running script

Advertisement

BVD
Guest

Timeout while waiting on server to respond when running script

Hello,

Apologies if this question has been asked before, but I am experiencing a problem when running a script using WinSCP 4.1.5.

To explain a little without going into details, when running the script it will use a "call" command to execute a series of commands on the server. Unfortunately, some of these commands are extremely slow. (this can't be changed unfortunately)

When running these commands, it can happen regularly that it suddenly decides to time out and not continue further with the script.

An example of the script being run: 
option batch continue
open Server1
call source remotescript1.sh
call source remotescript2.sh
get -delete output1.tar.gz "\\local-server\storage\"
get -delete output2.tar.gz "\\local-server\storage\"
close
exit
When looking at the logs, it seems to run fine, but suddenly it disconnects with a message stating the server timed out and it doesn't continue.

What I've tried as well is to open and close the session after each call command but if it loses connection ones it doesn't recover anymore.

The session in WinSCP's GUI is set up to have a timeout of the maximum allowed 6000 seconds, but this doesn't seem to help.

Any advice on this issue would be greatly appreciated.

Thanks.

Reply with quote

Advertisement

JRB_BMB
Joined:
Posts:
24
Location:
Midlands, UK

Tried breaking down the scripts into smaller more executable chunks maybe?
This would at least increase the server responses hopefully preventing the time out; okay so the caveat is you have to have maybe twice as many scripts but if its a copy and paste job pretty much is it really that bad?

Could always just look into your scripts and see what the delay is and why; sometimes there's not much you can do but you'd be surprised what a bit of re-ordering or re-thinking on certain bits can do to lighten the load overall and speed things up.

Good luck anyway!
JRB

Reply with quote

BVD
Guest

This is something I've tried already - unfortunately it all comes down to the server's performance as well in the end. On another server the same functions prove to be no problems and no timeouts occur.

When running the similar script using Putty, there is no timeout.

Reply with quote

JRB_BMB
Joined:
Posts:
24
Location:
Midlands, UK

Would it be worth looking into what other processes are running on this server then?
I presume the server boxes are the same capability wise; so maybe resource when you're timing this set of actions is being done at the same time the server has another scheduled task going on zapping both processes. So maybe some 'tweaking' of your timing may help.
Hardware is a very political territory - every man and his dog have opinions - so it's case of looking at your system and doing what you feel is necessary. Thus why I'm not gonna comment on it!

Bar that look at what resources the server does have and how they're being used. I always find a virus scan, spyware scan, a chkdsk and a defrag an easy maintenance task to perform before getting nitty gritty.

No offence but if it works with PuTTY; why so determined to make it work with WinSCP? Just curious not being rude! :o)

Reply with quote

martin◆
Site Admin
martin avatar
Joined:
Posts:
41,442
Location:
Prague, Czechia

Re: Timeout while waiting on server to respond when running scri

BVD wrote:

When running these commands, it can happen regularly that it suddenly decides to time out and not continue further with the script.
Can you post an exact message? Full output of the script may be useful.

Reply with quote

Advertisement

BVD
Guest

The servers are, as far as I know, not using the same specs which does explain the differences in the load on them.

To answer the question why using WinSCP - this is the program we use here for multiple operations so it'd be easier to use this than to move to a different solution.

Here are the last lines of the log file where it got to the end of running a script: 
. 2008-08-04 10:17:04.974 [Shell] Startup conversation with host finished.
. 2008-08-04 10:17:04.974 Executing user defined command on command session.
> 2008-08-04 10:17:04.974 [Shell] source remotescript1.sh ; echo "WinSCP: this is end-of-file:$?"
< 2008-08-04 11:05:52.332 [Shell] output.csv
< 2008-08-04 11:05:52.629 [Shell] WinSCP: this is end-of-file:0
> 2008-08-04 11:05:52.644 [Shell] pwd ; echo "WinSCP: this is end-of-file:$?"
< 2008-08-04 11:05:52.691 [Shell] /home/user/output
< 2008-08-04 11:05:52.691 [Shell] WinSCP: this is end-of-file:0
. 2008-08-04 11:05:52.691 Cached directory change via "/home/user/output" to "/home/user/output".
. 2008-08-04 11:05:52.691 Getting current directory name.
. 2008-08-04 11:05:52.691 Getting current directory name.
. 2008-08-04 11:05:52.691 Received disconnect message (protocol error)
. 2008-08-04 11:05:52.691 Disconnection message text: Timeout, your session not responding.
. 2008-08-04 11:05:52.691 Server sent disconnect message
. 2008-08-04 11:05:52.691 type 2 (protocol error):
. 2008-08-04 11:05:52.691 "Timeout, your session not responding."
. 2008-08-04 11:05:52.691 [Shell] Closing connection.
. 2008-08-04 11:05:52.691 [Shell] Sending special code: 12
. 2008-08-04 11:05:52.691 [Shell] Sent EOF message
It does run it's script and everything completes, but when it is to move on to the next step it disconnects despite having a valid connection. If it's WinSCP or the server causing the disconnect, I do not know.

I tried fixing this by having a close followed by an open after each command but that does not seem to work all the time either.

Reply with quote

Godai
Joined:
Posts:
1

Here's one. We are using WinSCP to update our game server source + patch data and sometimes rebuild the servers via a script.
This script can be very slow and WinSCP times out after a random period of time. See the log for two consecutive runs :) 
. 2008-09-19 11:29:59.187 --------------------------------------------------------------------------
. 2008-09-19 11:29:59.187 WinSCP Version 4.1.6 (Build 412) (OS 5.1.2600 Service Pack 3)
. 2008-09-19 11:29:59.187 Login time: 19. september 2008 11:29:59
. 2008-09-19 11:29:59.187 --------------------------------------------------------------------------
. 2008-09-19 11:29:59.203 Session name: root@192.168.1.25
. 2008-09-19 11:29:59.203 Host name: 192.168.1.25 (Port: 22)
. 2008-09-19 11:29:59.203 User name: root (Password: Yes, Key file: No)
. 2008-09-19 11:29:59.203 Tunnel: No
. 2008-09-19 11:29:59.203 Transfer Protocol: SFTP (SCP)
. 2008-09-19 11:29:59.203 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2008-09-19 11:29:59.203 Proxy: none
. 2008-09-19 11:29:59.203 SSH protocol version: 2; Compression: No
. 2008-09-19 11:29:59.203 Bypass authentication: No
. 2008-09-19 11:29:59.203 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2008-09-19 11:29:59.203 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2008-09-19 11:29:59.203 SSH Bugs: -,-,-,-,-,-,-,-
. 2008-09-19 11:29:59.203 SFTP Bugs: -,-
. 2008-09-19 11:29:59.203 Return code variable: Autodetect; Lookup user groups: Yes
. 2008-09-19 11:29:59.203 Shell: default, EOL: 0
. 2008-09-19 11:29:59.203 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2008-09-19 11:29:59.203 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2008-09-19 11:29:59.203 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2008-09-19 11:29:59.203 Cache directory changes: Yes, Permanent: Yes
. 2008-09-19 11:29:59.203 DST mode: 1
. 2008-09-19 11:29:59.203 --------------------------------------------------------------------------
. 2008-09-19 11:29:59.203 Looking up host "192.168.1.25"
. 2008-09-19 11:29:59.203 Connecting to 192.168.1.25 port 22
. 2008-09-19 11:29:59.234 Server version: SSH-2.0-OpenSSH_4.7p1 Debian-8ubuntu1.2
. 2008-09-19 11:29:59.234 We claim version: SSH-2.0-WinSCP_release_4.1.6
. 2008-09-19 11:29:59.234 SSPI: acquired credentials for: Odin Jensen@PC005
. 2008-09-19 11:29:59.234 Cannot convert IP address to SPN: DNS is not trusted
. 2008-09-19 11:29:59.234 GSSKEX disabled: Handlingen er gennemfort.
 
. 2008-09-19 11:29:59.234 Using SSH protocol version 2
. 2008-09-19 11:29:59.234 Doing Diffie-Hellman group exchange
. 2008-09-19 11:29:59.312 Doing Diffie-Hellman key exchange with hash SHA-1
. 2008-09-19 11:30:00.140 Host key fingerprint is:
. 2008-09-19 11:30:00.140 ssh-rsa 2048 29:5b:d3:d2:e8:4a:20:a9:2f:b9:b7:f7:62:52:11:71
. 2008-09-19 11:30:00.140 Initialised AES-256 SDCTR client->server encryption
. 2008-09-19 11:30:00.140 Initialised HMAC-SHA1 client->server MAC algorithm
. 2008-09-19 11:30:00.140 Initialised AES-256 SDCTR server->client encryption
. 2008-09-19 11:30:00.140 Initialised HMAC-SHA1 server->client MAC algorithm
! 2008-09-19 11:30:00.296 Using username "root".
. 2008-09-19 11:30:00.312 Prompt (6, SSH password, , &Password: )
. 2008-09-19 11:30:00.312 Using stored password.
. 2008-09-19 11:30:00.312 Sent password
. 2008-09-19 11:30:00.328 Access granted
. 2008-09-19 11:30:00.328 Opened channel for session
. 2008-09-19 11:30:00.437 Started a shell/command
. 2008-09-19 11:30:00.437 --------------------------------------------------------------------------
. 2008-09-19 11:30:00.437 Using SFTP protocol.
. 2008-09-19 11:30:00.437 Doing startup conversation with host.
> 2008-09-19 11:30:00.437 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2008-09-19 11:30:00.453 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2008-09-19 11:30:00.453 SFTP version 3 negotiated.
. 2008-09-19 11:30:00.453 We believe the server has signed timestamps bug
. 2008-09-19 11:30:00.453 We will use UTF-8 strings for status messages only
. 2008-09-19 11:30:00.453 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2008-09-19 11:30:00.453 Getting current directory name.
. 2008-09-19 11:30:00.453 Getting real path for '.'
> 2008-09-19 11:30:00.453 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2008-09-19 11:30:00.453 Type: SSH_FXP_NAME, Size: 31, Number: 16
. 2008-09-19 11:30:00.453 Real path is '/root'
. 2008-09-19 11:30:00.453 Startup conversation with host finished.
. 2008-09-19 11:30:00.453 Cached directory change via "/home/guppyworks" to "/home/guppyworks".
. 2008-09-19 11:30:00.453 Getting current directory name.
. 2008-09-19 11:30:00.468 [Shell] Looking up host "192.168.1.25"
. 2008-09-19 11:30:00.468 [Shell] Connecting to 192.168.1.25 port 22
. 2008-09-19 11:30:00.500 [Shell] Server version: SSH-2.0-OpenSSH_4.7p1 Debian-8ubuntu1.2
. 2008-09-19 11:30:00.500 [Shell] We claim version: SSH-2.0-WinSCP_release_4.1.6
. 2008-09-19 11:30:00.500 [Shell] SSPI: acquired credentials for: Odin Jensen@PC005
. 2008-09-19 11:30:00.500 [Shell] Cannot convert IP address to SPN: DNS is not trusted
. 2008-09-19 11:30:00.500 [Shell] GSSKEX disabled: Handlingen er gennemfort.
 
. 2008-09-19 11:30:00.500 [Shell] Using SSH protocol version 2
. 2008-09-19 11:30:00.515 [Shell] Doing Diffie-Hellman group exchange
. 2008-09-19 11:30:00.609 [Shell] Doing Diffie-Hellman key exchange with hash SHA-1
. 2008-09-19 11:30:01.390 [Shell] Host key fingerprint is:
. 2008-09-19 11:30:01.390 [Shell] ssh-rsa 2048 29:5b:d3:d2:e8:4a:20:a9:2f:b9:b7:f7:62:52:11:71
. 2008-09-19 11:30:01.390 [Shell] Initialised AES-256 SDCTR client->server encryption
. 2008-09-19 11:30:01.390 [Shell] Initialised HMAC-SHA1 client->server MAC algorithm
. 2008-09-19 11:30:01.390 [Shell] Initialised AES-256 SDCTR server->client encryption
. 2008-09-19 11:30:01.390 [Shell] Initialised HMAC-SHA1 server->client MAC algorithm
! 2008-09-19 11:30:01.390 [Shell] Using username "root".
. 2008-09-19 11:30:01.437 [Shell] Prompt (6, SSH password, , &Password: )
. 2008-09-19 11:30:01.437 [Shell] Using stored password.
. 2008-09-19 11:30:01.437 [Shell] Sent password
. 2008-09-19 11:30:01.468 [Shell] Access granted
. 2008-09-19 11:30:01.468 [Shell] Opened channel for session
. 2008-09-19 11:30:01.500 [Shell] Started a shell/command
. 2008-09-19 11:30:01.500 [Shell] --------------------------------------------------------------------------
. 2008-09-19 11:30:01.500 [Shell] Using SCP protocol.
. 2008-09-19 11:30:01.500 [Shell] Doing startup conversation with host.
. 2008-09-19 11:30:01.500 [Shell] Skipping host startup message (if any).
! 2008-09-19 11:30:01.500 [Shell] stdin: is not a tty
> 2008-09-19 11:30:01.500 [Shell] echo "WinSCP: this is end-of-file:0"
< 2008-09-19 11:30:01.546 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:30:01.546 [Shell] Detecting variable containing return code of last command.
. 2008-09-19 11:30:01.546 [Shell] Trying "$status".
> 2008-09-19 11:30:01.546 [Shell] echo "$status" ; echo "WinSCP: this is end-of-file:0"
< 2008-09-19 11:30:01.578 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:30:01.578 [Shell] Trying "$?".
> 2008-09-19 11:30:01.578 [Shell] echo "$?" ; echo "WinSCP: this is end-of-file:0"
< 2008-09-19 11:30:01.703 [Shell] 0
< 2008-09-19 11:30:01.703 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:30:01.703 [Shell] Return code variable "$?" selected.
. 2008-09-19 11:30:01.703 [Shell] Changing directory to "/home/guppyworks".
> 2008-09-19 11:30:01.703 [Shell] cd "/home/guppyworks" ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:30:01.734 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:30:01.734 [Shell] Getting current directory name.
> 2008-09-19 11:30:01.734 [Shell] pwd ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:30:01.781 [Shell] /home/guppyworks
< 2008-09-19 11:30:01.781 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:30:01.781 [Shell] Startup conversation with host finished.
. 2008-09-19 11:30:01.781 Executing user defined command on command session.
> 2008-09-19 11:30:01.781 [Shell] sudo ./killservers.sh ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:30:01.937 [Shell] WinSCP: this is end-of-file:0
> 2008-09-19 11:30:01.937 [Shell] pwd ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:30:01.953 [Shell] /home/guppyworks
< 2008-09-19 11:30:01.953 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:30:01.953 Cached directory change via "/home/guppyworks" to "/home/guppyworks".
. 2008-09-19 11:30:01.953 Getting current directory name.
. 2008-09-19 11:30:01.953 Getting current directory name.
. 2008-09-19 11:30:01.953 Executing user defined command on command session.
> 2008-09-19 11:30:01.953 [Shell] sudo ./rebuildservers.sh ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:30:02.171 [Shell] rm -rf *.o ../lib/libPlatform.a 
< 2008-09-19 11:30:02.171 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformConsole.cpp
< 2008-09-19 11:30:06.500 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformMutex.cpp
< 2008-09-19 11:30:06.812 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformWindow.cpp
< 2008-09-19 11:30:09.312 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformCRC.cpp
< 2008-09-19 11:30:12.125 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformINI.cpp
< 2008-09-19 11:30:24.593 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformProcess.cpp
< 2008-09-19 11:30:28.968 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformThread.cpp
< 2008-09-19 11:30:29.328 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformTime.cpp
< 2008-09-19 11:30:29.406 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformTimeBlock.cpp
< 2008-09-19 11:30:29.656 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformUtils.cpp
. 2008-09-19 11:30:44.656 [Shell] Waiting for data timed out, asking user what to do.
. 2008-09-19 11:30:44.656 [Shell] Asking user:
. 2008-09-19 11:30:44.656 [Shell] Host has not answered for 15 seconds.
. 2008-09-19 11:30:44.656 [Shell] 
. 2008-09-19 11:30:44.656 [Shell] Wait for another 15 seconds? ()
. 2008-09-19 11:30:44.656 [Shell] Attempt to close connection due to fatal exception:
* 2008-09-19 11:30:44.656 [Shell] Terminated by user.
. 2008-09-19 11:30:44.656 [Shell] Closing connection.
. 2008-09-19 11:30:44.656 [Shell] Sending special code: 12
. 2008-09-19 11:30:44.656 [Shell] Sent EOF message
. 2008-09-19 11:30:44.656 Closing connection.
. 2008-09-19 11:30:44.656 Sending special code: 12
. 2008-09-19 11:30:44.656 Sent EOF message
. 2008-09-19 11:32:03.234 --------------------------------------------------------------------------
. 2008-09-19 11:32:03.234 WinSCP Version 4.1.6 (Build 412) (OS 5.1.2600 Service Pack 3)
. 2008-09-19 11:32:03.234 Login time: 19. september 2008 11:32:03
. 2008-09-19 11:32:03.234 --------------------------------------------------------------------------
. 2008-09-19 11:32:03.234 Session name: root@192.168.1.25
. 2008-09-19 11:32:03.234 Host name: 192.168.1.25 (Port: 22)
. 2008-09-19 11:32:03.234 User name: root (Password: Yes, Key file: No)
. 2008-09-19 11:32:03.234 Tunnel: No
. 2008-09-19 11:32:03.234 Transfer Protocol: SFTP (SCP)
. 2008-09-19 11:32:03.234 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2008-09-19 11:32:03.234 Proxy: none
. 2008-09-19 11:32:03.234 SSH protocol version: 2; Compression: No
. 2008-09-19 11:32:03.234 Bypass authentication: No
. 2008-09-19 11:32:03.234 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2008-09-19 11:32:03.234 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2008-09-19 11:32:03.234 SSH Bugs: -,-,-,-,-,-,-,-
. 2008-09-19 11:32:03.234 SFTP Bugs: -,-
. 2008-09-19 11:32:03.234 Return code variable: Autodetect; Lookup user groups: Yes
. 2008-09-19 11:32:03.234 Shell: default, EOL: 0
. 2008-09-19 11:32:03.234 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2008-09-19 11:32:03.234 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2008-09-19 11:32:03.234 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2008-09-19 11:32:03.234 Cache directory changes: Yes, Permanent: Yes
. 2008-09-19 11:32:03.234 DST mode: 1
. 2008-09-19 11:32:03.234 --------------------------------------------------------------------------
. 2008-09-19 11:32:03.234 Looking up host "192.168.1.25"
. 2008-09-19 11:32:03.234 Connecting to 192.168.1.25 port 22
. 2008-09-19 11:32:03.265 Server version: SSH-2.0-OpenSSH_4.7p1 Debian-8ubuntu1.2
. 2008-09-19 11:32:03.265 We claim version: SSH-2.0-WinSCP_release_4.1.6
. 2008-09-19 11:32:03.265 SSPI: acquired credentials for: Odin Jensen@PC005
. 2008-09-19 11:32:03.265 Cannot convert IP address to SPN: DNS is not trusted
. 2008-09-19 11:32:03.265 GSSKEX disabled: Handlingen er gennemfort.
 
. 2008-09-19 11:32:03.265 Using SSH protocol version 2
. 2008-09-19 11:32:03.265 Doing Diffie-Hellman group exchange
. 2008-09-19 11:32:03.312 Doing Diffie-Hellman key exchange with hash SHA-1
. 2008-09-19 11:32:03.625 Host key fingerprint is:
. 2008-09-19 11:32:03.625 ssh-rsa 2048 29:5b:d3:d2:e8:4a:20:a9:2f:b9:b7:f7:62:52:11:71
. 2008-09-19 11:32:03.625 Initialised AES-256 SDCTR client->server encryption
. 2008-09-19 11:32:03.625 Initialised HMAC-SHA1 client->server MAC algorithm
. 2008-09-19 11:32:03.625 Initialised AES-256 SDCTR server->client encryption
. 2008-09-19 11:32:03.625 Initialised HMAC-SHA1 server->client MAC algorithm
! 2008-09-19 11:32:03.625 Using username "root".
. 2008-09-19 11:32:03.656 Prompt (6, SSH password, , &Password: )
. 2008-09-19 11:32:03.656 Using stored password.
. 2008-09-19 11:32:03.656 Sent password
. 2008-09-19 11:32:03.656 Access granted
. 2008-09-19 11:32:03.656 Opened channel for session
. 2008-09-19 11:32:03.671 Started a shell/command
. 2008-09-19 11:32:03.687 --------------------------------------------------------------------------
. 2008-09-19 11:32:03.687 Using SFTP protocol.
. 2008-09-19 11:32:03.687 Doing startup conversation with host.
> 2008-09-19 11:32:03.687 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2008-09-19 11:32:03.687 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2008-09-19 11:32:03.687 SFTP version 3 negotiated.
. 2008-09-19 11:32:03.687 We believe the server has signed timestamps bug
. 2008-09-19 11:32:03.687 We will use UTF-8 strings for status messages only
. 2008-09-19 11:32:03.687 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2008-09-19 11:32:03.687 Getting current directory name.
. 2008-09-19 11:32:03.687 Getting real path for '.'
> 2008-09-19 11:32:03.687 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2008-09-19 11:32:03.687 Type: SSH_FXP_NAME, Size: 31, Number: 16
. 2008-09-19 11:32:03.687 Real path is '/root'
. 2008-09-19 11:32:03.687 Startup conversation with host finished.
. 2008-09-19 11:32:03.687 Cached directory change via "/home/guppyworks" to "/home/guppyworks".
. 2008-09-19 11:32:03.687 Getting current directory name.
. 2008-09-19 11:32:03.687 [Shell] Looking up host "192.168.1.25"
. 2008-09-19 11:32:03.687 [Shell] Connecting to 192.168.1.25 port 22
. 2008-09-19 11:32:03.718 [Shell] Server version: SSH-2.0-OpenSSH_4.7p1 Debian-8ubuntu1.2
. 2008-09-19 11:32:03.718 [Shell] We claim version: SSH-2.0-WinSCP_release_4.1.6
. 2008-09-19 11:32:03.718 [Shell] SSPI: acquired credentials for: Odin Jensen@PC005
. 2008-09-19 11:32:03.718 [Shell] Cannot convert IP address to SPN: DNS is not trusted
. 2008-09-19 11:32:03.718 [Shell] GSSKEX disabled: Handlingen er gennemfort.
 
. 2008-09-19 11:32:03.718 [Shell] Using SSH protocol version 2
. 2008-09-19 11:32:03.718 [Shell] Doing Diffie-Hellman group exchange
. 2008-09-19 11:32:03.734 [Shell] Doing Diffie-Hellman key exchange with hash SHA-1
. 2008-09-19 11:32:04.046 [Shell] Host key fingerprint is:
. 2008-09-19 11:32:04.046 [Shell] ssh-rsa 2048 29:5b:d3:d2:e8:4a:20:a9:2f:b9:b7:f7:62:52:11:71
. 2008-09-19 11:32:04.046 [Shell] Initialised AES-256 SDCTR client->server encryption
. 2008-09-19 11:32:04.046 [Shell] Initialised HMAC-SHA1 client->server MAC algorithm
. 2008-09-19 11:32:04.046 [Shell] Initialised AES-256 SDCTR server->client encryption
. 2008-09-19 11:32:04.046 [Shell] Initialised HMAC-SHA1 server->client MAC algorithm
! 2008-09-19 11:32:04.046 [Shell] Using username "root".
. 2008-09-19 11:32:04.062 [Shell] Prompt (6, SSH password, , &Password: )
. 2008-09-19 11:32:04.078 [Shell] Using stored password.
. 2008-09-19 11:32:04.078 [Shell] Sent password
. 2008-09-19 11:32:04.078 [Shell] Access granted
. 2008-09-19 11:32:04.078 [Shell] Opened channel for session
. 2008-09-19 11:32:04.093 [Shell] Started a shell/command
. 2008-09-19 11:32:04.093 [Shell] --------------------------------------------------------------------------
. 2008-09-19 11:32:04.093 [Shell] Using SCP protocol.
. 2008-09-19 11:32:04.093 [Shell] Doing startup conversation with host.
. 2008-09-19 11:32:04.093 [Shell] Skipping host startup message (if any).
> 2008-09-19 11:32:04.093 [Shell] echo "WinSCP: this is end-of-file:0"
! 2008-09-19 11:32:04.109 [Shell] stdin: is not a tty
< 2008-09-19 11:32:04.109 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:32:04.109 [Shell] Detecting variable containing return code of last command.
. 2008-09-19 11:32:04.109 [Shell] Trying "$status".
> 2008-09-19 11:32:04.109 [Shell] echo "$status" ; echo "WinSCP: this is end-of-file:0"
< 2008-09-19 11:32:04.140 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:32:04.140 [Shell] Trying "$?".
> 2008-09-19 11:32:04.140 [Shell] echo "$?" ; echo "WinSCP: this is end-of-file:0"
< 2008-09-19 11:32:04.171 [Shell] 0
< 2008-09-19 11:32:04.187 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:32:04.187 [Shell] Return code variable "$?" selected.
. 2008-09-19 11:32:04.187 [Shell] Changing directory to "/home/guppyworks".
> 2008-09-19 11:32:04.187 [Shell] cd "/home/guppyworks" ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:32:04.218 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:32:04.218 [Shell] Getting current directory name.
> 2008-09-19 11:32:04.218 [Shell] pwd ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:32:04.265 [Shell] /home/guppyworks
< 2008-09-19 11:32:04.265 [Shell] WinSCP: this is end-of-file:0
. 2008-09-19 11:32:04.265 [Shell] Startup conversation with host finished.
. 2008-09-19 11:32:04.265 Executing user defined command on command session.
> 2008-09-19 11:32:04.265 [Shell] sudo ./killservers.sh ; echo "WinSCP: this is end-of-file:$?"
! 2008-09-19 11:32:04.328 [Shell] GameServerLinux.exe: no process killed
! 2008-09-19 11:32:04.343 [Shell] LobbyServerLinux.exe: no process killed
< 2008-09-19 11:32:04.343 [Shell] WinSCP: this is end-of-file:1
* 2008-09-19 11:32:04.343 (ETerminal) Command 'sudo ./killservers.sh'
* 2008-09-19 11:32:04.343 failed with return code 1 and error message
* 2008-09-19 11:32:04.343 GameServerLinux.exe: no process killed
* 2008-09-19 11:32:04.343 LobbyServerLinux.exe: no process killed.
. 2008-09-19 11:32:04.343 Executing user defined command on command session.
> 2008-09-19 11:32:04.343 [Shell] sudo ./rebuildservers.sh ; echo "WinSCP: this is end-of-file:$?"
< 2008-09-19 11:32:04.406 [Shell] rm -rf *.o ../lib/libPlatform.a 
< 2008-09-19 11:32:04.437 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformConsole.cpp
< 2008-09-19 11:32:07.234 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformMutex.cpp
< 2008-09-19 11:32:07.468 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformWindow.cpp
< 2008-09-19 11:32:09.359 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformCRC.cpp
< 2008-09-19 11:32:11.812 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformINI.cpp
< 2008-09-19 11:32:21.968 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformProcess.cpp
< 2008-09-19 11:32:23.921 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformThread.cpp
< 2008-09-19 11:32:24.187 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformTime.cpp
< 2008-09-19 11:32:24.281 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformTimeBlock.cpp
< 2008-09-19 11:32:24.500 [Shell] gcc -c -fPIC -O3 -g -w -static -I../../Net/code  PlatformUtils.cpp
< 2008-09-19 11:32:34.937 [Shell] ar  rcs ../lib/libPlatform.a PlatformConsole.o PlatformMutex.o PlatformWindow.o PlatformCRC.o PlatformINI.o PlatformProcess.o PlatformThread.o PlatformTime.o PlatformTimeBlock.o PlatformUtils.o
< 2008-09-19 11:32:34.984 [Shell] rm -rf *.o ../lib/libStatLogDb.a 
< 2008-09-19 11:32:35.000 [Shell] g++ -c -fPIC -O3 -g -w -static  -I ../../GameSvrDb/sqlite-3.5.7 -I ../../PlatformLib/source -I ../../LogSystem/Source StatLogDb.cpp
< 2008-09-19 11:32:44.656 [Shell] ar  rcs ../lib/libStatLogDb.a StatLogDb.o 
< 2008-09-19 11:32:44.671 [Shell] rm -rf *.o ../lib/libLobbyNet.a 
< 2008-09-19 11:32:44.703 [Shell] gcc -c -fPIC -O3 -g -w -static  -I../../Net/code -I../../PlatformLib/source -I../../MessageSystem/source LobbyNet.cpp
< 2008-09-19 11:32:44.796 [Shell] gcc -c -fPIC -O3 -g -w -static  -I../../Net/code -I../../PlatformLib/source -I../../MessageSystem/source LobbyNetClient.cpp
< 2008-09-19 11:32:56.515 [Shell] gcc -c -fPIC -O3 -g -w -static  -I../../Net/code -I../../PlatformLib/source -I../../MessageSystem/source LobbyNetServer.cpp
< 2008-09-19 11:33:00.078 [Shell] ar  rcs ../lib/libLobbyNet.a LobbyNet.o LobbyNetClient.o LobbyNetServer.o 
< 2008-09-19 11:33:00.109 [Shell] rm -rf *.o ../lib/libLogSystem.a 
< 2008-09-19 11:33:00.140 [Shell] g++ -c -fPIC -O3 -g -w  -static -I../../PlatformLib/source -I../../Net/code -I../../MessageSystem/source LogSystem.cpp
< 2008-09-19 11:33:12.906 [Shell] g++ -c -fPIC -O3 -g -w  -static -I../../PlatformLib/source -I../../Net/code -I../../MessageSystem/source LogAllFileListener.cpp
< 2008-09-19 11:33:16.453 [Shell] g++ -c -fPIC -O3 -g -w  -static -I../../PlatformLib/source -I../../Net/code -I../../MessageSystem/source LogConsoleListener.cpp
< 2008-09-19 11:33:21.968 [Shell] g++ -c -fPIC -O3 -g -w  -static -I../../PlatformLib/source -I../../Net/code -I../../MessageSystem/source LogNetDispatchListener.cpp
< 2008-09-19 11:33:30.218 [Shell] g++ -c -fPIC -O3 -g -w  -static -I../../PlatformLib/source -I../../Net/code -I../../MessageSystem/source LogSeperateFileListener.cpp
< 2008-09-19 11:33:35.953 [Shell] ar  rcs ../lib/libLogSystem.a LogSystem.o LogAllFileListener.o LogConsoleListener.o LogNetDispatchListener.o LogSeperateFileListener.o
< 2008-09-19 11:33:35.984 [Shell] rm -rf *.o ../lib/libLobbyServerLib.a 
< 2008-09-19 11:33:36.015 [Shell] g++    -c -o GameDb.o GameDb.cpp
< 2008-09-19 11:33:36.093 [Shell] g++ -c -fPIC -O3 -g -w -static  -I../../Net/code -I../../GameSvrDb/code -I../../LobbyNet/source -I../../PlatformLib/source -I../../LogSystem/Source -I../../MessageSystem/source -I../../StatLogDb/source -I../../LobbyServerWebInterface/code GameServerDb.cpp
< 2008-09-19 11:33:37.781 [Shell] g++ -c -fPIC -O3 -g -w -static  -I../../Net/code -I../../GameSvrDb/code -I../../LobbyNet/source -I../../PlatformLib/source -I../../LogSystem/Source -I../../MessageSystem/source -I../../StatLogDb/source -I../../LobbyServerWebInterface/code LobbyAccount.cpp
< 2008-09-19 11:33:39.046 [Shell] g++ -c -fPIC -O3 -g -w -static  -I../../Net/code -I../../GameSvrDb/code -I../../LobbyNet/source -I../../PlatformLib/source -I../../LogSystem/Source -I../../MessageSystem/source -I../../StatLogDb/source -I../../LobbyServerWebInterface/code LobbyDbLayerAccount.cpp
< 2008-09-19 11:33:40.093 [Shell] g++ -c -fPIC -O3 -g -w -static  -I../../Net/code -I../../GameSvrDb/code -I../../LobbyNet/source -I../../PlatformLib/source -I../../LogSystem/Source -I../../MessageSystem/source -I../../StatLogDb/source -I../../LobbyServerWebInterface/code LobbyServer.cpp
. 2008-09-19 11:33:55.093 [Shell] Waiting for data timed out, asking user what to do.
. 2008-09-19 11:33:55.093 [Shell] Asking user:
. 2008-09-19 11:33:55.093 [Shell] Host has not answered for 15 seconds.
. 2008-09-19 11:33:55.093 [Shell] 
. 2008-09-19 11:33:55.093 [Shell] Wait for another 15 seconds? ()
. 2008-09-19 11:33:55.093 [Shell] Attempt to close connection due to fatal exception:
* 2008-09-19 11:33:55.093 [Shell] Terminated by user.
. 2008-09-19 11:33:55.093 [Shell] Closing connection.
. 2008-09-19 11:33:55.093 [Shell] Sending special code: 12
. 2008-09-19 11:33:55.093 [Shell] Sent EOF message
. 2008-09-19 11:33:55.093 Closing connection.
. 2008-09-19 11:33:55.093 Sending special code: 12
. 2008-09-19 11:33:55.093 Sent EOF message
Thanks in advance

Reply with quote

martin◆
Site Admin
martin avatar
Joined:
Posts:
41,442
Location:
Prague, Czechia

Godai wrote:

Here's one. We are using WinSCP to update our game server source + patch data and sometimes rebuild the servers via a script.
This script can be very slow and WinSCP times out after a random period of time. See the log for two consecutive runs :)
You can set longer timeout: 
open -timeout=60

Reply with quote

Advertisement

unknown
Guest

Domain trust issue

This is a domain trust issue
if your client is not in the same domain as the server, there will be similar logs as you show here

try trusting the other domain, or join client to server domain, or create DNS sub domains to be trusted by parent dns domains

Reply with quote

rizutov
Guest

Waiting for data timed out, asking user what to do.

Hi, I try to synch local folder with remote ftp folder:
There is an ftp folder where every day every 15 mins new files are put automatically. I have server with local folder which should contain the same files. I wrote a script which copies all this files from FTP to this local folder: 
C:\bat\ftp\WinScp\winscp.exe /console /script=ftp_connect.txt
ftp_connect.txt: 
option batch on
option confirm off
option transfer automatic
open user:pass@194.154.80.6:21 -passive -timeout=60
synchronize local D:\ftp /remote_folder/
close
exit
But it doesn't work when I launch my script. However when I try it through GUI winscp it works
This is logged when I connect using GUI: 
. 2011-02-01 09:13:20.580 WinSCP Version 4.2.9 (Build 938) (OS 5.2.3790 Service Pack 2)
. 2011-02-01 09:13:20.580 Login time: 1 Февраль 2011 г. 9:13:20
. 2011-02-01 09:13:20.580 --------------------------------------------------------------------------
. 2011-02-01 09:13:20.580 Session name: user@194.154.80.6
. 2011-02-01 09:13:20.580 Host name: 194.154.80.6 (Port: 21)
. 2011-02-01 09:13:20.580 User name: ALGLExport (Password: Yes, Key file: No)
. 2011-02-01 09:13:20.580 Tunnel: No
. 2011-02-01 09:13:20.580 Transfer Protocol: SFTP (SCP)
. 2011-02-01 09:13:20.580 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2011-02-01 09:13:20.580 Proxy: none
. 2011-02-01 09:13:20.580 SSH protocol version: 2; Compression: No
. 2011-02-01 09:13:20.580 Bypass authentication: No
. 2011-02-01 09:13:20.580 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2011-02-01 09:13:20.580 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2011-02-01 09:13:20.580 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2011-02-01 09:13:20.580 SFTP Bugs: -,-
. 2011-02-01 09:13:20.580 Return code variable: Autodetect; Lookup user groups: Yes
. 2011-02-01 09:13:20.580 Shell: default
. 2011-02-01 09:13:20.580 EOL: 0, UTF: 2
. 2011-02-01 09:13:20.580 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2011-02-01 09:13:20.580 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2011-02-01 09:13:20.580 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2011-02-01 09:13:20.580 Cache directory changes: Yes, Permanent: Yes
. 2011-02-01 09:13:20.580 DST mode: 1
. 2011-02-01 09:13:20.580 --------------------------------------------------------------------------
And this was logged while trying batch file: 
. 2011-02-01 09:13:20.580 Looking up host "194.154.80.6"
. 2011-02-01 09:13:20.580 Connecting to 194.154.80.6 port 21
. 2011-02-01 09:13:35.595 Waiting for data timed out, asking user what to do.
. 2011-02-01 09:13:35.595 Asking user:
. 2011-02-01 09:13:35.595 Host is not communicating for 15 seconds.
. 2011-02-01 09:13:35.595 
. 2011-02-01 09:13:35.595 Wait for another 15 seconds? ()
. 2011-02-01 09:13:35.595 Attempt to close connection due to fatal exception:
* 2011-02-01 09:13:35.595 Terminated by user.
. 2011-02-01 09:13:35.595 Closing connection.
. 2011-02-01 09:13:35.595 Sending special code: 12
By the way, I tried to change password and username entries. And this didn't change anything. This means the problem is in connection i.e. in "open" string. But still I can not figure what it says by 
Waiting for data timed out, asking user what to do.
. 2011-02-01 09:13:35.595 Asking user:
I would appreciate any help. Thanks in advance.

Reply with quote

Advertisement

Guest

Re: Waiting for data timed out, asking user what to do.

rizutov wrote:

Hi, I try to synch local folder with remote ftp folder:
There is an ftp folder where every day every 15 mins new files are put automatically.
...
Now it works. Sorry

Reply with quote

martin◆
Site Admin
martin avatar

Re: Waiting for data timed out, asking user what to do.

@VinothSS: You cannot really expect an anonymous user to respond you after 10 years. Notwithstanding that the issue is unlikely the same.

If you want our help, please start a new topic and post detailed description of the problem, and session log.

Reply with quote

Advertisement

You can post new topics in this forum

Documentation

Support

Associations

Follow Us