IT13909: SFTP CLIENT BEGIN SESSION FAILS WITH ERROR MESSAGE = [COULD NOT COMPLETE CONNECTION TO SPECIFIED HOST]

iSeries_Media_5020601_2
Media_IM_5020601_2
iSeries_Media_5020601_2

APAR status

• Closed as program error.

Error description

• This is a newly configured communication.
  When the parameter ConnectionRetries, in the SFTP Client Begin
  session, is set to a value higher than 55, the error does not
  reflect what the problem is.
  
  SFTP Client begin session fails with:
  Error Message = [Could not complete connection to specified
  host ]
  SFTPClientAdapter=[SFTPClientAdapter]
  ServiceStartTime =[2015-11-24 13:48:48.858]
  ServiceEndTime = [2015-11-24 13:48:48.875]
  Time taken to complete the Service is 0.017 Seconds
  SessionToken=[SFTPClientAdapter_SFTPClientAdapter_node1_XXXXXXX
  XXXXXX:11]
  
  Error in sftpclient.log
  [2015-11-24 14:16:36.941] DEBUG
  BeginSessionService.processData() - Starting ...
  [2015-11-24 14:16:36.941] DEBUG
  BeginSessionService.registerService() - Successfully registered
  service [SFTPClientBeginSession] with Ops.
  [2015-11-24 14:16:36.941] DEBUG
  BeginSessionRequest.extractConfiguration() entered.
  [2015-11-24 14:16:36.941] DEBUG
  BeginSessionRequest.extractCharacterEncoding() character
  encoding - null
  [2015-11-24 14:16:36.941] DEBUG
  BeginSessionRequest.extractConfiguration() exited.
  [2015-11-24 14:16:36.941] DEBUG [[SERVICE]] --
  SFTPClientBeginSession
  [2015-11-24 14:16:36.942] DEBUG primitiveProcessData request=
  <BeginSessionRequest>
  <SFTPClientAdapter>SFTPClientAdapter</SFTPClientAdapter>
  <SessionToken>empty</SessionToken>
  <WorkflowContextId>HOSTNAME:node1:1513a3f9783:4097</WorkflowCont
  extId>
  <ResponseTimeout>30</ResponseTimeout>
  <ConnectionRetries>55</ConnectionRetries>
  <RetryDelay>1000</RetryDelay>
  <RemotePort>53839</RemotePort>
  <RemoteHost>MYIPADDRESS</RemoteHost>
  <RemoteUserId>MYUSER</RemoteUserId>
  <RemotePasswd>********</RemotePasswd>
  <ResponseTimeout>30</ResponseTimeout>
  <KnownHostKeyId>[HOSTNAME:node1:1513a3f9783:3263]</KnownHostKeyI
  d>
  <UserIdentityKeyId>null</UserIdentityKeyId>
  <PreferredAuthenticationMethod>null</PreferredAuthenticationMeth
  od>
  <PreferredCipher>null</PreferredCipher>
  <PreferredMAC>null</PreferredMAC>
  <ProfileId>null</ProfileId>
  <Compression>none</Compression>
  </BeginSessionRequest>
  
  
  [2015-11-24 14:16:36.942] DEBUG
  BeginSessionService.primitiveProcessData() -
  SftpClientAdapterInstanceName from wfc = [SFTPClientAdapter].
  [2015-11-24 14:16:36.942] DEBUG
  BeginSessionService.getServerAdapterLittleA() - looking up
  Server Adapter for [SFTPClientAdapter].
  [2015-11-24 14:16:36.944] DEBUG Client.beginSession(BSR)
  entering.
  [2015-11-24 14:16:36.952] DEBUG Client.beginSession(BSR) -
  starting timer with timeout [30000] ms, on
  com.sterlingcommerce.woodstock.services.sftpclient.client.Timeou
  tHandler@a6462120
  [2015-11-24 14:16:36.952] DEBUG Session.beginSession(BSR,R,PT)
  entering.
  [2015-11-24 14:16:36.953] DEBUG Session.beginSession(BSR,R,PT)
  exiting.
  [2015-11-24 14:16:36.953] DEBUG Session.beginSession(BSR,R,PT)
  runnable entering.
  [2015-11-24 14:16:36.953] DEBUG Client.beginSession(BSR)
  request parameters:
  
  
  <BeginSessionRequest>
  <SFTPClientAdapter>SFTPClientAdapter</SFTPClientAdapter>
  <SessionToken>empty</SessionToken>
  <WorkflowContextId>HOSTNAME:node1:1513a3f9783:4097</WorkflowCont
  extId>
  <ResponseTimeout>30</ResponseTimeout>
  <ConnectionRetries>55</ConnectionRetries>
  <RetryDelay>1000</RetryDelay>
  <RemotePort>53839</RemotePort>
  <RemoteHost>MYIPADDRESS</RemoteHost>
  <RemoteUserId>MYUSER</RemoteUserId>
  <RemotePasswd>********</RemotePasswd>
  <ResponseTimeout>30</ResponseTimeout>
  <KnownHostKeyId>[HOSTNAME:node1:1513a3f9783:3263]</KnownHostKeyI
  d>
  <UserIdentityKeyId>null</UserIdentityKeyId>
  <PreferredAuthenticationMethod>null</PreferredAuthenticationMeth
  od>
  <PreferredCipher>null</PreferredCipher>
  <PreferredMAC>null</PreferredMAC>
  <ProfileId>null</ProfileId>
  <Compression>none</Compression>
  </BeginSessionRequest>
  
  
  [2015-11-24 14:16:36.955] DEBUG Session.beginSession(BSR,R,PT)
  opening PsSshTransport to MYIPADDRESS:53839
  [2015-11-24 14:16:36.955] DEBUG
  PsSshTransport.PsSshTransport(7) entering with
  remoteAddr=[MYIPADDRESS], remotePort=[53839],
  localPorts=[null], retries=[55], retryDelay=[1000000].
  [2015-11-24 14:16:36.955] ERROR Session.failedSequence(S,R)
  Could not complete connection to specified host
  [2015-11-24 14:16:36.955] DEBUG Response.sendResults - Entered.
  Time - 1448392596955
  [2015-11-24 14:16:36.955] DEBUG Response.sendResults -
  isSynchronized. Time - 1448392596955
  [2015-11-24 14:16:36.955] DEBUG Response.sendResults - exit.
  Time - 1448392596955
  [2015-11-24 14:16:36.955] DEBUG Client.beginSession(BSR)
  exiting.
  [2015-11-24 14:16:36.957] DEBUG Session.beginSession(BSR,R,PT)
  runnable exiting.
  [2015-11-24 14:16:36.957] DEBUG
  BeginSessionService.primitiveProcessData() - Incremented
  Session Token Count for
  SFTPClientAdapter_SFTPClientAdapter_node1
  [2015-11-24 14:16:36.958] DEBUG
  BeginSessionService.processData() - Un-registered thread with
  Ops.
  [2015-11-24 14:16:36.958] DEBUG
  BeginSessionService.processData() - Exiting ...
  

Local fix

• STRRTC - 484641
  SB / SB
  
  Circumvention:
  Decrease the "ConnectionRetries" parameter in the SFTP
  Client Begin configuration so that the numeric value is lower
  than 50.
  

Problem summary

• USERS AFFECTED:
  ALL
  
  PROBLEM DESCRIPTION:
  SFTP Client Begin errors when connectionRetries is higher than
  55.
  
  PLATFORMS AFFECTED:
  ALL
  

Problem conclusion

• Resolution Summary:
  A code fix has been provided.
  
  DELIVERED IN:
  5020601_2
  

Temporary fix

Comments

APAR Information

• APAR is sysrouted FROM one or more of the following:

• APAR is sysrouted TO one or more of the following:

Fix information

• Fixed component name

  STR B2B INTEGRA

• Fixed component ID

  5725D0600

Applicable component levels