SSIS job works when user is logged in but otherwise fails

Advertisement

geninfarun
Joined:
Posts:
14
Location:
USA

SSIS job works when user is logged in but otherwise fails

I am running an ssis job to download a file from an sftp using a stored session but it only works if I am logged into the server running the ssis job. If I am logged out it fails with this error:

2011-10-24 07:00:24.071 Looking up host "abc.domain.com"
. 2011-10-24 07:00:24.216 Connecting to xxx.xxx.xxx.xxx port 22
. 2011-10-24 07:00:24.348 Server version: SSH-2.0-SSHD
. 2011-10-24 07:00:24.349 Using SSH protocol version 2
. 2011-10-24 07:00:24.349 We claim version: SSH-2.0-WinSCP_release_4.2.7
. 2011-10-24 07:00:24.695 Using Diffie-Hellman with standard group "group14"
. 2011-10-24 07:00:24.695 Doing Diffie-Hellman key exchange with hash SHA-1
. 2011-10-24 07:00:24.774 Server unexpectedly closed network connection

The help file says its probably a firewall issue.

Reply with quote

Advertisement

geninfarun
Joined:
Posts:
14
Location:
USA

I guess I spoke too soon. The job failed again, this time I was logged in but the server was locked, and it still failed. This is the message when it was scheduled;

2011-10-26 07:00:28.528 WinSCP Version 4.3.5 (Build 1463) (OS 6.0.6001 Service Pack 1)
. 2011-10-26 07:00:28.530 Configuration: C:\Program Files (x86)\WinSCP\WinSCP.ini
. 2011-10-26 07:00:28.530 Local account: xxxxxx.GOV\xxxxx
. 2011-10-26 07:00:28.530 Login time: Wednesday, October 26, 2011 7:00:28 AM
. 2011-10-26 07:00:28.530 --------------------------------------------------------------------------
. 2011-10-26 07:00:28.607 Session name: ftsqa (Stored session)
. 2011-10-26 07:00:28.607 Host name: xxx.xxx.com(Port: 22)
. 2011-10-26 07:00:28.607 User name: xxxxx (Password: Yes, Key file: No)
. 2011-10-26 07:00:28.607 Tunnel: No
. 2011-10-26 07:00:28.607 Transfer Protocol: SFTP (SCP)
. 2011-10-26 07:00:28.607 Ping type: -, Ping interval: 30 sec; Timeout: 600 sec
. 2011-10-26 07:00:28.607 Proxy: none
. 2011-10-26 07:00:28.607 SSH protocol version: 2; Compression: No
. 2011-10-26 07:00:28.607 Bypass authentication: No
. 2011-10-26 07:00:28.607 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2011-10-26 07:00:28.607 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2011-10-26 07:00:28.607 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2011-10-26 07:00:28.607 SFTP Bugs: -,-
. 2011-10-26 07:00:28.607 Return code variable: Autodetect; Lookup user groups: Yes
. 2011-10-26 07:00:28.607 Shell: default
. 2011-10-26 07:00:28.607 EOL: 0, UTF: 2
. 2011-10-26 07:00:28.607 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2011-10-26 07:00:28.607 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2011-10-26 07:00:28.607 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2011-10-26 07:00:28.607 Cache directory changes: Yes, Permanent: Yes
. 2011-10-26 07:00:28.608 DST mode: 1
. 2011-10-26 07:00:28.608 --------------------------------------------------------------------------
. 2011-10-26 07:00:29.037 Looking up host "xxx.xxx.com"
. 2011-10-26 07:00:29.194 Connecting to xxx.xxx.xxx.xxx 1 port 22
. 2011-10-26 07:00:29.327 Server version: SSH-2.0-SSHD
. 2011-10-26 07:00:29.327 We believe remote version has SSH-2 ignore bug
. 2011-10-26 07:00:29.327 Using SSH protocol version 2
. 2011-10-26 07:00:29.327 We claim version: SSH-2.0-WinSCP_release_4.3.5
. 2011-10-26 07:00:29.892 Using Diffie-Hellman with standard group "group14"
. 2011-10-26 07:00:29.892 Doing Diffie-Hellman key exchange with hash SHA-1
. 2011-10-26 07:00:30.038 Network error: Software caused connection abort



And this is the message when I ran the job manually and it was successful;

. 2011-10-26 07:25:54.870 --------------------------------------------------------------------------
. 2011-10-26 07:25:54.870 WinSCP Version 4.3.5 (Build 1463) (OS 6.0.6001 Service Pack 1)
. 2011-10-26 07:25:54.872 Configuration: C:\Program Files (x86)\WinSCP\WinSCP.ini
. 2011-10-26 07:25:54.873 Local account: xxxxx.GOV\xxxx
. 2011-10-26 07:25:54.873 Login time: Wednesday, October 26, 2011 7:25:54 AM
. 2011-10-26 07:25:54.873 --------------------------------------------------------------------------
. 2011-10-26 07:25:54.873 Session name: ftsqa (Stored session)
. 2011-10-26 07:25:54.873 Host name: fts.xxxxx.com (Port: 22)
. 2011-10-26 07:25:54.873 User name: stxxx960 (Password: Yes, Key file: No)
. 2011-10-26 07:25:54.873 Tunnel: No
. 2011-10-26 07:25:54.873 Transfer Protocol: SFTP (SCP)
. 2011-10-26 07:25:54.873 Ping type: -, Ping interval: 30 sec; Timeout: 600 sec
. 2011-10-26 07:25:54.873 Proxy: none
. 2011-10-26 07:25:54.873 SSH protocol version: 2; Compression: No
. 2011-10-26 07:25:54.873 Bypass authentication: No
. 2011-10-26 07:25:54.873 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2011-10-26 07:25:54.873 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2011-10-26 07:25:54.873 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2011-10-26 07:25:54.873 SFTP Bugs: -,-
. 2011-10-26 07:25:54.874 Return code variable: Autodetect; Lookup user groups: Yes
. 2011-10-26 07:25:54.874 Shell: default
. 2011-10-26 07:25:54.874 EOL: 0, UTF: 2
. 2011-10-26 07:25:54.874 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2011-10-26 07:25:54.874 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2011-10-26 07:25:54.874 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2011-10-26 07:25:54.874 Cache directory changes: Yes, Permanent: Yes
. 2011-10-26 07:25:54.874 DST mode: 1
. 2011-10-26 07:25:54.874 --------------------------------------------------------------------------
. 2011-10-26 07:25:54.874 Looking up host "fts.xxxxxx.com"
. 2011-10-26 07:25:54.989 Connecting to xxx.xxx.xxx.xxx 2 port 22
. 2011-10-26 07:25:55.123 Server version: SSH-2.0-SSHD
. 2011-10-26 07:25:55.123 We believe remote version has SSH-2 ignore bug
. 2011-10-26 07:25:55.123 Using SSH protocol version 2
. 2011-10-26 07:25:55.123 We claim version: SSH-2.0-WinSCP_release_4.3.5
. 2011-10-26 07:25:55.188 Using Diffie-Hellman with standard group "group14"
. 2011-10-26 07:25:55.188 Doing Diffie-Hellman key exchange with hash SHA-1
. 2011-10-26 07:25:56.133 Host key fingerprint is:
. 2011-10-26 07:25:56.133 ssh-rsa 2048 ed:f3:71:f5:94:3e:d7:eb:cc:39:9d:98:9c:40:08:11
. 2011-10-26 07:25:56.151 Initialised AES-256 CBC client->server encryption
. 2011-10-26 07:25:56.151 Initialised HMAC-SHA1 client->server MAC algorithm
. 2011-10-26 07:25:56.199 Initialised AES-256 CBC server->client encryption
. 2011-10-26 07:25:56.199 Initialised HMAC-SHA1 server->client MAC algorithm
! 2011-10-26 07:25:56.264 Using username "st10960".
. 2011-10-26 07:25:56.330 Prompt (7, SSH password, , &Password: )
. 2011-10-26 07:25:56.331 Using stored password.
. 2011-10-26 07:25:56.332 Sent password
. 2011-10-26 07:25:56.503 Access granted
. 2011-10-26 07:25:56.567 Opened channel for session
. 2011-10-26 07:25:57.150 Started a shell/command
. 2011-10-26 07:25:57.150 --------------------------------------------------------------------------
. 2011-10-26 07:25:57.404 Using SFTP protocol.
. 2011-10-26 07:25:57.406 Doing startup conversation with host.
> 2011-10-26 07:25:57.461 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2011-10-26 07:25:57.527 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2011-10-26 07:25:57.527 SFTP version 3 negotiated.
. 2011-10-26 07:25:57.527 We believe the server has signed timestamps bug
. 2011-10-26 07:25:57.527 We will use UTF-8 strings for status messages only
. 2011-10-26 07:25:57.527 Getting current directory name.
. 2011-10-26 07:25:57.527 Getting real path for '.'
> 2011-10-26 07:25:57.564 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2011-10-26 07:25:57.667 Type: SSH_FXP_NAME, Size: 51, Number: 16
. 2011-10-26 07:25:57.667 Real path is '/'
. 2011-10-26 07:25:57.667 Startup conversation with host finished.
< 2011-10-26 07:25:57.667 Script: Active session: [1] ftsqa
> 2011-10-26 07:25:57.667 Script: option confirm off
< 2011-10-26 07:25:57.667 Script: confirm off
> 2011-10-26 07:25:57.667 Script: get /outbound/*.csv c:\testpayment.csv
. 2011-10-26 07:25:57.668 Listing directory "/outbound".
> 2011-10-26 07:25:57.668 Type: SSH_FXP_OPENDIR, Size: 18, Number: 267
< 2011-10-26 07:25:57.752 Type: SSH_FXP_HANDLE, Size: 10, Number: 267
> 2011-10-26 07:25:57.752 Type: SSH_FXP_READDIR, Size: 10, Number: 524
< 2011-10-26 07:25:57.819 Type: SSH_FXP_NAME, Size: 513, Number: 524
> 2011-10-26 07:25:57.819 Type: SSH_FXP_READDIR, Size: 10, Number: 780
< 2011-10-26 07:25:57.885 Type: SSH_FXP_STATUS, Size: 17, Number: 780
< 2011-10-26 07:25:57.885 Status code: 1
> 2011-10-26 07:25:57.885 Type: SSH_FXP_CLOSE, Size: 10, Number: 1028
. 2011-10-26 07:25:57.907 File: "/outbound/EBR_PSO_2011_10_25.csv"
. 2011-10-26 07:25:57.907 Copying "/outbound/EBR_PSO_2011_10_25.csv" to local directory started.
. 2011-10-26 07:25:57.907 Binary transfer mode selected.
. 2011-10-26 07:25:57.932 Opening remote file.
> 2011-10-26 07:25:57.936 Type: SSH_FXP_OPEN, Size: 49, Number: 1283
< 2011-10-26 07:25:57.950 Type: SSH_FXP_STATUS, Size: 40, Number: 1028
. 2011-10-26 07:25:57.950 Discarding reserved response
< 2011-10-26 07:25:58.034 Type: SSH_FXP_HANDLE, Size: 10, Number: 1283
> 2011-10-26 07:25:58.034 Type: SSH_FXP_FSTAT, Size: 10, Number: 1544
> 2011-10-26 07:25:58.034 Type: SSH_FXP_READ, Size: 22, Number: 1797
< 2011-10-26 07:25:58.134 Type: SSH_FXP_ATTRS, Size: 37, Number: 1544
. 2011-10-26 07:25:58.134 Storing reserved response
< 2011-10-26 07:25:58.291 Status code: 1
. 2011-10-26 07:25:58.291 3 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
> 2011-10-26 07:25:58.291 Type: SSH_FXP_CLOSE, Size: 10, Number: 2308
. 2011-10-26 07:25:58.291 Closing connection.
. 2011-10-26 07:25:58.291 Sending special code: 12
. 2011-10-26 07:25:58.291 Sent EOF message

Reply with quote

Advertisement

geninfarun

Problem is we dont control the server. Heres something more interesting. I created another schedule for the same job for 30 minutes later, and for the last 2 days, the first time the job always fails with connection closed and the second time, the job always works.

Reply with quote

geninfarun
Joined:
Posts:
14
Location:
USA

What is is this line mean??

Looking up host "123.abc.com"
. 2011-11-08 08:34:34.794 Connecting to xxx.xxx.xxx.xxx port 22
. 2011-11-08 08:34:34.923 Server version: SSH-2.0-SSHD
. 2011-11-08 08:34:34.923 We believe remote version has SSH-2 ignore bug.
2011-11-08 08:34:34.923 Using SSH protocol version 2
. 2011-11-08 08:34:34.923 We claim version: SSH-2.0-WinSCP_release_4.3.5
. 2011-11-08 08:34:35.511 Using Diffie-Hellman with standard group "group14"
. 2011-11-08 08:34:35.511 Doing Diffie-Hellman key exchange with hash SHA-1
. 2011-11-08 08:34:35.714 Network error: Software caused connection abort

Reply with quote

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

geninfarun wrote:

What is is this line mean??

. 2011-11-08 08:34:34.923 We believe remote version has SSH-2 ignore bug.
That's actually a bug. Thanks for spotting this!
Added to tracker:
https://winscp.net/tracker/767

I do not think this is related though. Anyway, I'll send you build with fix, just in case.

Reply with quote

Advertisement

You can post new topics in this forum