Connect to R/3 failed. USERID and/or PASSWD could not be retrieved from SSFS

[Former Member]

Dear All,

Solman installation 7.1 fails with Error in the Start instance phase.

Database : Oracle
OS: AIX 6100-07-02-1150
Oracle version: 11.2.0.3
Kernel version: 720 patch number is 114

1. I have checked the SAP Note #1639578 - SSFS as password storage for primary database connect .

The below values has alreadt been set in default profile and in environment variables also
---------------------------------------------------------------------------
RSEC_SSFS_DATAPATH=/usr/sap/SMI/SYS/global/security/rsecssfs/data
RSEC_SSFS_KEYPATH=/usr/sap/SMI/SYS/global/security/rsecssfs/key
rsdb_ssfs_connect=1
---------------------------------------------------------------------------

2. The Permission of the Global directory

------------------------------------------------------------------------------
solmantrg:smiadm 13> ls -all
total 0
drwxr-xr-x    5 smiadm   sapsys          256 Apr 18 17:37 .
drwxr-xr-x    5 smiadm   sapsys          256 Apr 19 21:51 ..
drwxr-xr-x    3 smiadm   sapsys          256 Apr 18 17:37 exe
drwxr-xr-x    3 smiadm   sapsys          256 Apr 18 17:37 gen
lrwxrwxrwx    1 smiadm   sapsys           18 Apr 18 17:37 global -> /sapmnt/SMI/global

Permission Of security Folder:
solmantrg:smiadm 17> ls -all
total 8
drwx------    5 smiadm   sapsys          256 Apr 22 13:25 .
drwxr-xr-x    5 smiadm   sapsys          256 Apr 18 17:37 ..
-rw-r--r--    1 smiadm   sapsys            0 Apr 22 13:25 ABAPPROT
drwxr-xr-x    8 smiadm   sapsys          256 Apr 18 17:39 SDT
-rw-r--r--    1 smiadm   sapsys          272 Apr 18 17:37 ms_acl_info
drwxr-xr-x    2 smiadm   sapsys          256 Apr 22 13:26 sapcontrol
drwx------    5 smiadm   sapsys          256 Apr 18 17:37 security

Permission Of rsecssfs:
solmantrg:smiadm 20> ls -all
total 0
drwx------    5 smiadm   sapsys          256 Apr 18 17:37 .
drwx------    5 smiadm   sapsys          256 Apr 22 13:25 ..
drwx------    2 smiadm   sapsys          256 Apr 19 21:01 data
drwx------    3 smiadm   sapsys          256 Apr 18 17:37 lib
drwx------    4 smiadm   sapsys          256 Apr 18 17:37 rsecssfs

permission Of data & Key
solmantrg:smiadm 22> ls -all
total 0
drwx------    4 smiadm   sapsys          256 Apr 18 17:37 .
drwx------    5 smiadm   sapsys          256 Apr 18 17:37 ..
drwx------    2 smiadm   sapsys          256 Apr 22 12:42 data
drwx------    2 smiadm   sapsys          256 Apr 18 17:37 key

permission Of SSFS_SMI.DAT
solmantrg:smiadm 24> pwd
/sapmnt/SMI/global/security/rsecssfs/data
solmantrg:smiadm 25> ls -all
total 8
drwx------    2 smiadm   sapsys          256 Apr 22 12:42 .
drwx------    4 smiadm   sapsys          256 Apr 18 17:37 ..
-rw-------    1 smiadm   sapsys          972 Apr 22 12:42 SSFS_SMI.DAT

No key for File ( SSFS_SMI.KEY ) IN PATH ( /sapmnt/SMI/global/security/rsecssfs/key)  Exists
solmantrg:smiadm 28> cd key
solmantrg:smiadm 29> ls -altr
total 0
drwx------    4 smiadm   sapsys          256 Apr 18 17:37 ..
drwx------    2 smiadm   sapsys          256 Apr 18 17:37 .
---------------------------------------------------------------------------------------------------------------------------

3. From smiadm, R3trans -x shows 0000.  BUT from ora>sid>, R3trans -x shows 0012

---------------------------------------------------------------------------------------------------
solmantrg:orasmi 2> R3trans -x
This is R3trans version 6.22 (release 720 - 26.10.11 - 13:00:00).
unicode enabled version
2EETW169 no connect possible: "DBMS = ORACLE                           --- dbs_ora_tnsname = 'SMI'"
R3trans finished (0012).
----------------------------------------------------------------------------------------------------------------

4. Output Of Trans.log from Ora<SID> User
*********************************************************************************************
solmantrg:orasmi 3> more trans.log
4 ETW000 R3trans version 6.22 (release 720 - 26.10.11 - 13:00:00).
4 ETW000 unicode enabled version
4 ETW000 ===============================================
4 ETW000
4 ETW000 date&time   : 22.04.2014 - 14:41:32
4 ETW000 control file: <no ctrlfile>
4 ETW000 R3trans was called as follows: R3trans -x
4 ETW000  trace at level 2 opened for a given file pointer
4 ETW000  [     dev trc,00000]  Tue Apr 22 14:41:32 2014                                                 145  0.000145
4 ETW000  [     dev trc,00000]  db_con_init called                                                        41  0.000186
4 ETW000  [     dev trc,00000]  set_use_ext_con_info(): ssfs will be used to get connect information
4 ETW000                                                                                                  91  0.000277
4 ETW000  [     dev trc,00000]  determine_block_commit: no con_hdl found as blocked for con_name = R/3
4 ETW000                                                                                                  84  0.000361
4 ETW000  [     dev trc,00000]  create_con (con_name=R/3)                                                 40  0.000401
4 ETW000  [     dev trc,00000]  Loading DB library '/usr/sap/SMI/SYS/exe/run/dboraslib.o' ...             74  0.000475
4 ETW000  [     dev trc,00000]  DlLoadLib() success: dlopen("/usr/sap/SMI/SYS/exe/run/dboraslib.o"), hdl 0
4 ETW000                                                                                               31906  0.032381
4 ETW000  [     dev trc,00000]  Library '/usr/sap/SMI/SYS/exe/run/dboraslib.o' loaded                     49  0.032430
4 ETW000  [     dev trc,00000]  function DbSlExpFuns loaded from library /usr/sap/SMI/SYS/exe/run/dboraslib.o
4 ETW000                                                                                                  61  0.032491
4 ETW000  [     dev trc,00000]  Version of '/usr/sap/SMI/SYS/exe/run/dboraslib.o' is "720.00", patchlevel (0.114)
4 ETW000                                                                                                 493  0.032984
4 ETW000  [     dev trc,00000]  function dsql_db_init loaded from library /usr/sap/SMI/SYS/exe/run/dboraslib.o
4 ETW000                                                                                                  55  0.033039
4 ETW000  [     dev trc,00000]  function dbdd_exp_funs loaded from library /usr/sap/SMI/SYS/exe/run/dboraslib.o
4 ETW000                                                                                                 108  0.033147
4 ETW000  [     dev trc,00000]  New connection 0 created                                                 264  0.033411
4 ETW000  [     dev trc,00000]  0: name = R/3, con_id = -000000001, state = DISCONNECTED, tx = NO , bc = NO , hc = NO , perm = YES, reco = NO , frco = NO , timeout = 00
0, con_max = 255, con_opt = 255, occ = NO , prog =
4 ETW000                                                                                                  79  0.033490
4 ETW000  [     dev trc,00000]  db_con_connect (con_name=R/3)                                             79  0.033569
4 ETW000  [     dev trc,00000]  determine_block_commit: no con_hdl found as blocked for con_name = R/3
4 ETW000                                                                                                  53  0.033622
4 ETW000  [     dev trc,00000]  find_con_by_name found the following connection:                          35  0.033657
4 ETW000  [     dev trc,00000]  0: name = R/3, con_id = 000000000, state = DISCONNECTED, tx = NO , bc = NO , hc = NO , perm = YES, reco = NO , frco = NO , timeout = 000
, con_max = 255, con_opt = 255, occ = NO , prog =
4 ETW000                                                                                                  74  0.033731
4 ETW000  [     dev trc,00000]  read_con_info_ssfs(): reading connect info for connection R/3             36  0.033767
4 ETW000  [     dev trc,00000]  read_con_info_ssfs(): DBSL supports extended connect protocol             49  0.033816
4 ETW000                          ==> connect info for default DB will be read from ssfs
4 ETW000  [     dev trc,00000]  read_con_info_ssfs(): register callback ssfs_dbi_trace and max level 2
4 ETW000                                                                                                  88  0.033904
4 ETW000  [     dev trc,00000]  function DbSlExpFuns loaded from library /usr/sap/SMI/SYS/exe/run/dboraslib.o
4 ETW000                                                                                                  61  0.032491
4 ETW000  [     dev trc,00000]  Version of '/usr/sap/SMI/SYS/exe/run/dboraslib.o' is "720.00", patchlevel (0.114)
4 ETW000                                                                                                 493  0.032984
4 ETW000  [     dev trc,00000]  function dsql_db_init loaded from library /usr/sap/SMI/SYS/exe/run/dboraslib.o
4 ETW000                                                                                                  55  0.033039
4 ETW000  [     dev trc,00000]  function dbdd_exp_funs loaded from library /usr/sap/SMI/SYS/exe/run/dboraslib.o
4 ETW000                                                                                                 108  0.033147
4 ETW000  [     dev trc,00000]  New connection 0 created                                                 264  0.033411
4 ETW000  [     dev trc,00000]  0: name = R/3, con_id = -000000001, state = DISCONNECTED, tx = NO , bc = NO , hc = NO , perm = YES, reco = NO , frco = NO , timeout = 00
0, con_max = 255, con_opt = 255, occ = NO , prog =
4 ETW000                                                                                                  79  0.033490
4 ETW000  [     dev trc,00000]  db_con_connect (con_name=R/3)                                             79  0.033569
4 ETW000  [     dev trc,00000]  determine_block_commit: no con_hdl found as blocked for con_name = R/3
4 ETW000                                                                                                  53  0.033622
4 ETW000  [     dev trc,00000]  find_con_by_name found the following connection:                          35  0.033657
4 ETW000  [     dev trc,00000]  0: name = R/3, con_id = 000000000, state = DISCONNECTED, tx = NO , bc = NO , hc = NO , perm = YES, reco = NO , frco = NO , timeout = 000
, con_max = 255, con_opt = 255, occ = NO , prog =
4 ETW000                                                                                                  74  0.033731
4 ETW000  [     dev trc,00000]  read_con_info_ssfs(): reading connect info for connection R/3             36  0.033767
4 ETW000  [     dev trc,00000]  read_con_info_ssfs(): DBSL supports extended connect protocol             49  0.033816
4 ETW000                          ==> connect info for default DB will be read from ssfs
4 ETW000  [     dev trc,00000]  read_con_info_ssfs(): register callback ssfs_dbi_trace and max level 2
4 ETW000                                                                                                  88  0.033904
4 ETW000  [     dev trc,00000]  RSecSSFs: Entering function "RSecSSFsGetRecord" [rsecssfs.c 836]          84  0.033988
4 ETW000  [     dev trc,00000]  RSecSSFs: Configuration data read from environment parameters [rsecssfs.c 3664]
4 ETW000                                                                                               11463  0.045451
4 ETW000  [     dev trc,00000]  RSecSSFs: Data file "/usr/sap/SMI/SYS/global/security/rsecssfs/data/SSFS_SMI.DAT" cannot be opened for read [rsecssfs.c 2198]
4 ETW000                                                                                                 174  0.045625
4 ETW000  [     dev trc,00000]  RSecSSFs: Exiting function "RSecSSFsGetRecord" with return code 1 (message: Data file "/usr/sap/SMI/SYS/global/security/rsecssfs/data/SS
FS_SMI.DAT" cannot be opened for read) [rsecssfs.c 897]
4 ETW000                                                                                                  91  0.045716
4 ETW000  [    dbcon.c ,00000]  *** ERROR => read_ssfs_record(): RSecSSFsGetRecord(DB_CONNECT/DEFAULT_DB_USER)=1 : Data file "/usr/sap/SMI/SYS/global/security/rsecssfs/
data/SSFS_SMI.DAT" cannot be opened for read
4 ETW000                                                                                                  82  0.045798
4 ETW000  [     dev trc,00000]  read_con_info_ssfs(): deregister callback ssfs_dbi_trace                  37  0.045835
4 ETW000  [    dbcon.c ,00000]  *** ERROR => Connect to R/3 failed. USERID and/or PASSWD could not be retrieved from SSFS
4 ETW000                                                                                                  68  0.045903
2EETW169 no connect possible: "DBMS = ORACLE                           --- dbs_ora_tnsname = 'SMI'"
trans.log: END
****************************************************************************************************************************************************
Kindly help us to fix the error ""ERROR => read_ssfs_record(): RSecSSFsGetRecord(DB_CONNECT/DEFAULT_DB_USER)=1 : Data file "/usr/sap/SMI/SYS/global/security/rsecssfs/
data/SSFS_SMI.DAT" cannot be opened for read"

Thanks and Regards,
Gayathri.K

[former_member188883]

Hi Gayathri,

R3trans will work only with sidadm user. Do not check this command with orasid user.

If R3trans -d is working fine with sidadm user then SAP system can communicate with ORacle database.

Now coming to your installation issue,

Plesae attach sapinst_dev.log

Hope this helps.

Regards,

Deepak Kori

[Former Member]

Hi Deepak,

Now my installation failed with "Start Java engine "phase as in the attached screenshot.

scs00 instance is failing and i am unable to start it.

---------------------

ERROR      2014-04-22 18:28:50.499 [sixxcstepexecute.cpp:899]

FCO-00011  The step startSCS with step key |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CI_Instance|ind|ind|ind|ind|ci|0|NW_CI_Instance_Configure_Java|ind|ind|ind|ind|javaconfig|0|startSCS was executed with status ERROR ( Last error reported by the step: Instance 'SCS00/solmantrg' of SAP system SMI is in an inconsistent state: the processes do not seem to have been started within the instance.).

----------------------------------

when i checked in getprocess list

-------------------------------------------------

solmantrg:smiadm 56> sapcontrol -prot NI_HTTP -nr 00 -function GetProcessList

22.04.2014 18:29:43

GetProcessList

OK

name, description, dispstatus, textstatus, starttime, elapsedtime, pid

msg_server, MessageServer, GRAY, Stopped, , , 13631618

enserver, EnqueueServer, GRAY, Stopped, , , 11468864

--------------------------------------------

have attached the screenshot and sapinst.dev.log

Please assist

regards,

gayathri.K

sapinst_dev_log.txt.zip

[former_member188883]

Hi Gayathri,

Looks like a problem with sapcontrol process. few times it has return code 4.

Could you share the log sapcontrol_SMI_SCS00.log

Additionally you may do the following

1) Stop the installer

2) Stop all the running sapinst processes

3) stop sapcontrol

4) start sapcontrol

5) start the installer again and check the results

Regards,

Deepak Kori

[Reagan]

Hello Gayathri

Stop the installer and then stop SAP.

Start the SAP manually and check if the ABAP and Java starts.

If there is a problem with the J2EE startup then provide the logs (dev_server, std_server) from the work directory.

Also try using a different or updated version of the kernel.

Regards

RB

[Former Member]

Hi Reagan,

I have stopped the installer and rebooted the Solman System. After Reboot, it worked.

Many Thanks for the Solution above.

Regards,

Gayathri.K