ACSLS - ACSLS fails to start up - "Method or service exit timed out. Killing contract xxxxxx" (Doc ID 2073780.1)

Last updated on APRIL 17, 2017

Applies to:

Sun StorageTek Auto Cartridge Sys Lib SW (ACSLS) - Version 8.0 to 8.4 [Release 8.0]
Information in this document applies to any platform.

Symptoms

ACSLS is not starting up successfully. The acsss_event.log shows the ACS going online but only gets as far as verifying the LSM status.
( The CAP and the drive status should have been verified next. )

-
ss_main: Initiation started, Oracle ACSLS (Automated Cartridge System Library Software) 8.0.2

2015-11-01 13:55:16 ACSSA[0]:
1439 N sa_demux.c 1 296
Server system recovery started.

2015-11-01 13:55:17 ACSSA[0]:
1434 N sa_demux.c 1 296
ACS 1: Online.

2015-11-01 13:55:17 ACSLM[0]:
37 N lm_msg_hdlr.c 1 1067
lm_msg_hdlr: LMU READY received for ACS Identifier 1

2015-11-01 13:55:18 CSI[0]:
935 N csi_mt_init.c 1 162
ONC RPC: csi_mt_init(): Initiation Started


2015-11-01 13:55:18 CSI[0]:
935 N csi_mt_init.c 1 162
ONC RPC: csi_mt_init(): Initiation Started

2015-11-01 13:55:18 CSI[0]:
964 N csi_rpcuinit.c 1 241
ONC RPC: csi_rpcuinit(): Unmapped previously registered RPC service.

2015-11-01 13:55:18 CSI[0]:
964 N csi_rpcuinit.c 1 241
ONC RPC: csi_rpcuinit(): Unmapped previously registered RPC service.

2015-11-01 13:55:18 CSI[0]:
964 N csi_rpctinit.c 1 371
ONC RPC: csi_rpctinit(): Unmapped previously registered RPC service.

2015-11-01 13:55:18 CSI[0]:
964 N csi_rpctinit.c 1 371
ONC RPC: csi_rpctinit(): Unmapped previously registered RPC service.

2015-11-01 13:55:18 CSI[0]:
938 N csi_mt_init.c 1 281
ONC RPC: csi_mt_init(): Initiation Completed

2015-11-01 13:55:18 ACSLH[0]:
2551 I Co_status.C 2 1356
Checking for LSM 1,0 status ...

2015-11-01 13:55:21 storage server[0]:
351 N ss_main.c 4 826
ss_main: Initiation completed

2015-11-01 13:55:28 ACSLH[0]:
2552 I Co_status.C 2 3079
LSM 1,0 status Ready, on-line,door closed.
  hand-0 operative, empty.
  hand-1 operative, empty.

2015-11-01 13:55:30 ACSLH[0]:
2551 I Co_status.C 2 1356
Checking for LSM 1,0 status ...

2015-11-01 13:55:40 ACSLH[0]:
2552 I Co_status.C 2 3079
LSM 1,0 status Ready, on-line,door closed.
  hand-0 operative, empty.
  hand-1 operative, empty.

2015-11-01 13:55:45 gui_ctl.sh:
Restarting Oracle Java(TM) Web Console Version 3.1 ... The console is running

2015-11-01 13:59:16 storage server[0]:
347 N ss_main.c 4 580
----------------------------------------------------------------



ACSLS was restarted multiple times. These are among the messages logged when ACSLS was restarted:

....

2015-11-01 05:44:09 ACSLM[0]:
37 N lm_msg_hdlr.c 1 1067
lm_msg_hdlr: LMU READY received for ACS Identifier 1

2015-11-01 05:44:09 ACSLM[0]:
2157 I lm_msg_hdlr.c 1 2183
ACS 1 is affected by a configuration or capacity on demand change. Please run
dynamic config to update the configuration of this ACS.

2015-11-01 05:44:10 CSI[0]:
935 N csi_mt_init.c 1 162
ONC RPC: csi_mt_init(): Initiation Started


Messages in the ACSLS service start up log:

[ Nov  1 05:43:27 Executing start method ("/lib/svc/method/svc-acsls start") ]
/export/home/ACSSS/bin/acsls.startup: Initializing ACSLS.
[ Nov  1 05:48:07 Method or service exit timed out.  Killing contract 566573 ]
[ Nov  1 05:48:07 Method "start" failed due to signal KILL ]

 

These are the messages in the SL3000 error warn info event log:
---
 
  Time : 2015-11-01T02:12:02.301
  Device Address : 1.0.0.0.0
  User : root
  Requester : default
  Activity : internal
  Request Id : 0
  Level : warn
  Result Code : 3912
  Text : "Hli connection status change - transition to non-operational"
  Parameters : port=11170542/skt/50001/10.1.20.74
  
  
  Time : 2015-11-01T04:48:48.290
  Device Address : 1.0.0.0.0
  User : root
  Requester : default
  Activity : internal
  Request Id : 0
  Level : warn
  Result Code : 3912
  Text : "Hli connection status change - transition to non-operational"
  Parameters : port=3537988/skt/50001/10.1.20.74
  
  
  Time : 2015-11-01T04:52:46.420
  Device Address : 1.0.0.0.0
  User : root
  Requester : default
  Activity : internal
  Request Id : 0
  Level : warn
  Result Code : 3912
  Text : "Hli connection status change - transition to non-operational"
  Parameters : port=3537988/skt/50001/10.1.20.74
  
  
  Time : 2015-11-01T04:53:28.651
  Device Address : 1.0.0.0.0
  User : root
  Requester : default
  Activity : internal
  Request Id : 0
  Level : warn
  Result Code : 3912
  Text : "Hli connection status change - transition to non-operational"
  Parameters : port=4076096/skt/50001/10.1.20.74
  
  
  Time : 2015-11-01T04:57:27.439
  Device Address : 1.0.0.0.0
  User : root
  Requester : default
  Activity : internal
  Request Id : 0
  Level : warn
  Result Code : 3912
  Text : "Hli connection status change - transition to non-operational"
  Parameters : port=4076096/skt/50001/10.1.20.74
  ...


Changes

ACSLS was shut down during testing of the HSC application. After the tests concluded,
ACSLS was restarted but the recovery process during the start up process failed to complete.

Cause

Sign In with your My Oracle Support account

Don't have a My Oracle Support account? Click to get started

My Oracle Support provides customers with access to over a
Million Knowledge Articles and hundreds of Community platforms