I have the same problem in my company, sometimes the winscp.com and winscp.exe still running in taskmanager and I have to kill the process to continue the process.
Those JOB are schedule to start at the same time.
I configured to write log to try to identify the problem, but in those logs, the winscp have been done with success.
If you have any suggestion to fix the problem, I thank you.
Henrique Brandi (hbbrandi@gmail.com)
LOG JOB 8.25.A
. 2017-10-05 20:00:07.843 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.843 WinSCP Version 5.7 (Build 5125) (OS 6.1.7601 Service Pack 1 - Windows Server 2008 R2 Standard)
. 2017-10-05 20:00:07.858 Configuration: C:\MONPROC\WinSCP\WinSCP.ini
. 2017-10-05 20:00:07.858 Log level: Normal
. 2017-10-05 20:00:07.858 Local account: BR\BRSVPMTZLAB015$
. 2017-10-05 20:00:07.858 Working directory: C:\MONPROC\Admin
. 2017-10-05 20:00:07.858 Process ID: 6488
. 2017-10-05 20:00:07.858 Command-line: "C:\MONPROC\WinSCP\WinSCP.exe" /console=570 /consoleinstance=_5764_305 "/newinstance" "/log=8.25.A105587.log" "/script=C:\Windows\Temp\8.25.A105587.in"
. 2017-10-05 20:00:07.858 Time zone: Current: GMT-3, Standard: GMT-3 (E. South America Standard Time), DST: GMT-2 (E. South America Daylight Time), DST Start: 14/10/2017, DST End: 18/02/2017
. 2017-10-05 20:00:07.858 Login time: quinta-feira, 5 de outubro de 2017 20:00:07
. 2017-10-05 20:00:07.858 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.905 Script: Retrospectively logging previous script records:
> 2017-10-05 20:00:07.905 Script: open sftp://aiservice:***@brcmaiapp003 -certificate="ssh-rsa 2048 01:65:41:ea:7d:78:92:a7:2d:f5:ed:b9:8f:b6:4c:b3"
. 2017-10-05 20:00:07.905 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.905 Session name: aiservice@brcmaiapp003 (Ad-Hoc site)
. 2017-10-05 20:00:07.905 Host name: brcmaiapp003 (Port: 22)
. 2017-10-05 20:00:07.905 User name: aiservice (Password: Yes, Key file: No)
. 2017-10-05 20:00:07.905 Tunnel: No
. 2017-10-05 20:00:07.905 Transfer Protocol: SFTP
. 2017-10-05 20:00:07.905 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2017-10-05 20:00:07.905 Disable Nagle: No
. 2017-10-05 20:00:07.905 Proxy: none
. 2017-10-05 20:00:07.905 Send buffer: 262144
. 2017-10-05 20:00:07.905 SSH protocol version: 2; Compression: No
. 2017-10-05 20:00:07.905 Bypass authentication: No
. 2017-10-05 20:00:07.905 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2017-10-05 20:00:07.905 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2017-10-05 20:00:07.905 KEX: dh-gex-sha1,dh-group14-sha1,dh-group1-sha1,rsa,WARN
. 2017-10-05 20:00:07.905 SSH Bugs: A,A,A,A,A,A,A,A,A,A,A
. 2017-10-05 20:00:07.905 Simple channel: Yes
. 2017-10-05 20:00:07.905 Return code variable: Autodetect; Lookup user groups: A
. 2017-10-05 20:00:07.905 Shell: default
. 2017-10-05 20:00:07.905 EOL: 0, UTF: 2
. 2017-10-05 20:00:07.905 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2017-10-05 20:00:07.905 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2017-10-05 20:00:07.905 SFTP Bugs: A,A
. 2017-10-05 20:00:07.905 SFTP Server: default
. 2017-10-05 20:00:07.905 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2017-10-05 20:00:07.905 Cache directory changes: Yes, Permanent: Yes
. 2017-10-05 20:00:07.905 DST mode: 1
. 2017-10-05 20:00:07.905 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.905 Looking up host "brcmaiapp003"
. 2017-10-05 20:00:07.952 Connecting to 100.68.5.9 port 22
. 2017-10-05 20:00:07.999 Server version: SSH-2.0-OpenSSH_6.6.1
. 2017-10-05 20:00:07.999 Using SSH protocol version 2
. 2017-10-05 20:00:07.999 We claim version: SSH-2.0-WinSCP_release_5.7
. 2017-10-05 20:00:08.014 Doing Diffie-Hellman group exchange
. 2017-10-05 20:00:08.045 Doing Diffie-Hellman key exchange with hash SHA-256
. 2017-10-05 20:00:09.169 Verifying host key rsa2 0x10001,0xa01997bff0ab3c5e 60c43527bccc4710 921ade9c66cb180b 7a94b98eb1fe5be3 a81536a25ac476a1 3f0f824fc1de9810 34df9b6fbd3154a1 27031659901a1b88 7c8d463cdfcea7c4 24b899df5e20006e af81c5405cb93cbe 48c7b956a6a3a29e f04f22bd9b7c5356 8696e2ecf17a4399 bd0c63063350591a 7aeb376cfdc5f2e0 6101e3132c01dfd1 4b6b8f4745a822b7 2b058cfc1b54031f 6ff7053f292358cb d91b4c038fb5c067 f66e37da0f94bf84 b0b24d52c92e8cbf 71f4cde1f59c8b8c 142820f9ad44d4d2 6190dd54ea3ee6b0 5e66768eb71d7579 c57bfb5a13b88ce2 b1705918afed9165 de7544bd1d6d51b4 4fb7240b4dc1e1f6 005092cb64b6711b with fingerprint ssh-rsa 2048 01:65:41:ea:7d:78:92:a7:2d:f5:ed:b9:8f:b6:4c:b3
. 2017-10-05 20:00:09.184 Host key matches configured key
. 2017-10-05 20:00:09.184 Host key fingerprint is:
. 2017-10-05 20:00:09.184 ssh-rsa 2048 01:65:41:ea:7d:78:92:a7:2d:f5:ed:b9:8f:b6:4c:b3
. 2017-10-05 20:00:09.184 Initialised AES-256 SDCTR client->server encryption
. 2017-10-05 20:00:09.184 Initialised HMAC-SHA-256 client->server MAC algorithm
. 2017-10-05 20:00:09.184 Initialised AES-256 SDCTR server->client encryption
. 2017-10-05 20:00:09.184 Initialised HMAC-SHA-256 server->client MAC algorithm
! 2017-10-05 20:00:09.231 Using username "aiservice".
. 2017-10-05 20:00:09.512 Prompt (password, "SSH password", <no instructions>, "&Password: ")
. 2017-10-05 20:00:09.512 Using stored password.
. 2017-10-05 20:00:09.512 Sent password
. 2017-10-05 20:00:09.527 Access granted
. 2017-10-05 20:00:09.527 Opening session as main channel
. 2017-10-05 20:00:09.559 Opened main channel
. 2017-10-05 20:00:09.605 Started a shell/command
. 2017-10-05 20:00:09.605 --------------------------------------------------------------------------
. 2017-10-05 20:00:09.605 Using SFTP protocol.
. 2017-10-05 20:00:09.621 Doing startup conversation with host.
> 2017-10-05 20:00:09.621 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2017-10-05 20:00:09.621 Type: SSH_FXP_VERSION, Size: 150, Number: -1
. 2017-10-05 20:00:09.621 SFTP version 3 negotiated.
. 2017-10-05 20:00:09.621 Unknown server extension posix-rename@openssh.com="1"
. 2017-10-05 20:00:09.621 Supports statvfs@openssh.com extension version "2"
. 2017-10-05 20:00:09.621 Unknown server extension fstatvfs@openssh.com="2"
. 2017-10-05 20:00:09.621 Supports hardlink@openssh.com extension version "1"
. 2017-10-05 20:00:09.621 Unknown server extension fsync@openssh.com="1"
. 2017-10-05 20:00:09.621 We believe the server has signed timestamps bug
. 2017-10-05 20:00:09.621 We will use UTF-8 strings until server sends an invalid UTF-8 string as with SFTP version 3 and older UTF-8 string are not mandatory
. 2017-10-05 20:00:09.621 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2017-10-05 20:00:09.621 Getting current directory name.
. 2017-10-05 20:00:09.621 Getting real path for '.'
> 2017-10-05 20:00:09.621 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2017-10-05 20:00:09.621 Type: SSH_FXP_NAME, Size: 51, Number: 16
. 2017-10-05 20:00:09.637 Real path is '/home/aiservice'
. 2017-10-05 20:00:09.637 Startup conversation with host finished.
< 2017-10-05 20:00:09.637 Script: Active session: [1] aiservice@brcmaiapp003
> 2017-10-05 20:00:09.637 Script: cd /home/cinstall/interf/out_VENDORS_01_CPFCWEB
. 2017-10-05 20:00:09.637 Cached directory change via "/home/cinstall/interf/out_VENDORS_01_CPFCWEB" to "/home/cinstall/interf/out_VENDORS_01_CPFCWEB".
. 2017-10-05 20:00:09.637 Getting current directory name.
< 2017-10-05 20:00:09.637 Script: /home/cinstall/interf/out_VENDORS_01_CPFCWEB
> 2017-10-05 20:00:09.637 Script: ls
. 2017-10-05 20:00:09.637 Listing directory "/home/cinstall/interf/out_VENDORS_01_CPFCWEB".
> 2017-10-05 20:00:09.637 Type: SSH_FXP_OPENDIR, Size: 53, Number: 267
< 2017-10-05 20:00:09.637 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
> 2017-10-05 20:00:09.637 Type: SSH_FXP_READDIR, Size: 13, Number: 524
< 2017-10-05 20:00:09.652 Type: SSH_FXP_NAME, Size: 325, Number: 524
> 2017-10-05 20:00:09.652 Type: SSH_FXP_READDIR, Size: 13, Number: 780
< 2017-10-05 20:00:09.652 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2017-10-05 20:00:09.652 Status code: 1
> 2017-10-05 20:00:09.652 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
. 2017-10-05 20:00:09.652 processed;d;0;2017-10-05T20:44:09.000Z;"aiservice" [15001];"aiservice" [15001];rwxrwxrwx;1
. 2017-10-05 20:00:09.652 ..;d;0;2017-10-05T23:00:44.000Z;"aiservice" [15001];"root" [0];rwxrwxrwx;1
. 2017-10-05 20:00:09.652 .;d;0;2017-10-05T20:44:09.000Z;"aiservice" [15001];"root" [0];rwxrwxrwx;3
< 2017-10-05 20:00:09.652 Script: drwxrwxrwx 3 aiservice aiservice 24576 Oct 5 17:44:09 2017 processed
< 2017-10-05 20:00:09.652 Script: drwxrwxrwx 35 aiservice root 4096 Oct 5 20:00:44 2017 ..
< 2017-10-05 20:00:09.652 Script: drwxrwxrwx 3 aiservice root 4096 Oct 5 17:44:09 2017 .
> 2017-10-05 20:00:09.652 Script: close
. 2017-10-05 20:00:09.652 Closing connection.
. 2017-10-05 20:00:09.652 Sending special code: 12
. 2017-10-05 20:00:09.652 Sent EOF message
LOG JOB 8.25.B
. 2017-10-05 20:00:07.843 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.843 WinSCP Version 5.7 (Build 5125) (OS 6.1.7601 Service Pack 1 - Windows Server 2008 R2 Standard)
. 2017-10-05 20:00:07.858 Configuration: C:\MONPROC\WinSCP\WinSCP.ini
. 2017-10-05 20:00:07.858 Log level: Normal
. 2017-10-05 20:00:07.858 Local account: BR\BRSVPMTZLAB015$
. 2017-10-05 20:00:07.858 Working directory: C:\MONPROC\Admin
. 2017-10-05 20:00:07.858 Process ID: 4324
. 2017-10-05 20:00:07.858 Command-line: "C:\MONPROC\WinSCP\WinSCP.exe" /console=570 /consoleinstance=_4680_305 "/newinstance" "/log=8.25.B105163.log" "/script=C:\Windows\Temp\8.25.B105163.in"
. 2017-10-05 20:00:07.858 Time zone: Current: GMT-3, Standard: GMT-3 (E. South America Standard Time), DST: GMT-2 (E. South America Daylight Time), DST Start: 14/10/2017, DST End: 18/02/2017
. 2017-10-05 20:00:07.858 Login time: quinta-feira, 5 de outubro de 2017 20:00:07
. 2017-10-05 20:00:07.858 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.905 Script: Retrospectively logging previous script records:
> 2017-10-05 20:00:07.905 Script: open sftp://aiservice:***@brcmaiapp003 -certificate="ssh-rsa 2048 01:65:41:ea:7d:78:92:a7:2d:f5:ed:b9:8f:b6:4c:b3"
. 2017-10-05 20:00:07.905 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.905 Session name: aiservice@brcmaiapp003 (Ad-Hoc site)
. 2017-10-05 20:00:07.905 Host name: brcmaiapp003 (Port: 22)
. 2017-10-05 20:00:07.905 User name: aiservice (Password: Yes, Key file: No)
. 2017-10-05 20:00:07.905 Tunnel: No
. 2017-10-05 20:00:07.905 Transfer Protocol: SFTP
. 2017-10-05 20:00:07.905 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2017-10-05 20:00:07.905 Disable Nagle: No
. 2017-10-05 20:00:07.905 Proxy: none
. 2017-10-05 20:00:07.905 Send buffer: 262144
. 2017-10-05 20:00:07.905 SSH protocol version: 2; Compression: No
. 2017-10-05 20:00:07.905 Bypass authentication: No
. 2017-10-05 20:00:07.905 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2017-10-05 20:00:07.905 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2017-10-05 20:00:07.905 KEX: dh-gex-sha1,dh-group14-sha1,dh-group1-sha1,rsa,WARN
. 2017-10-05 20:00:07.905 SSH Bugs: A,A,A,A,A,A,A,A,A,A,A
. 2017-10-05 20:00:07.905 Simple channel: Yes
. 2017-10-05 20:00:07.905 Return code variable: Autodetect; Lookup user groups: A
. 2017-10-05 20:00:07.905 Shell: default
. 2017-10-05 20:00:07.905 EOL: 0, UTF: 2
. 2017-10-05 20:00:07.905 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2017-10-05 20:00:07.905 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2017-10-05 20:00:07.905 SFTP Bugs: A,A
. 2017-10-05 20:00:07.905 SFTP Server: default
. 2017-10-05 20:00:07.905 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2017-10-05 20:00:07.905 Cache directory changes: Yes, Permanent: Yes
. 2017-10-05 20:00:07.905 DST mode: 1
. 2017-10-05 20:00:07.905 --------------------------------------------------------------------------
. 2017-10-05 20:00:07.905 Looking up host "brcmaiapp003"
. 2017-10-05 20:00:07.952 Connecting to 100.68.5.9 port 22
. 2017-10-05 20:00:07.999 Server version: SSH-2.0-OpenSSH_6.6.1
. 2017-10-05 20:00:07.999 Using SSH protocol version 2
. 2017-10-05 20:00:07.999 We claim version: SSH-2.0-WinSCP_release_5.7
. 2017-10-05 20:00:08.014 Doing Diffie-Hellman group exchange
. 2017-10-05 20:00:08.030 Doing Diffie-Hellman key exchange with hash SHA-256
. 2017-10-05 20:00:09.184 Verifying host key rsa2 0x10001,0xa01997bff0ab3c5e 60c43527bccc4710 921ade9c66cb180b 7a94b98eb1fe5be3 a81536a25ac476a1 3f0f824fc1de9810 34df9b6fbd3154a1 27031659901a1b88 7c8d463cdfcea7c4 24b899df5e20006e af81c5405cb93cbe 48c7b956a6a3a29e f04f22bd9b7c5356 8696e2ecf17a4399 bd0c63063350591a 7aeb376cfdc5f2e0 6101e3132c01dfd1 4b6b8f4745a822b7 2b058cfc1b54031f 6ff7053f292358cb d91b4c038fb5c067 f66e37da0f94bf84 b0b24d52c92e8cbf 71f4cde1f59c8b8c 142820f9ad44d4d2 6190dd54ea3ee6b0 5e66768eb71d7579 c57bfb5a13b88ce2 b1705918afed9165 de7544bd1d6d51b4 4fb7240b4dc1e1f6 005092cb64b6711b with fingerprint ssh-rsa 2048 01:65:41:ea:7d:78:92:a7:2d:f5:ed:b9:8f:b6:4c:b3
. 2017-10-05 20:00:09.200 Host key matches configured key
. 2017-10-05 20:00:09.200 Host key fingerprint is:
. 2017-10-05 20:00:09.200 ssh-rsa 2048 01:65:41:ea:7d:78:92:a7:2d:f5:ed:b9:8f:b6:4c:b3
. 2017-10-05 20:00:09.200 Initialised AES-256 SDCTR client->server encryption
. 2017-10-05 20:00:09.200 Initialised HMAC-SHA-256 client->server MAC algorithm
. 2017-10-05 20:00:09.200 Initialised AES-256 SDCTR server->client encryption
. 2017-10-05 20:00:09.200 Initialised HMAC-SHA-256 server->client MAC algorithm
! 2017-10-05 20:00:09.247 Using username "aiservice".
. 2017-10-05 20:00:09.512 Prompt (password, "SSH password", <no instructions>, "&Password: ")
. 2017-10-05 20:00:09.512 Using stored password.
. 2017-10-05 20:00:09.512 Sent password
. 2017-10-05 20:00:09.512 Access granted
. 2017-10-05 20:00:09.512 Opening session as main channel
. 2017-10-05 20:00:09.543 Opened main channel
. 2017-10-05 20:00:09.605 Started a shell/command
. 2017-10-05 20:00:09.605 --------------------------------------------------------------------------
. 2017-10-05 20:00:09.621 Using SFTP protocol.
. 2017-10-05 20:00:09.637 Doing startup conversation with host.
> 2017-10-05 20:00:09.637 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2017-10-05 20:00:09.637 Type: SSH_FXP_VERSION, Size: 150, Number: -1
. 2017-10-05 20:00:09.637 SFTP version 3 negotiated.
. 2017-10-05 20:00:09.637 Unknown server extension posix-rename@openssh.com="1"
. 2017-10-05 20:00:09.637 Supports statvfs@openssh.com extension version "2"
. 2017-10-05 20:00:09.637 Unknown server extension fstatvfs@openssh.com="2"
. 2017-10-05 20:00:09.637 Supports hardlink@openssh.com extension version "1"
. 2017-10-05 20:00:09.637 Unknown server extension fsync@openssh.com="1"
. 2017-10-05 20:00:09.637 We believe the server has signed timestamps bug
. 2017-10-05 20:00:09.637 We will use UTF-8 strings until server sends an invalid UTF-8 string as with SFTP version 3 and older UTF-8 string are not mandatory
. 2017-10-05 20:00:09.637 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2017-10-05 20:00:09.637 Getting current directory name.
. 2017-10-05 20:00:09.637 Getting real path for '.'
> 2017-10-05 20:00:09.637 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2017-10-05 20:00:09.637 Type: SSH_FXP_NAME, Size: 51, Number: 16
. 2017-10-05 20:00:09.637 Real path is '/home/aiservice'
. 2017-10-05 20:00:09.637 Startup conversation with host finished.
< 2017-10-05 20:00:09.637 Script: Active session: [1] aiservice@brcmaiapp003
> 2017-10-05 20:00:09.637 Script: cd /home/cinstall/interf/out_VENDORS_01_SAR
. 2017-10-05 20:00:09.637 Cached directory change via "/home/cinstall/interf/out_VENDORS_01_SAR" to "/home/cinstall/interf/out_VENDORS_01_SAR".
. 2017-10-05 20:00:09.637 Getting current directory name.
< 2017-10-05 20:00:09.637 Script: /home/cinstall/interf/out_VENDORS_01_SAR
> 2017-10-05 20:00:09.637 Script: ls
. 2017-10-05 20:00:09.637 Listing directory "/home/cinstall/interf/out_VENDORS_01_SAR".
> 2017-10-05 20:00:09.637 Type: SSH_FXP_OPENDIR, Size: 49, Number: 267
< 2017-10-05 20:00:09.652 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
> 2017-10-05 20:00:09.652 Type: SSH_FXP_READDIR, Size: 13, Number: 524
< 2017-10-05 20:00:09.652 Type: SSH_FXP_NAME, Size: 325, Number: 524
> 2017-10-05 20:00:09.652 Type: SSH_FXP_READDIR, Size: 13, Number: 780
< 2017-10-05 20:00:09.652 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2017-10-05 20:00:09.652 Status code: 1
> 2017-10-05 20:00:09.652 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
. 2017-10-05 20:00:09.652 .;d;0;2017-10-05T20:45:00.000Z;"aiservice" [15001];"root" [0];rwxrwxrwx;3
. 2017-10-05 20:00:09.652 ..;d;0;2017-10-05T23:00:44.000Z;"aiservice" [15001];"root" [0];rwxrwxrwx;1
. 2017-10-05 20:00:09.652 processed;d;0;2017-10-05T20:45:00.000Z;"aiservice" [15001];"aiservice" [15001];rwxrwxrwx;1
< 2017-10-05 20:00:09.652 Script: drwxrwxrwx 3 aiservice root 12288 Oct 5 17:45:00 2017 .
< 2017-10-05 20:00:09.652 Script: drwxrwxrwx 35 aiservice root 4096 Oct 5 20:00:44 2017 ..
< 2017-10-05 20:00:09.652 Script: drwxrwxrwx 3 aiservice aiservice 32768 Oct 5 17:45:00 2017 processed
> 2017-10-05 20:00:09.652 Script: close
. 2017-10-05 20:00:09.652 Closing connection.
. 2017-10-05 20:00:09.652 Sending special code: 12
. 2017-10-05 20:00:09.652 Sent EOF message