Post a reply

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

martin

Re: WINSCP Returning Not Putting files to the server

I do not see any problem with uploading the files in the log.

All I can see that you are trying to execute some complex shell commands, what fails.
nolly190

WINSCP Returning Not Putting files to the server

. 2020-04-29 14:38:08.893 --------------------------------------------------------------------------
. 2020-04-29 14:38:08.893 WinSCP Version 5.15.5 (Build 9925) (OS 6.0.6001 Service Pack 1 - Windows Server (R) 2008 Standard)
. 2020-04-29 14:38:08.893 Configuration: nul
. 2020-04-29 14:38:08.893 Log level: Normal
. 2020-04-29 14:38:08.893 Local account: ICBGM\PART-006-ADT1$
. 2020-04-29 14:38:08.893 Working directory: e:\MainACPACH
. 2020-04-29 14:38:08.893 Process ID: 4920
. 2020-04-29 14:38:08.893 Command-line: "e:\MainACPACH\winscp.exe" /xmllog="C:\Windows\TEMP\wscp0EC4.031CAFD2.tmp" /xmlgroups /xmllogrequired /nointeractiveinput /dotnet=5.15.5 /ini=nul /log="D:\ADHIN\Logs\WinScpLog 2020-29-4--15-38-08.txt" /console /consoleinstance=_3780_30223245_445
. 2020-04-29 14:38:08.893 Time zone: Current: GMT+0 (Greenwich Standard Time), No DST
. 2020-04-29 14:38:08.893 Login time: 29 April 2020 14:38:08
. 2020-04-29 14:38:08.893 --------------------------------------------------------------------------
. 2020-04-29 14:38:08.893 Script: Retrospectively logging previous script records:
> 2020-04-29 14:38:08.893 Script: option batch on
< 2020-04-29 14:38:08.893 Script: batch on
< 2020-04-29 14:38:08.893 Script: reconnecttime 120
> 2020-04-29 14:38:08.893 Script: option confirm off
< 2020-04-29 14:38:08.893 Script: confirm off
> 2020-04-29 14:38:08.893 Script: option reconnecttime 120
< 2020-04-29 14:38:08.893 Script: reconnecttime 120
> 2020-04-29 14:38:08.893 Script: open sftp://swiftapp:***@172.16.25.104:22 -hostkey="ssh-rsa 2048 " -timeout=15
. 2020-04-29 14:38:08.893 --------------------------------------------------------------------------
. 2020-04-29 14:38:08.893 Session name: swiftapp@172.16.25.104 (Ad-Hoc site)
. 2020-04-29 14:38:08.893 Host name: 172.16.25.104 (Port: 22)
. 2020-04-29 14:38:08.893 User name: swiftapp (Password: Yes, Key file: No, Passphrase: No)
. 2020-04-29 14:38:08.893 Tunnel: No
. 2020-04-29 14:38:08.893 Transfer Protocol: SFTP
. 2020-04-29 14:38:08.893 Ping type: Off, Ping interval: 30 sec; Timeout: 15 sec
. 2020-04-29 14:38:08.893 Disable Nagle: No
. 2020-04-29 14:38:08.893 Proxy: None
. 2020-04-29 14:38:08.893 Send buffer: 262144
. 2020-04-29 14:38:08.893 SSH protocol version: 2; Compression: No
. 2020-04-29 14:38:08.893 Bypass authentication: No
. 2020-04-29 14:38:08.893 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: Yes
. 2020-04-29 14:38:08.893 GSSAPI: Forwarding: No; Libs: gssapi32,sspi,custom; Custom:
. 2020-04-29 14:38:08.893 Ciphers: aes,chacha20,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2020-04-29 14:38:08.893 KEX: ecdh,dh-gex-sha1,dh-group14-sha1,rsa,WARN,dh-group1-sha1
. 2020-04-29 14:38:08.893 SSH Bugs: Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto
. 2020-04-29 14:38:08.893 Simple channel: Yes
. 2020-04-29 14:38:08.893 Return code variable: Autodetect; Lookup user groups: Auto
. 2020-04-29 14:38:08.893 Shell: default
. 2020-04-29 14:38:08.893 EOL: LF, UTF: Auto
. 2020-04-29 14:38:08.893 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes; Follow directory symlinks: No
. 2020-04-29 14:38:08.893 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No; Exit code 1 is error: No
. 2020-04-29 14:38:08.893 SFTP Bugs: Auto,Auto
. 2020-04-29 14:38:08.893 SFTP Server: default
. 2020-04-29 14:38:08.893 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2020-04-29 14:38:08.893 Cache directory changes: Yes, Permanent: Yes
. 2020-04-29 14:38:08.893 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2020-04-29 14:38:08.893 DST mode: Unix
. 2020-04-29 14:38:08.893 --------------------------------------------------------------------------
. 2020-04-29 14:38:08.893 Looking up host "172.16.25.104" for SSH connection
. 2020-04-29 14:38:08.893 Connecting to 172.16.25.104 port 22
. 2020-04-29 14:38:09.080 We claim version: SSH-2.0-WinSCP_release_5.15.5
. 2020-04-29 14:38:09.283 Server version: SSH-2.0-OpenSSH_6.0
. 2020-04-29 14:38:09.283 We believe remote version has SSH-2 channel request bug
. 2020-04-29 14:38:09.283 Using SSH protocol version 2
. 2020-04-29 14:38:09.283 Have a known host key of type rsa2
. 2020-04-29 14:38:09.470 Doing Diffie-Hellman group exchange
. 2020-04-29 14:38:09.689 Doing Diffie-Hellman key exchange with hash SHA-256
. 2020-04-29 14:38:10.874 Server also has ssh-dss host key, but we don't know it
. 2020-04-29 14:38:10.874 Host key fingerprint is:
. 2020-04-29 14:38:10.874 ssh-rsa 2048 ***************************************************
. 2020-04-29 14:38:10.874 Host key matches configured key
. 2020-04-29 14:38:10.874 Initialised AES-256 SDCTR client->server encryption
. 2020-04-29 14:38:10.874 Initialised HMAC-SHA-256 client->server MAC algorithm
. 2020-04-29 14:38:10.874 Initialised AES-256 SDCTR server->client encryption
. 2020-04-29 14:38:10.874 Initialised HMAC-SHA-256 server->client MAC algorithm
! 2020-04-29 14:38:11.327 Using username "swiftapp".
. 2020-04-29 14:38:11.514 Server offered these authentication methods: publickey,password,keyboard-interactive
. 2020-04-29 14:38:11.514 Attempting keyboard-interactive authentication
. 2020-04-29 14:38:11.717 Server refused keyboard-interactive authentication
. 2020-04-29 14:38:11.717 Server offered these authentication methods: publickey,password,keyboard-interactive
. 2020-04-29 14:38:11.717 Prompt (password, "SSH password", <no instructions>, "&Password: ")
. 2020-04-29 14:38:11.717 Using stored password.
. 2020-04-29 14:38:11.717 Sent password
. 2020-04-29 14:38:11.904 Access granted
. 2020-04-29 14:38:11.904 Opening session as main channel
. 2020-04-29 14:38:12.091 Opened main channel
. 2020-04-29 14:38:12.528 Started a shell/command
. 2020-04-29 14:38:12.528 --------------------------------------------------------------------------
. 2020-04-29 14:38:12.528 Using SFTP protocol.
. 2020-04-29 14:38:12.528 Doing startup conversation with host.
> 2020-04-29 14:38:12.528 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2020-04-29 14:38:12.715 Type: SSH_FXP_VERSION, Size: 124, Number: -1
. 2020-04-29 14:38:12.715 SFTP version 3 negotiated.
. 2020-04-29 14:38:12.715 Unknown server extension posix-rename@openssh.com="1"
. 2020-04-29 14:38:12.715 Supports statvfs@openssh.com extension version "2"
. 2020-04-29 14:38:12.715 Unknown server extension fstatvfs@openssh.com="2"
. 2020-04-29 14:38:12.715 Supports hardlink@openssh.com extension version "1"
. 2020-04-29 14:38:12.715 We believe the server has signed timestamps bug
. 2020-04-29 14:38:12.715 We will use UTF-8 strings until server sends an invalid UTF-8 string as with SFTP version 3 and older UTF-8 strings are not mandatory
. 2020-04-29 14:38:12.715 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2020-04-29 14:38:12.715 Getting current directory name.
. 2020-04-29 14:38:12.715 Getting real path for '.'
> 2020-04-29 14:38:12.715 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2020-04-29 14:38:12.918 Type: SSH_FXP_NAME, Size: 49, Number: 16
. 2020-04-29 14:38:12.918 Real path is '/home/swiftapp'
. 2020-04-29 14:38:12.918 Startup conversation with host finished.
< 2020-04-29 14:38:12.918 Script: Active session: [1] swiftapp@172.16.25.104
> 2020-04-29 14:38:12.980 Script: pwd
< 2020-04-29 14:38:12.980 Script: /home/swiftapp
> 2020-04-29 14:38:13.043 Script: get -nopermissions -preservetime -transfer="binary" -- "/finreports/FBNANGLO/06/ACH/gmbfileren.sh" "D:\ADHIN\BatchFiles\gmbfileren.sh"
. 2020-04-29 14:38:13.043 Listing file "/finreports/FBNANGLO/06/ACH/gmbfileren.sh".
> 2020-04-29 14:38:13.043 Type: SSH_FXP_LSTAT, Size: 50, Number: 263
< 2020-04-29 14:38:13.230 Type: SSH_FXP_ATTRS, Size: 37, Number: 263
. 2020-04-29 14:38:13.230 gmbfileren.sh;-;192;2020-04-28T16:03:16.000Z;3;"" [1050];"" [15];rwxrwxr-x;0
. 2020-04-29 14:38:13.230 Copying 1 files/directories to local directory "D:\ADHIN\BatchFiles\" - total size: 192
. 2020-04-29 14:38:13.230 PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: gmbfileren.sh
. 2020-04-29 14:38:13.230 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2020-04-29 14:38:13.230 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2020-04-29 14:38:13.230 File: '/finreports/FBNANGLO/06/ACH/gmbfileren.sh' [2020-04-28T16:03:16.000Z] [192]
. 2020-04-29 14:38:13.230 Copying "/finreports/FBNANGLO/06/ACH/gmbfileren.sh" to local directory started.
. 2020-04-29 14:38:13.230 Binary transfer mode selected.
. 2020-04-29 14:38:13.230 Opening remote file.
> 2020-04-29 14:38:13.230 Type: SSH_FXP_OPEN, Size: 58, Number: 515
< 2020-04-29 14:38:13.417 Type: SSH_FXP_HANDLE, Size: 13, Number: 515
> 2020-04-29 14:38:13.417 Type: SSH_FXP_FSTAT, Size: 13, Number: 776
< 2020-04-29 14:38:13.604 Type: SSH_FXP_ATTRS, Size: 37, Number: 776
> 2020-04-29 14:38:13.604 Type: SSH_FXP_READ, Size: 25, Number: 1029
< 2020-04-29 14:38:13.979 Status code: 1
. 2020-04-29 14:38:13.979 3 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2020-04-29 14:38:13.979 Type: SSH_FXP_CLOSE, Size: 13, Number: 1540
. 2020-04-29 14:38:13.979 Preserving timestamp [2020-04-28T16:03:16.000Z]
. 2020-04-29 14:38:13.979 Transfer done: '/finreports/FBNANGLO/06/ACH/gmbfileren.sh' => 'D:\ADHIN\BatchFiles\gmbfileren.sh' [192]
. 2020-04-29 14:38:13.979 Copying finished: Transferred: 192, Elapsed: 0:00:00, CPS: 0/s
> 2020-04-29 14:38:14.634 Script: call #----Start
> 2020-04-29 14:38:14.634 cdate="`date +%d-%m-%Y`"
> 2020-04-29 14:38:14.634 echo "The date is .....$cdate " >> test-ach.log
> 2020-04-29 14:38:14.634
> 2020-04-29 14:38:14.634 for x in `ls -t1 *.RCP` ; do
> 2020-04-29 14:38:14.634
> 2020-04-29 14:38:14.634 echo " File name is: $x " >> test-ach.log
> 2020-04-29 14:38:14.634 mv $x $x"."$cdate
> 2020-04-29 14:38:14.634
> 2020-04-29 14:38:14.634 done
> 2020-04-29 14:38:14.634 #--exit
< 2020-04-29 14:38:14.634 Script: Searching for host...
. 2020-04-29 14:38:14.634 [Shell] Looking up host "172.16.25.104" for SSH connection
. 2020-04-29 14:38:14.634 [Shell] Connecting to 172.16.25.104 port 22
. 2020-04-29 14:38:14.821 [Shell] We claim version: SSH-2.0-WinSCP_release_5.15.5
< 2020-04-29 14:38:14.821 Script: Connecting to host...
. 2020-04-29 14:38:15.024 [Shell] Server version: SSH-2.0-OpenSSH_6.0
. 2020-04-29 14:38:15.024 [Shell] We believe remote version has SSH-2 channel request bug
. 2020-04-29 14:38:15.024 [Shell] Using SSH protocol version 2
. 2020-04-29 14:38:15.024 [Shell] Have a known host key of type rsa2
. 2020-04-29 14:38:15.211 [Shell] Doing Diffie-Hellman group exchange
. 2020-04-29 14:38:15.414 [Shell] Doing Diffie-Hellman key exchange with hash SHA-256
. 2020-04-29 14:38:16.724 [Shell] Server also has ssh-dss host key, but we don't know it
. 2020-04-29 14:38:16.724 [Shell] Host key fingerprint is:
. 2020-04-29 14:38:16.724 [Shell] ssh-rsa 2048 *****************************************************
< 2020-04-29 14:38:16.724 Script: Authenticating...
. 2020-04-29 14:38:16.724 [Shell] Host key matches configured key
. 2020-04-29 14:38:16.724 [Shell] Initialised AES-256 SDCTR client->server encryption
. 2020-04-29 14:38:16.724 [Shell] Initialised HMAC-SHA-256 client->server MAC algorithm
. 2020-04-29 14:38:16.724 [Shell] Initialised AES-256 SDCTR server->client encryption
. 2020-04-29 14:38:16.724 [Shell] Initialised HMAC-SHA-256 server->client MAC algorithm
! 2020-04-29 14:38:17.270 [Shell] Using username "swiftapp".
< 2020-04-29 14:38:17.270 Script: Using username "swiftapp".
. 2020-04-29 14:38:17.458 [Shell] Server offered these authentication methods: publickey,password,keyboard-interactive
. 2020-04-29 14:38:17.458 [Shell] Attempting keyboard-interactive authentication
. 2020-04-29 14:38:17.645 [Shell] Server refused keyboard-interactive authentication
. 2020-04-29 14:38:17.645 [Shell] Server offered these authentication methods: publickey,password,keyboard-interactive
. 2020-04-29 14:38:17.645 [Shell] Prompt (password, "SSH password", <no instructions>, "&Password: ")
. 2020-04-29 14:38:17.645 [Shell] Using stored password.
< 2020-04-29 14:38:17.645 Script: Authenticating with pre-entered password.
. 2020-04-29 14:38:17.645 [Shell] Sent password
. 2020-04-29 14:38:17.848 [Shell] Access granted
. 2020-04-29 14:38:17.848 [Shell] Opening session as main channel
. 2020-04-29 14:38:18.035 [Shell] Opened main channel
. 2020-04-29 14:38:18.472 [Shell] Started a shell/command
< 2020-04-29 14:38:18.472 Script: Authenticated.
. 2020-04-29 14:38:18.472 [Shell] --------------------------------------------------------------------------
. 2020-04-29 14:38:18.472 [Shell] Using SCP protocol.
. 2020-04-29 14:38:18.472 [Shell] Doing startup conversation with host.
< 2020-04-29 14:38:18.472 Script: Starting the session...
. 2020-04-29 14:38:18.472 [Shell] Skipping host startup message (if any).
> 2020-04-29 14:38:18.472 [Shell] echo "WinSCP: this is end-of-file:0"
! 2020-04-29 14:38:18.472 [Shell] .profile[6]: /etc/b2k/FBNANGLO/FINCORE/02/com/commonenv.com: not found.
< 2020-04-29 14:38:18.659 [Shell] WinSCP: this is end-of-file:0
. 2020-04-29 14:38:18.659 [Shell] Detecting variable containing return code of last command.
. 2020-04-29 14:38:18.659 [Shell] Trying "$status".
> 2020-04-29 14:38:18.659 [Shell] echo "$status" ; echo "WinSCP: this is end-of-file:0"
< 2020-04-29 14:38:18.846 [Shell] WinSCP: this is end-of-file:0
. 2020-04-29 14:38:18.846 [Shell] The response is not numerical exit code
. 2020-04-29 14:38:18.846 [Shell] Trying "$?".
> 2020-04-29 14:38:18.846 [Shell] echo "$?" ; echo "WinSCP: this is end-of-file:0"
< 2020-04-29 14:38:19.033 [Shell] 0
< 2020-04-29 14:38:19.033 [Shell] WinSCP: this is end-of-file:0
. 2020-04-29 14:38:19.033 [Shell] Return code variable "$?" selected.
> 2020-04-29 14:38:19.033 [Shell] printenv LANG ; echo "WinSCP: this is end-of-file:$?"
< 2020-04-29 14:38:19.220 [Shell] en_US
< 2020-04-29 14:38:19.220 [Shell] WinSCP: this is end-of-file:0
. 2020-04-29 14:38:19.220 [Shell] We will not use UTF-8
. 2020-04-29 14:38:19.220 [Shell] Changing directory to "/home/swiftapp".
> 2020-04-29 14:38:19.220 [Shell] cd "/home/swiftapp" ; echo "WinSCP: this is end-of-file:$?"
< 2020-04-29 14:38:19.408 [Shell] WinSCP: this is end-of-file:0
. 2020-04-29 14:38:19.408 [Shell] Getting current directory name.
> 2020-04-29 14:38:19.408 [Shell] pwd ; echo "WinSCP: this is end-of-file:$?"
< 2020-04-29 14:38:19.595 [Shell] /home/swiftapp
< 2020-04-29 14:38:19.595 [Shell] WinSCP: this is end-of-file:0
. 2020-04-29 14:38:19.595 [Shell] Startup conversation with host finished.
< 2020-04-29 14:38:19.595 Script: Session started.
. 2020-04-29 14:38:19.595 Executing user defined command on command session.
> 2020-04-29 14:38:19.595 [Shell] #----Start
> 2020-04-29 14:38:19.595 [Shell] cdate="`date +%d-%m-%Y`"
> 2020-04-29 14:38:19.595 [Shell] echo "The date is .....$cdate " >> test-ach.log
> 2020-04-29 14:38:19.595 [Shell]
> 2020-04-29 14:38:19.595 [Shell] for x in `ls -t1 *.RCP` ; do
> 2020-04-29 14:38:19.595 [Shell]
> 2020-04-29 14:38:19.595 [Shell] echo " File name is: $x " >> test-ach.log
> 2020-04-29 14:38:19.595 [Shell] mv $x $x"."$cdate
> 2020-04-29 14:38:19.595 [Shell]
> 2020-04-29 14:38:19.595 [Shell] done
> 2020-04-29 14:38:19.595 [Shell] #--exit ; echo "WinSCP: this is end-of-file:$?"
< 2020-04-29 14:38:19.813 Script: ls: 0653-341 The file *.RCP does not exist.
! 2020-04-29 14:38:19.813 [Shell] ls: 0653-341 The file *.RCP does not exist.
. 2020-04-29 14:38:36.193 [Shell] Waiting for data timed out, asking user what to do.
. 2020-04-29 14:38:36.193 [Shell] Asking user:
. 2020-04-29 14:38:36.193 [Shell] **Host is not communicating for 15 seconds.
. 2020-04-29 14:38:36.193 [Shell]
. 2020-04-29 14:38:36.193 [Shell] Wait for another 15 seconds?** ()
< 2020-04-29 14:38:36.193 Script: Host is not communicating for more than 15 seconds.
< 2020-04-29 14:38:36.193 Still waiting...
< 2020-04-29 14:38:36.193 Note: If the problem repeats, try turning off 'Optimize connection buffer size'.
< 2020-04-29 14:38:36.193 Warning: Aborting this operation will close connection!