Error during Sap installation in step "Perform Or...

Hello,

I am getting below error during installation of SOLMAN 7.1 on Linux(x_86). Pl help on the same.

I tried stopping ,starting Oracle , tried cleaning memory and killing all oracle processes and also tried changing control.xml file as below..But all of no use

I changed at

/tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/control.xml

/tmp/sapinst_instdir/SOLMAN71SR1/LM/PRC/control.xml

var javaArgs = { classpath:["migmon.jar"], mainclass:"com.sap.inst.lib.app.SecureClient", jdkArgs:["-showversion", "-Xmx128m"] };

var javaArgs = { classpath:["migmon.jar"], mainclass:"com.sap.inst.lib.app.SecureClient", jdkArgs:["-showversion", "-Xmx1024m"] };

Error is as below:

FJS-00010 Could not get value for property .

INFO 2012-08-22 09:15:34.912 [sixxcstepexecute.cpp:1020]
An error occured and the user decided to retry the current step: "|NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CreateDBandLoad|ind|ind|ind|ind|createdbandload|0|NW_Postload|ind|ind|ind|ind|postload|0|NW_Postload_ORA|ind|ind|ind|ind|0|0|setOraclePasswords".

INFO       2012-08-22 09:15:34.945 [syuxccuren.cpp:562]
           CSyCurrentProcessEnvironmentImpl::setWorkingDirectory(const CSyPath& /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS)
           lib=syslib module=syslib
Working directory changed to /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS.

INFO       2012-08-22 09:15:37.178 [syuxcfile.cpp:273]
           CSyFileImpl::removeEx(ISyFSErrorHandler * pErrorHandler)
           lib=syslib module=syslib
Removed file /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/ora_query3_tmporasd10_1.sql.

INFO 2012-08-22 09:15:37.183 [syuxcpath.cpp:471]
CSyPath::createFile() lib=syslib module=syslib
Creating file /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/ora_query3_tmporasd10_1.sql.

ERROR 2012-08-22 09:15:40.512 [iaxxinscbk.cpp:268]
CJS-00084 SQL statement or script failed. DIAGNOSIS: Error message: ORA-01102: cannot mount database in EXCLUSIVE mode

Disconnected from Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options
. SOLUTION: For more information, see ora_sql_results.log and the Oracle documentation.

ERROR 2012-08-22 09:15:40.513
CJSlibModule::writeError_impl()
MUT-03025 Caught ESAPinstException in module call: .

ERROR 2012-08-22 09:15:40.749 [sixxcstepexecute.cpp:937]
FCO-00011 The step setOraclePasswords with step key |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CreateDBandLoad|ind|ind|ind|ind|createdbandload|0|NW_Postload|ind|ind|ind|ind|postload|0|NW_Postload_ORA|ind|ind|ind|ind|0|0|setOraclePasswords was executed with status ERROR ( Last error reported by the step: SQL statement or script failed. DIAGNOSIS: Error message: ORA-01102: cannot mount database in EXCLUSIVE mode

INFO 2012-08-22 09:15:41.680 [syuxcpath.cpp:471]
CSyPath::createFile() lib=syslib module=syslib
Creating file /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.xml.

WARNING 2012-08-22 09:15:46.276 [iaxxejshlp.cpp:150]
Could not get property IDs of the JavaScript object.

ERROR 2012-08-22 09:15:46.276 [iaxxejsctl.cpp:367]
FJS-00010 Could not get value for property .

You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.

Comment

Accepted Solutions (0)

Answers (2)

Can you check if the spfile has any issues . create a pfile from the spfile . Analyze the content .

Also check if there is any file sgadef<sid>.dbf under $ORACLE_HOME/dbs/

Delete it if there is any such file.

Then try restarting the oracle instance (if anything still running)

sql> startup force pfile=<path_to_the_file>

Regards

Ratnajit

You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.

Comment

Hi Ratnajit,

Thanks for your reply

There is no .dbf in $ORACLE_HOME/dbs directory

I tried doing the below:

I backed up current pfile (renamed that)..

Then i did as below as you suggested:

Connected to:
Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options

SQL> shutdown immediate;
Database closed.
Database dismounted.
ORACLE instance shut down.
SQL> startup nomount;
ORA-32004: obsolete or deprecated parameter(s) specified for RDBMS instance
ORACLE instance started.

Total System Global Area 2.3183E+10 bytes
Fixed Size                  2238776 bytes
Variable Size            1.1811E+10 bytes
Database Buffers         1.1341E+10 bytes
Redo Buffers               28606464 bytes

SQL> create pfile from spfile='/oracle/SD1/11203/dbs/spfileSD1.ora';

File created.

After this i again ran sapinst and below error is coming (Also please note i had changed control.xml as mentioned in my first post above)

INFO 2012-08-22 16:33:26.846 [iaxxclib.cpp:114]
CChangeCWD::~CChangeCWD()
Working directory changed to /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS.

ERROR 2012-08-22 16:33:34.666 [iaxxinscbk.cpp:268]
CJS-00084 SQL statement or script failed. DIAGNOSIS: Error message: ORA-01102: cannot mount database in EXCLUSIVE mode

Disconnected from Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options
. SOLUTION: For more information, see ora_sql_results.log and the Oracle documentation.

ERROR 2012-08-22 16:33:34.668
CJSlibModule::writeError_impl()
MUT-03025 Caught ESAPinstException in module call: .

ERROR 2012-08-22 16:33:34.959 [sixxcstepexecute.cpp:937]
FCO-00011 The step setOraclePasswords with step key |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CreateDBandLoad|ind|ind|ind|ind|createdbandload|0|NW_Postload|ind|ind|ind|ind|postload|0|NW_Postload_ORA|ind|ind|ind|ind|0|0|setOraclePasswords was executed with status ERROR ( Last error reported by the step: SQL statement or script failed. DIAGNOSIS: Error message: ORA-01102: cannot mount database in EXCLUSIVE mode

INFO 2012-08-22 16:33:35.833 [syuxcpath.cpp:471]
CSyPath::createFile() lib=syslib module=syslib
Creating file /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.xml.

WARNING 2012-08-22 16:33:40.386 [iaxxejshlp.cpp:150]
Could not get property IDs of the JavaScript object.

ERROR 2012-08-22 16:33:40.386 [iaxxejsctl.cpp:367]
FJS-00010 Could not get value for property .

Also output of ora_sql_results.log as below

SAPINST: End of output of SQL executing program /oracle/SD1/112_64/bin/sqlplus.

2012-08-22, 16:33:31 SAPINST ORACLE stop logging

================================================================================
2012-08-22, 16:33:31 SAPINST ORACLE start logging for

STARTUP;

exit;

Output of SQL executing program:

SQL*Plus: Release 11.2.0.3.0 Production on Wed Aug 22 16:33:31 2012

Connected to an idle instance.

ORA-32004: obsolete or deprecated parameter(s) specified for RDBMS instance
ORACLE instance started.

Total System Global Area 2.3183E+10 bytes
Fixed Size                  2238776 bytes
Variable Size            1.1811E+10 bytes
Database Buffers         1.1341E+10 bytes
Redo Buffers               28606464 bytes
ORA-01102: cannot mount database in EXCLUSIVE mode

Disconnected from Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options

SAPINST: End of output of SQL executing program /oracle/SD1/112_64/bin/sqlplus.

SAPINST found errors.
SAPINST The current process environment may be found in sapinst_ora_environment.log.

2012-08-22, 16:33:34 SAPINST ORACLE stop logging

Regards

Vishal

Can you try mounting the database manually ?

SQL> startup force mount

Then check in the alert log file if you find issues .

Also I suggest you reboot the server once and then retry starting the sapinst. It might be such that your shared memory segments are still not flushed.

Regards

Ratnajit

Please show the output from sysresv command as ora<sid> user

Regards

Roman

Hi,

The output is as below:

orasd1 42> sysresv

IPC Resources for ORACLE_SID "SD1" :
Shared Memory
ID KEY
No shared memory segments used
Semaphores:
ID KEY
No semaphore resources used
Oracle Instance not alive for sid "SD1"

Also i tried running again sapinst but sometimes i get a different error as below:

Output of /usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX is written to the logfile brconnect.log.

WARNING 2012-08-23 09:40:16.017
CJSlibModule::writeWarning_impl()
Execution of the command "/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX" finished with return code 3. Output:
BR0801I BRCONNECT 7.20 (20)

BR0613E Database instance SD1 is shut down

BR0280I BRCONNECT time stamp: 2012-08-23 09.40.15
BR0804I BRCONNECT terminated with errors

ERROR 2012-08-23 09:40:16.028
CJSlibModule::writeError_impl()
CJS-30023 Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.

ERROR 2012-08-23 09:40:16.373 [sixxcstepexecute.cpp:937]
FCO-00011 The step setOraclePasswords with step key |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CreateDBandLoad|ind|ind|ind|ind|createdbandload|0|NW_Postload|ind|ind|ind|ind|postload|0|NW_Postload_ORA|ind|ind|ind|ind|0|0|setOraclePasswords was executed with status ERROR ( Last error reported by the step: Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.).

INFO 2012-08-23 09:40:17.224 [syuxcpath.cpp:471]
CSyPath::createFile() lib=syslib module=syslib
Creating file /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.xml.

WARNING 2012-08-23 09:40:21.895 [iaxxejshlp.cpp:150]
Could not get property IDs of the JavaScript object.

ERROR 2012-08-23 09:40:21.895 [iaxxejsctl.cpp:367]
FJS-00010 Could not get value for property .

It seems the database instance SD1 is down.

Please bring up the database instance .

SQL> startup force

Regards

Ratnajit

Please paste the output of

ps -ef | grep ora_ | grep $ORACLE_SID

command

Regards

Roman

Hi,

I tried starting after killing all ora processes and cleaning memory but still the error is there.

I have tried almost all probablities but I wonder what could be the cause

The o/p is as below:

orasd1 16052 1 0 09:55 ? 00:00:00 ora_w000_SD1

orasd1 20807 1 0 09:39 ? 00:00:00 ora_pmon_SD1

orasd1 20809 1 0 09:39 ? 00:00:00 ora_psp0_SD1

orasd1 20811 1 0 09:39 ? 00:00:06 ora_vktm_SD1

orasd1 20815 1 0 09:39 ? 00:00:00 ora_gen0_SD1

orasd1 20817 1 0 09:39 ? 00:00:00 ora_diag_SD1

orasd1 20819 1 0 09:39 ? 00:00:00 ora_dbrm_SD1

orasd1 20821 1 0 09:39 ? 00:00:01 ora_dia0_SD1

orasd1 20823 1 0 09:39 ? 00:00:01 ora_mman_SD1

orasd1 20825 1 0 09:39 ? 00:00:00 ora_dbw0_SD1

orasd1 20827 1 0 09:39 ? 00:00:00 ora_dbw1_SD1

orasd1 20829 1 0 09:39 ? 00:00:00 ora_dbw2_SD1

orasd1 20831 1 0 09:39 ? 00:00:00 ora_dbw3_SD1

orasd1 20833 1 0 09:39 ? 00:00:00 ora_lgwr_SD1

orasd1 20835 1 0 09:39 ? 00:00:00 ora_ckpt_SD1

orasd1 20837 1 0 09:39 ? 00:00:00 ora_smon_SD1

orasd1 20839 1 0 09:39 ? 00:00:00 ora_reco_SD1

orasd1 20841 1 0 09:39 ? 00:00:00 ora_mmon_SD1

orasd1 20843 1 0 09:39 ? 00:00:01 ora_mmnl_SD1

orasd1 21781 1 0 09:40 ? 00:00:00 ora_arc0_SD1

orasd1 21783 1 0 09:40 ? 00:00:00 ora_arc1_SD1

orasd1 21785 1 0 09:40 ? 00:00:00 ora_arc2_SD1

orasd1 21787 1 0 09:40 ? 00:00:00 ora_arc3_SD1

orasd1 21805 1 0 09:40 ? 00:00:00 ora_qmnc_SD1

orasd1 21970 1 0 09:40 ? 00:00:00 ora_q000_SD1

orasd1 21972 1 0 09:40 ? 00:00:00 ora_q001_SD1

orasd1 30076 1 0 09:45 ? 00:00:00 ora_smco_SD1

Have you tried to startup oracle? Please clear all resources (processes and memory) before continuing.

Alse paste the output from following command

ls $ORACLE_HOME/dbs

Regards

Roman

Hi Roman,

Yes I tried starting up oracle but oracle starts only when all the ora processes are killed after shutdown.

But when i startup oracle , and run with 'Retry' option in sapinst ..then also sapisnt fails.

I tried other way also by stopping oracle , cleaning processes and memory and then again running sapinst via another session but still it fails.

ls $ORACLE_HOME/dbs

hc_SD1.dat initSD1.1.ora initSD1.ora initSD1.ora.SAPINST-ORIG lkSD1 spfileSD1.ora

init.ora initSD1.2.ora initSD1.ora_bak initSD1.sap orapwSD1

Please note that i had created a new pfile from existing spfile. Also i had moved lkSD1 file earlier to check if it will help.

And i have made some changes in control.xml file at two location present (Pl see first post by me)

Kindly suggest further now and Thanks a lot to you all for your help guys.

Regards

Vishal Bhat

Did you check the oracle alert log file ?

Regards

Ratnajit

Hi Ratnajit,

Thanks for ur reply:

Alert log as of now:

Thu Aug 23 09:52:10 2012

PMON started with pid=2, OS id=10558

Thu Aug 23 09:52:10 2012

PSP0 started with pid=3, OS id=10560

Thu Aug 23 09:52:11 2012

VKTM started with pid=4, OS id=10562 at elevated priority

VKTM running at (1)millisec precision with DBRM quantum (100)ms

Thu Aug 23 09:52:11 2012

GEN0 started with pid=5, OS id=10566

Thu Aug 23 09:52:11 2012

DIAG started with pid=6, OS id=10568

Thu Aug 23 09:52:11 2012

DBRM started with pid=7, OS id=10570

Thu Aug 23 09:52:11 2012

DIA0 started with pid=8, OS id=10572

Thu Aug 23 09:52:11 2012

MMAN started with pid=9, OS id=10574

Thu Aug 23 09:52:11 2012

DBW0 started with pid=10, OS id=10576

Thu Aug 23 09:52:11 2012

DBW1 started with pid=11, OS id=10578

Thu Aug 23 09:52:11 2012

DBW2 started with pid=12, OS id=10580

Thu Aug 23 09:52:11 2012

DBW3 started with pid=13, OS id=10582

Thu Aug 23 09:52:11 2012

LGWR started with pid=14, OS id=10584

Thu Aug 23 09:52:11 2012

CKPT started with pid=15, OS id=10586

Thu Aug 23 09:52:11 2012

SMON started with pid=16, OS id=10590

Thu Aug 23 09:52:11 2012

RECO started with pid=17, OS id=10602

Thu Aug 23 09:52:11 2012

MMON started with pid=18, OS id=10620

Thu Aug 23 09:52:12 2012

MMNL started with pid=19, OS id=10640

ORACLE_BASE from environment = /oracle/SD1

Thu Aug 23 09:52:12 2012

ALTER DATABASE MOUNT

sculkget: failed to lock /oracle/SD1/11203/dbs/lkSD1 exclusive

sculkget: lock held by PID: 20825

ORA-09968: unable to lock file

Linux-x86_64 Error: 11: Resource temporarily unavailable

Additional information: 20825

ORA-1102 signalled during: ALTER DATABASE MOUNT...

Thu Aug 23 09:52:49 2012

License high water mark = 1

USER (ospid: 18296): terminating the instance

Instance terminated by USER, pid = 18296

Thu Aug 23 10:10:13 2012

Incremental checkpoint up to RBA [0xe4.80c.0], current log tail at RBA [0xe4.9ac.0]

Thu Aug 23 10:40:19 2012

Incremental checkpoint up to RBA [0xe4.9f4.0], current log tail at RBA [0xe4.9f4.0]

Thu Aug 23 11:10:22 2012

Incremental checkpoint up to RBA [0xe4.1342.0], current log tail at RBA [0xe4.1342.0]

Thu Aug 23 11:40:27 2012

Incremental checkpoint up to RBA [0xe4.138d.0], current log tail at RBA [0xe4.1394.0]

HI,

Now when i open the DB after cleaning processes iot opens with startup

Now as soon as i try to retry via sapinst (clicking 'retrt') it again ends up in error..

Below is now what alert log is saying: (I don't understand why its not picking up ORACLE_BASE which is set already)

SMON started with pid=16, OS id=4704

Thu Aug 23 12:12:43 2012

RECO started with pid=17, OS id=4707

Thu Aug 23 12:12:43 2012

MMON started with pid=18, OS id=4709

Thu Aug 23 12:12:43 2012

MMNL started with pid=19, OS id=4711

ORACLE_BASE not set in environment. It is recommended

that ORACLE_BASE be set in the environment

Reusing ORACLE_BASE from an earlier startup = /oracle/SD1

Thu Aug 23 12:12:44 2012

ALTER DATABASE MOUNT

sculkget: failed to lock /oracle/SD1/112_64/dbs/lkSD1 exclusive

sculkget: lock held by PID: 1494

ORA-09968: unable to lock file

Linux-x86_64 Error: 11: Resource temporarily unavailable

Additional information: 1494

ORA-1102 signalled during: ALTER DATABASE MOUNT...

Stop the oracle instance.

kill the process ID 1494

kill -9 1494

Then remove the file

# rm /oracle/SD1/112_64/dbs/lkSD1

Then try starting Oracle.

Regards

Ratnajit

Hi,

After doing above the DB is opened and when i again start the sapinst from the erro i get the below erro:

An error occurred while processing option SAP Solution Manager 7.1 SR1 > SAP Systems > Oracle > Central System > Central System( Last error reported by the step: Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.)

Also as i see oracle becomes Idle now after running sapinst and stopping in above error

Shutdown the database .

SQL> shutdown immediate

Then retry with SAPINST. Let SAPINST open the database.

Can you paste the brconnect.log . Typically this should not happen, but lets see the log. Also check the alert log file.

Regards

Ratnajit

Hi Ranjit,

I did the shutdown immediate and then ran sapinst.

1. Brconnect.log:

cat brconnect.log
BR0801I BRCONNECT 7.20 (20)

BR0613E Database instance SD1 is shut down

BR0280I BRCONNECT time stamp: 2012-08-23 14.01.17
BR0804I BRCONNECT terminated with errors

2. Below is the log from alert log:

RECO started with pid=17, OS id=23580

Thu Aug 23 13:11:58 2012

MMON started with pid=18, OS id=23582

Thu Aug 23 13:11:58 2012

MMNL started with pid=19, OS id=23584

ORACLE_BASE from environment = /oracle/SD1

Thu Aug 23 13:11:58 2012

ALTER DATABASE MOUNT

sculkget: failed to lock /oracle/SD1/11203/dbs/lkSD1 exclusive

sculkget: lock held by PID: 21516

ORA-09968: unable to lock file

Linux-x86_64 Error: 11: Resource temporarily unavailable

Additional information: 21516

ORA-1102 signalled during: ALTER DATABASE MOUNT...

Thu Aug 23 13:16:15 2012

Starting background process SMCO

Thu Aug 23 13:16:15 2012

SMCO started with pid=58, OS id=30884

Thu Aug 23 13:41:18 2012

Incremental checkpoint up to RBA [0xea.923.0], current log tail at RBA [0xea.936.0]

Thu Aug 23 14:00:57 2012

Shutting down instance (immediate)

Shutting down instance: further logons disabled

Stopping background process MMNL

Stopping background process MMON

License high water mark = 3

ALTER DATABASE CLOSE NORMAL

ORA-1507 signalled during: ALTER DATABASE CLOSE NORMAL...

ARCH: Archival disabled due to shutdown: 1089

Shutting down archive processes

Archiving is disabled

ARCH: Archival disabled due to shutdown: 1089

Shutting down archive processes

Thu Aug 23 14:01:02 2012

Stopping background process VKTM

Archiving is disabled

Thu Aug 23 14:01:06 2012

Instance shutdown complete

3. Below is the sapinst log

Output of /usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX is written to the logfile brconnect.log.

WARNING 2012-08-23 14:01:17.897
CJSlibModule::writeWarning_impl()
Execution of the command "/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX" finished with return code 3. Output:
BR0801I BRCONNECT 7.20 (20)

BR0613E Database instance SD1 is shut down

BR0280I BRCONNECT time stamp: 2012-08-23 14.01.17
BR0804I BRCONNECT terminated with errors

ERROR 2012-08-23 14:01:17.907
CJSlibModule::writeError_impl()
CJS-30023 Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.

ERROR 2012-08-23 14:01:18.231 [sixxcstepexecute.cpp:937]
FCO-00011 The step setOraclePasswords with step key |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CreateDBandLoad|ind|ind|ind|ind|createdbandload|0|NW_Postload|ind|ind|ind|ind|postload|0|NW_Postload_ORA|ind|ind|ind|ind|0|0|setOraclePasswords was executed with status ERROR ( Last error reported by the step: Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.).

Please try the folowing:

1) try to shutdown oracle from sqlplus

SQL> shutdown immediate

2) check oracle processes with ps command and if exists kill them

ps -ef | grep ora_ | grep $ORACLE_SID

kill -9 <pid>

3) clear shared memory and semaphores for oracle user if exists

ipcs -m|-s

ipcrm -m <shmid>

ipcrm -s <semid>

4) remove sgadef.dbf and lk<SID> files in $ORACLE_HOME/dbs

rm $ORACLE_HOME/dbs/sgadef.dbf

rm $ORACLE_HOME/dbs/lk<SID>

5) retry with SAPINST

Regards

Roman

Hi Roman,

I already had done the above.. again i tried the above process as suggested by you but i gert the same error (see below)in sapinst when i run it again after cleaning the processes and everything:

Thanks for your advice

Can you paste the last 200 lines of the sapinst_dev.log.

remove the file /oracle/SD1/11203/dbs/lkSD1

reboot the OS .

Then try running sapinst .

Regards

Ratnajit

Hi Ratnajit,

PFB the last 200 lines of sapinst_dev.log.... I have already tried running sapinst both ways by removing lkSD1 file and also with DB as down and DB as up but fails againa and again.

However below log might help you to suggest me further. Thanks for your help.

Regarding reboot of server I will have to check with Linux team in mean time (and does reboot really help in such cases ?)

FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:53.834
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).start(false)

TRACE      2012-08-23 15:52:53.847 [syuxcuser.cpp:701]
           PSyGroupImpl CSyUserImpl::getPrimaryGroup()const
           lib=syslib module=syslib
account user="orasd1" uid="502" exists with parameter gid="503"

TRACE      2012-08-23 15:52:53.858 [syuxcuser.cpp:701]
           PSyGroupImpl CSyUserImpl::getPrimaryGroup()const
           lib=syslib module=syslib
account user="orasd1" uid="502" exists with parameter gid="503"

TRACE      2012-08-23 15:52:53.869 [syuxcuser.cpp:701]
           PSyGroupImpl CSyUserImpl::getPrimaryGroup()const
           lib=syslib module=syslib
account user="orasd1" uid="502" exists with parameter gid="503"

TRACE      2012-08-23 15:52:53.880 [syuxcuser.cpp:701]
           PSyGroupImpl CSyUserImpl::getPrimaryGroup()const
           lib=syslib module=syslib
account user="orasd1" uid="502" exists with parameter gid="503"

TRACE 2012-08-23 15:52:53.912 [syuxctask.cpp:276]
CChildApplicationImpl::doStart() lib=syslib module=syslib
A child process has been started. Pid = 18875

TRACE 2012-08-23 15:52:53.920
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:53.921
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).start() done: true

TRACE 2012-08-23 15:52:53.923
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

INFO 2012-08-23 15:52:53.924
CJSlibModule::writeInfo_impl()
Output of /usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX is written to the logfile brconnect.log.

TRACE 2012-08-23 15:52:54.098
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:54.098
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).getReturnCode(true)

TRACE      2012-08-23 15:52:54.99 [syuxctask.cpp:886]
           CUnixProcessManager::sap_waitpid(pid_t pid, int * pStatus, int options)
           lib=syslib module=syslib
Child process 18875 returned 3

TRACE 2012-08-23 15:52:54.101
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:54.102
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).getReturnCode() done: 3

TRACE 2012-08-23 15:52:54.104
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:54.105
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).getStatus()

TRACE 2012-08-23 15:52:54.108
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:54.108
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).getStatus() done: TST_FINISHED

TRACE 2012-08-23 15:52:54.111
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

WARNING 2012-08-23 15:52:54.112
CJSlibModule::writeWarning_impl()
Execution of the command "/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX" finished with return code 3. Output:
BR0801I BRCONNECT 7.20 (20)

BR0613E Database instance SD1 is shut down

BR0280I BRCONNECT time stamp: 2012-08-23 15.52.54
BR0804I BRCONNECT terminated with errors

TRACE 2012-08-23 15:52:54.114
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:54.115
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).run(): 3. done.

TRACE 2012-08-23 15:52:54.117
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:54.118
ChildApplication(/usr/sap/SD1/SYS/exe/run/brconnect).runAs() done

TRACE 2012-08-23 15:52:54.120
FSPath(/usr/sap/SD1/SYS/exe/run/brconnect) done

TRACE 2012-08-23 15:52:54.121
Function setMessageIdOfExceptionMessage: nw.processError

ERROR 2012-08-23 15:52:54.122
CJSlibModule::writeError_impl()
CJS-30023 Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.

TRACE 2012-08-23 15:52:54.122 [iaxxejsbas.hpp:503]
EJS_Base::dispatchFunctionCall()
JS Callback has thrown unknown exception. Rethrowing.

TRACE 2012-08-23 15:52:54.184 [syuxctask.cpp:276]
CChildApplicationImpl::doStart() lib=syslib module=syslib
A child process has been started. Pid = 18879

TRACE      2012-08-23 15:52:54.233 [syuxctask.cpp:886]
           CUnixProcessManager::sap_waitpid(pid_t pid, int * pStatus, int options)
           lib=syslib module=syslib
Child process 18879 returned 0

TRACE 2012-08-23 15:52:54.294 [syuxctask.cpp:276]
CChildApplicationImpl::doStart() lib=syslib module=syslib
A child process has been started. Pid = 18880

TRACE      2012-08-23 15:52:54.346 [syuxctask.cpp:886]
           CUnixProcessManager::sap_waitpid(pid_t pid, int * pStatus, int options)
           lib=syslib module=syslib
Child process 18880 returned 0

ERROR 2012-08-23 15:52:54.445 [sixxcstepexecute.cpp:937]
FCO-00011 The step setOraclePasswords with step key |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CreateDBandLoad|ind|ind|ind|ind|createdbandload|0|NW_Postload|ind|ind|ind|ind|postload|0|NW_Postload_ORA|ind|ind|ind|ind|0|0|setOraclePasswords was executed with status ERROR ( Last error reported by the step: Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.).

TRACE 2012-08-23 15:52:54.576 [kdxxctaco.cpp:225]
CKdbTableContainerImpl::syncToContainerFile lib=iakdblib
CKdbTableContainerImpl::syncToContainerFile start ...

TRACE 2012-08-23 15:52:55.120 [kdxxctaco.cpp:257]
CKdbTableContainerImpl::syncToContainerFile lib=iakdblib
after creating out stream

TRACE 2012-08-23 15:52:55.291 [kdxxctaco.cpp:291]
CKdbTableContainerImpl::syncToContainerFile lib=iakdblib
CKdbTableContainerImpl::syncToContainerFile stop ...

TRACE      2012-08-23 15:52:55.336 [syxxcfile.cpp:123]
           CSyFileImpl::decideIfMoveCopyNode(const CopyMoveDestinationInfo & {m_nodeTypeForCombiCheck: ..., m_path: /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.57.xml, m_realNodeType: 8}, ISyNode::CopyMoveMode_t 0x3, PSyNodeInt &) const
           lib=syslib module=syslib
Target node does not exist and (mode & ISyNode::MISSING) ==> I will copy/move.

TRACE      2012-08-23 15:52:55.337 [syxxcnode.cpp:454]
           CSyNodeImpl::move(const CSyPath & /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.57.xml, ISyNode::CopyMoveMode_t 0x3)
           lib=syslib module=syslib
Moved /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.xml to /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.57.xml

INFO 2012-08-23 15:52:55.339 [syuxcpath.cpp:471]
CSyPath::createFile() lib=syslib module=syslib
Creating file /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.xml.

TRACE 2012-08-23 15:52:55.369 [syuxctask.cpp:276]
CChildApplicationImpl::doStart() lib=syslib module=syslib
A child process has been started. Pid = 18881

TRACE      2012-08-23 15:52:55.418 [syuxctask.cpp:886]
           CUnixProcessManager::sap_waitpid(pid_t pid, int * pStatus, int options)
           lib=syslib module=syslib
Child process 18881 returned 0

TRACE 2012-08-23 15:52:55.481 [syuxctask.cpp:276]
CChildApplicationImpl::doStart() lib=syslib module=syslib
A child process has been started. Pid = 18882

TRACE      2012-08-23 15:52:55.532 [syuxctask.cpp:886]
           CUnixProcessManager::sap_waitpid(pid_t pid, int * pStatus, int options)
           lib=syslib module=syslib
Child process 18882 returned 0

TRACE 2012-08-23 15:52:59.741
SAPInst calls the LogInquirer. The output of the LogInquirer can be found in file sapinst_loginquirer.log.

TRACE 2012-08-23 15:53:00.72 [iaxxgenimp.cpp:699]
CGuiEngineImp::showMessageBox

<html> <head> </head> <body> An error occurred while processing option SAP Solution Manager 7.1 SR1 > SAP Systems > Oracle > Central System > Central System( Last error reported by the step: Process call '/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -f chpass -o SAPSR3 -p XXXXXX' exits with error code 3. For details see log file(s) brconnect.log.). You can now: <ul> <li> Choose Retry to repeat the current step. </li> <li> Choose Log Files to get more information about the error. </li> <li> Stop the option and continue with it later. </li> </ul> Log files are written to <code>/tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS</code>. </body></html>

TRACE 2012-08-23 15:53:00.72 [iaxxgenimp.cpp:1248]
CGuiEngineImp::acceptAnswerForBlockingRequest
Waiting for an answer from GUI

TRACE 2012-08-24 06:52:07.667 [iaxxgenimp.cpp:1248]
CGuiEngineImp::acceptAnswerForBlockingRequest
Waiting for an answer from GUI

Regards

Vishal Bhat

Hi,

I tried to run sapinst again after rebooting the server also but the same error.

Can anyone suggest further please ?

Thanks

Vishal Bhat

Please paste content of brconnect.log file

Regards

Roman

Hi Roman,

Thanks for your mail.

Its same as earlier:

/tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS
el1165:orasd1 77> cat brconnect.log
BR0801I BRCONNECT 7.20 (20)

BR0613E Database instance SD1 is shut down

BR0280I BRCONNECT time stamp: 2012-08-24 12.41.42
BR0804I BRCONNECT terminated with errors

Regards

Vishal Bhat

Ok. Run sqlplus / as sysdba and

SQL> shutdown immediate

and paste output

Regards

Roman

Hi,

Ouput is as below:

SQL> shutdown immediate;

Database closed.

Database dismounted.

ORACLE instance shut down.

SQL>

Also alert log last few lines :

Incremental checkpoint up to RBA [0xee.35db0.0], current log tail at RBA [0xee.35dc6.0]
Mon Aug 27 08:52:15 2012
Incremental checkpoint up to RBA [0xee.35e0a.0], current log tail at RBA [0xee.35e0f.0]
Mon Aug 27 09:22:17 2012
Incremental checkpoint up to RBA [0xee.3690d.0], current log tail at RBA [0xee.36916.0]
Mon Aug 27 09:36:34 2012
Shutting down instance (immediate)
Stopping background process SMCO
Shutting down instance: further logons disabled
Stopping background process QMNC
Stopping background process MMNL
Stopping background process MMON
License high water mark = 32
ALTER DATABASE CLOSE NORMAL
Mon Aug 27 09:36:38 2012
SMON: disabling tx recovery
SMON: disabling cache recovery
Mon Aug 27 09:36:39 2012
Shutting down archive processes
Archiving is disabled
Mon Aug 27 09:36:39 2012
ARCH shutting down
Mon Aug 27 09:36:39 2012
ARCH shutting down
Mon Aug 27 09:36:39 2012
ARCH shutting downMon Aug 27 09:36:39 2012

ARCH shutting down
ARC3: Archival stopped
ARC2: Archival stopped
ARC0: Archival stopped
ARC1: Archival stopped
Thread 1 closed at log sequence 238
Successful close of redo thread 1
Completed: ALTER DATABASE CLOSE NORMAL
ALTER DATABASE DISMOUNT
Shutting down archive processes
Archiving is disabled
Completed: ALTER DATABASE DISMOUNT
ARCH: Archival disabled due to shutdown: 1089
Shutting down archive processes
Archiving is disabled
ARCH: Archival disabled due to shutdown: 1089
Mon Aug 27 09:36:41 2012
Stopping background process VKTM
Shutting down archive processes
Archiving is disabled
Mon Aug 27 09:36:44 2012
Instance shutdown complete

SQL> startup

and paste output

Regards

Roman

Hi,

SQL> startup;
ORA-32004: obsolete or deprecated parameter(s) specified for RDBMS instance
ORACLE instance started.

Total System Global Area 2.3183E+10 bytes
Fixed Size                  2238776 bytes
Variable Size            1.1811E+10 bytes
Database Buffers         1.1341E+10 bytes
Redo Buffers               28606464 bytes
Database mounted.
Database opened.
SQL>

Alert log:

Mon Aug 27 09:44:31 2012
Starting ORACLE instance (normal)
****************** Large Pages Information *****************

Total Shared Global Region in Large Pages = 0 KB (0%)

Large Pages used by this instance: 0 (0 KB)
Large Pages unused system wide = 0 (0 KB) (alloc incr 64 MB)
Large Pages configured system wide = 0 (0 KB)
Large Page size = 2048 KB

RECOMMENDATION:
Total Shared Global Region size is 22 GB. For optimal performance,
prior to the next instance restart increase the number
of unused Large Pages by atleast 11105 2048 KB Large Pages (22 GB)
system wide to get 100% of the Shared
Global Region allocated with Large pages
***********************************************************
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
Picked latch-free SCN scheme 3
Autotune of undo retention is turned on.
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Starting up:
Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options.
ORACLE_HOME = /oracle/SD1/11203
System name:    Linux
Node name:      el1165
Release:        2.6.32-220.el6.x86_64
Version:        #1 SMP Wed Nov 9 08:03:13 EST 2011
Machine:        x86_64
Using parameter settings in server-side spfile /oracle/SD1/11203/dbs/spfileSD1.ora
System parameters with non-default values:
processes                = 430
sessions                 = 864
shared_pool_size         = 10816M
filesystemio_options     = "setall"
control_files            = "/oracle/SD1/origlogA/cntrl/cntrlSD1.dbf"
control_files            = "/oracle/SD1/origlogB/cntrl/cntrlSD1.dbf"
control_files            = "/oracle/SD1/sapdata1/cntrl/cntrlSD1.dbf"
control_file_record_keep_time= 30
db_block_size            = 8192
db_cache_size            = 10816M
compatible               = "11.2.0"
log_archive_dest_1       = "LOCATION=/oracle/SD1/oraarch/SD1arch"
log_archive_format       = "%t_%s_%r.dbf"
db_recovery_file_dest    = "/oracle/SD1/oraflash"
db_recovery_file_dest_size= 30000M
log_checkpoints_to_alert = TRUE
replication_dependency_tracking= FALSE
undo_tablespace          = "PSAPUNDO"
_in_memory_undo          = FALSE
recyclebin               = "off"
remote_os_authent        = TRUE
remote_login_passwordfile= "EXCLUSIVE"
parallel_execution_message_size= 16384
_table_lookup_prefetch_size= 0
audit_file_dest          = "/oracle/SD1/saptrace/audit"
db_name                  = "SD1"
open_cursors             = 2000
_sort_elimination_cost_ratio= 10
_b_tree_bitmap_plans     = FALSE
star_transformation_enabled= "true"
parallel_threads_per_cpu = 1
query_rewrite_enabled    = "false"
_index_join_enabled      = FALSE
_optim_peek_user_binds   = FALSE
pga_aggregate_target     = 14400M
_optimizer_mjc_enabled   = FALSE
diagnostic_dest          = "/oracle/SD1/saptrace"
max_dump_file_size       = "20000"
Deprecated system parameters with specified values:
remote_os_authent
End of deprecated system parameter listing
Mon Aug 27 09:44:32 2012
PMON started with pid=2, OS id=6285
Mon Aug 27 09:44:32 2012
PSP0 started with pid=3, OS id=6287
Mon Aug 27 09:44:33 2012
VKTM started with pid=4, OS id=6291 at elevated priority
VKTM running at (1)millisec precision with DBRM quantum (100)ms
Mon Aug 27 09:44:33 2012
GEN0 started with pid=5, OS id=6295
Mon Aug 27 09:44:33 2012
DIAG started with pid=6, OS id=6297
Mon Aug 27 09:44:33 2012
DBRM started with pid=7, OS id=6299
Mon Aug 27 09:44:33 2012
DIA0 started with pid=8, OS id=6301
Mon Aug 27 09:44:33 2012
MMAN started with pid=9, OS id=6303
Mon Aug 27 09:44:33 2012
DBW0 started with pid=10, OS id=6305
Mon Aug 27 09:44:33 2012
DBW1 started with pid=11, OS id=6307
Mon Aug 27 09:44:34 2012
DBW2 started with pid=12, OS id=6309
Mon Aug 27 09:44:34 2012
DBW3 started with pid=13, OS id=6311
Mon Aug 27 09:44:34 2012
LGWR started with pid=14, OS id=6313
Mon Aug 27 09:44:34 2012
CKPT started with pid=15, OS id=6315
Mon Aug 27 09:44:34 2012
SMON started with pid=16, OS id=6317
Mon Aug 27 09:44:34 2012
RECO started with pid=17, OS id=6319
Mon Aug 27 09:44:34 2012
MMON started with pid=18, OS id=6321
Mon Aug 27 09:44:34 2012
MMNL started with pid=19, OS id=6323
ORACLE_BASE from environment = /oracle/SD1
Mon Aug 27 09:44:34 2012
ALTER DATABASE   MOUNT
Successful mount of redo thread 1, with mount id 368331586
Database mounted in Exclusive Mode
Lost write protection disabled
Completed: ALTER DATABASE   MOUNT
Mon Aug 27 09:44:38 2012
ALTER DATABASE OPEN
LGWR: STARTING ARCH PROCESSES
Mon Aug 27 09:44:39 2012
ARC0 started with pid=21, OS id=6334
ARC0: Archival started
LGWR: STARTING ARCH PROCESSES COMPLETE
ARC0: STARTING ARCH PROCESSES
Mon Aug 27 09:44:40 2012
ARC1 started with pid=22, OS id=6336
Mon Aug 27 09:44:40 2012
ARC2 started with pid=23, OS id=6338
Mon Aug 27 09:44:40 2012
ARC3 started with pid=24, OS id=6340
ARC1: Archival started
ARC2: Archival started
ARC1: Becoming the 'no FAL' ARCH
ARC1: Becoming the 'no SRL' ARCH
ARC2: Becoming the heartbeat ARCH
Thread 1 opened at log sequence 238
Current log# 2 seq# 238 mem# 0: /oracle/SD1/origlogB/log_g12m1.dbf
Current log# 2 seq# 238 mem# 1: /oracle/SD1/mirrlogB/log_g12m2.dbf
Successful open of redo thread 1
MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
SMON: enabling cache recovery
ARC3: Archival started
ARC0: STARTING ARCH PROCESSES COMPLETE
[6332] Successfully onlined Undo Tablespace 2.
Undo initialization finished serial:0 start:261506024 end:261506524 diff:500 (5 seconds)
Verifying file header compatibility for 11g tablespace encryption..
Verifying 11g file header compatibility for tablespace encryption completed
SMON: enabling tx recovery
Database Characterset is UTF8
No Resource Manager plan active
Starting background process QMNC
Mon Aug 27 09:44:42 2012
QMNC started with pid=25, OS id=6444
Incremental checkpoint up to RBA [0xee.36968.0], current log tail at RBA [0xee.3699a.0]
Completed: ALTER DATABASE OPEN
Mon Aug 27 09:44:46 2012
db_recovery_file_dest_size of 30000 MB is 0.00% used. This is a
user-specified limit on the amount of space that will be used by this
database for recovery-related files, and does not reflect the amount of
space available in the underlying filesystem or ASM diskgroup.

Ok. Run R3trans -d and paste output.

Regards

Roman

The o/p is as below: (Note: DB is open currently, Listener also i started)

el1165:sd1adm 5> R3trans -d

This is R3trans version 6.22 (release 720 - 26.10.11 - 13:00:00).

unicode enabled version

R3trans finished (0000).

el1165:sd1adm 6>

Trans.log

4 ETW000 38 0.018671

4 ETW000 [ dev trc,00000] # Patch Description: Created on 3 Jan 2012, 02:50:27 hrs PST8PDT 50 0.018721

4 ETW000 [ dev trc,00000] # Patch 11742262 : Created on 7 Mar 2012, 12:22:41 hrs CET 73 0.018794

4 ETW000 [ dev trc,00000] Default connection: tnsname ='SD1' 40 0.018834

4 ETW000 [ dev trc,00000] -->oci_initialize(con=0, char='UTF16', nchar='AL16UTF16', nls=-1),uc_ln=2

4 ETW000 50 0.018884

4 ETW000 [ dev trc,00000] OCIEnvCreate(mode=16384=OCI_UTF16) returned 0 (for default NLS handle)

4 ETW000 13557 0.032441

4 ETW000 [ dev trc,00000] OCIHandleAlloc(OCI_HTYPE_ERROR, con=0, ErrHp=3872378) 72 0.032513

4 ETW000 [ dev trc,00000] OCIHandleAlloc(OCI_HTYPE_ERROR, con=0, ErrBt=38733f8) 45 0.032558

4 ETW000 [ dev trc,00000] NLS env. settings: lang=41004D00450052004900430041004E00 174 0.032732

4 ETW000 [ dev trc,00000] terr=41004D0045005200490043004100 56 0.032788

4 ETW000 [ dev trc,00000] char=55005400460031003600 46 0.032834

4 ETW000 [ dev trc,00000] Client NLS setting (by OCINlsGetInfo(con=0)): 'AMERICAN_AMERICA.UTF16'

4 ETW000 55 0.032889

4 ETW000 [ dev trc,00000] charset='UTF16', ncharset='AL16UTF16', UNI_ASC=FALSE 37 0.032926

4 ETW000 [ dev trc,00000] Logon as OPS$-user to get SAPSR3's password 44 0.032970

4 ETW000 [ dev trc,00000] Connecting as /@SD1 on connection 0 (nls 0) ... (dbsl 720 251111, UNICODE[2])

4 ETW000 60 0.033030

4 ETW000 [ dev trc,00000] OCIHandleAlloc(OCI_HTYPE_SVCCTX, con=0, svchp=3873318) 56 0.033086

4 ETW000 [ dev trc,00000] Allocating server context handle 36 0.033122

4 ETW000 [ dev trc,00000] Attaching to database server SD1 (con=0, svchp=3873318, srvhp=3875fa0)

4 ETW000 132 0.033254

4 ETW000 [ dev trc,00000] Assigning server context 3875fa0 to service context 3873318 89221 0.122475

4 ETW000 [ dev trc,00000] OCIHandleAlloc(OCI_HTYPE_SESSION, con=0, usrhp=38a4270) 6509 0.128984

4 ETW000 [ dev trc,00000] Starting user session: OCISessionBegin(con=0, usr='/', svc=3873318, srv=3875fa0, usr=38a4270)

4 ETW000 70 0.129054

4 ETW000 [ dev trc,00000] OCISessionBegin(OCI_DEFAULT) failed with -1=OCI_ERROR 69818 0.198872

4 ETW000 [ dev trc,00000] OCISessionBegin(OCI_DEFAULT) failed with SQL error 1033: 198 0.199070

4 ETW000 [ dev trc,00000] ORA-01033: ORACLE initialization or shutdown in progress

4 ETW000 48 0.199118

4 ETW000 [ dev trc,00000] server_detach(con=0,stale=1,srvhp=3875fa0) 34 0.199152

4 ETW000 [ dev trc,00000] Server handle (con=0,svchp=3873318,srvhp=3875fa0) detached 324 0.199476

4 ETW000 [ dev trc,00000] Free server handle 3875fa0 of con=0 33 0.199509

4 ETW000 [ dbsloci.,00000] *** ERROR => CONNECT failed with sql error '1033' 63 0.199572

4 ETW000 [ dev trc,00000] set_ocica() -> SQL error code 1033 33 0.199605

4 ETW000 [ dev trc,00000] -->oci_get_errmsg (con=0, rc=1033) 30 0.199635

4 ETW000 [ dev trc,00000] OCIErrorGet -> SQL error code: 1033 143 0.199778

4 ETW000 [ dev trc,00000] ORA-01033: ORACLE initialization or shutdown in progress

4 ETW000 55 0.199833

4 ETW000 [ dev trc,00000] SAPUSER error or connect error 99=DBSL_ERR_DB, oerr=1033. 32 0.199865

4 ETW000 [ dev trc,00000] DbSlConnect(con=0) -> orc=0, 99=DBSL_ERR_DB 31 0.199896

4 ETW000 [ dblink ,00000] ***LOG BY2=>sql error 1033 performing CON 69 0.199965

4 ETW000 [ dblink ,00000] ***LOG BY0=>ORA-01033: ORACLE initialization or shutdown in progress

4 ETW000 42 0.200007

2EETW169 no connect possible: "DBMS = ORACLE --- dbs_ora_tnsname = 'SD1'"

R3trans -d is OK. What trans.log you paste? Check trans.log in current directory R3trans was executed from.

Try to continue

Regards

Roman

sorry pasted u the wrong tran.log

4 ETW000 54 1.361490

4 ETW000 [ dev trc,00000] - oci_bind ( 4: SQLT_AFC , l= 6, con=0, stp=3e7e140, pv=3e3ea58, in=3e263e0,rl=3e28558,rc=0000NULL)

4 ETW000 55 1.361545

4 ETW000 [ dev trc,00000] - oci_bind ( 5: SQLT_AFC , l= 28, con=0, stp=3e7e140, pv=3e3fb14, in=3e26974,rl=3e28aec,rc=0000NULL)

4 ETW000 53 1.361598

4 ETW000 [ dev trc,00000] exec_modify(): bulk=0, input_cnt=6, literal_cnt=0, fae_cnt=0 36 1.361634

4 ETW000 [ dev trc,00000] AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000 61 1.361695

4 ETW000 [ dev trc,00000] time 2012-08-27 11:21:39, con=0 connected, new transaction 45 1.361740

4 ETW000 [ dev trc,00000] -->oci_execute_stmt(#40: con=0,stp=3e7e140,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000 46 1.361786

4 ETW000 [ dev trc,00000] before OCIStmtExecute(svchp=3d12318, errhp=3d11378): [dur. 0,000s] 34 1.361820

4 ETW000 [ dev trc,00000] after OCIStmtExecute(rpc=1): [dur. 0,002s] 2136 1.363956

4 ETW000 [ dev trc,00000] <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,002s]

4 ETW000 56 1.364012

4 ETW000 [ dev trc,00000] stmt_execute() -> orc=0, rc=0=DBSL_ERR_OK 30 1.364042

4 ETW000 [ dev trc,00000] exec_modify() -> 0=DBSL_ERR_OK 28 1.364070

4 ETW000 [ dev trc,00000] DbSlModify() --> 0=DBSL_ERR_OK 49 1.364119

4 ETW000 [ dev trc,00000] } db_rtab( fcode = 'RT_DELETE', retcode = 0 ) 25 1.364144

4 ETW690 COMMIT "0" "0"

4 ETW000 [ dev trc,00000] db_con_commit (con_da={R/3,0,0},th_commit=1,tx=1,hold_cursor=0) 63 1.364207

4 ETW000 [ dev trc,00000] ->DbSlCommit(con=0, FORCE, DROP_CURSOR), after 40 stmts executed 36 1.364243

4 ETW000 [ dev trc,00000] -->oci_commit(con=0), time: 2012-08-27 11:21:39 72 1.364315

4 ETW000 [ dev trc,00000] OCITransCommit(svchp=3d12318, errhp=3d11378, OCI_DEFAULT) 31 1.364346

4 ETW000 [ dev trc,00000] <--oci_commit(), orc=0, time: 2012-08-27 11:21:39 3017 1.367363

4 ETW000 trace to file pointer closed

4 ETW000 trace at level 1 opened for a given file pointer

4 ETW000

4 ETW000 ================== STEP 1 =====================

4 ETW000 date&time : 27.08.2012 - 11:21:39

4 ETW000 function : CONNECT

4 ETW000 buffersync : YES

4 ETW000 clients : default

4 ETW000 commit : 100000

4 ETW000 table cache : dynamic

4 ETW000

4 ETW000 [ dev trc,00000] Disconnecting from ALL connections: 321 0.000321

4 ETW000 [ dev trc,00000] Disconnecting from connection 0 ... 55 0.000376

4 ETW000 [ dev trc,00000] Closing user session (con=0, svc=3d12318, usr=3d43270) 343 0.000719

4 ETW000 [ dev trc,00000] Server handle (con=0,svchp=3d12318,srvhp=3d14fa0) detached 1400 0.002119

4 ETW000 [ dev trc,00000] Disconnected (con=0) from ORACLE. 45 0.002164

4 ETW000 [ dev trc,00000] Disconnected from connection 0 29 0.002193

4 ETW000 [ dev trc,00000] statistics db_con_commit (com_total=1, com_tx=1) 28 0.002221

4 ETW000 [ dev trc,00000] statistics db_con_rollback (roll_total=0, roll_tx=0) 27 0.002248

4 ETW000 Disconnected from database.

4 ETW000 End of Transport (0000).

4 ETW000 date&time: 27.08.2012 - 11:21:39

Try to continue with post load activities

Regards

Roman

Oops again the same error

ERROR 2012-08-27 12:02:46.284 [iaxxinscbk.cpp:268]
CJS-00084 SQL statement or script failed. DIAGNOSIS: Error message: ORA-01102: cannot mount database in EXCLUSIVE mode

ERROR 2012-08-27 12:02:46.286
CJSlibModule::writeError_impl()
MUT-03025 Caught ESAPinstException in module call: .

ERROR 2012-08-27 12:02:46.556 [sixxcstepexecute.cpp:937]
FCO-00011 The step setOraclePasswords with step key |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CreateDBandLoad|ind|ind|ind|ind|createdbandload|0|NW_Postload|ind|ind|ind|ind|postload|0|NW_Postload_ORA|ind|ind|ind|ind|0|0|setOraclePasswords was executed with status ERROR ( Last error reported by the step: SQL statement or script failed. DIAGNOSIS: Error message: ORA-01102: cannot mount database in EXCLUSIVE mode

INFO 2012-08-27 12:02:47.441 [syuxcpath.cpp:471]
CSyPath::createFile() lib=syslib module=syslib
Creating file /tmp/sapinst_instdir/SOLMAN71SR1/SYSTEM/ORA/CENTRAL/AS/__instana_tmp.xml.

WARNING 2012-08-27 12:02:51.865 [iaxxejshlp.cpp:150]
Could not get property IDs of the JavaScript object.

ERROR 2012-08-27 12:02:51.865 [iaxxejsctl.cpp:367]
FJS-00010 Could not get value for property .

Please paste alert.log file

Regards

Roman

Alert Log:

Large Pages used by this instance: 0 (0 KB)
Large Pages unused system wide = 0 (0 KB) (alloc incr 64 MB)
Large Pages configured system wide = 0 (0 KB)
Large Page size = 2048 KB

RECOMMENDATION:
Total Shared Global Region size is 22 GB. For optimal performance,
prior to the next instance restart increase the number
of unused Large Pages by atleast 11105 2048 KB Large Pages (22 GB)
system wide to get 100% of the Shared
Global Region allocated with Large pages
***********************************************************
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
Picked latch-free SCN scheme 3
Autotune of undo retention is turned on.
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Starting up:
Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options.
ORACLE_HOME = /oracle/SD1/112_64
System name:    Linux
Node name:      el1165
Release:        2.6.32-220.el6.x86_64
Version:        #1 SMP Wed Nov 9 08:03:13 EST 2011
Machine:        x86_64
Using parameter settings in server-side spfile /oracle/SD1/112_64/dbs/spfileSD1.ora
System parameters with non-default values:
processes                = 430
sessions                 = 864
shared_pool_size         = 10816M
filesystemio_options     = "setall"
control_files            = "/oracle/SD1/origlogA/cntrl/cntrlSD1.dbf"
control_files            = "/oracle/SD1/origlogB/cntrl/cntrlSD1.dbf"
control_files            = "/oracle/SD1/sapdata1/cntrl/cntrlSD1.dbf"
control_file_record_keep_time= 30
db_block_size            = 8192
db_cache_size            = 10816M
compatible               = "11.2.0"
log_archive_dest_1       = "LOCATION=/oracle/SD1/oraarch/SD1arch"
log_archive_format       = "%t_%s_%r.dbf"
db_recovery_file_dest    = "/oracle/SD1/oraflash"
db_recovery_file_dest_size= 30000M
log_checkpoints_to_alert = TRUE
replication_dependency_tracking= FALSE
undo_tablespace          = "PSAPUNDO"
_in_memory_undo          = FALSE
recyclebin               = "off"
remote_os_authent        = TRUE
remote_login_passwordfile= "EXCLUSIVE"
parallel_execution_message_size= 16384
_table_lookup_prefetch_size= 0
audit_file_dest          = "/oracle/SD1/saptrace/audit"
db_name                  = "SD1"
open_cursors             = 2000
_sort_elimination_cost_ratio= 10
_b_tree_bitmap_plans     = FALSE
star_transformation_enabled= "true"
parallel_threads_per_cpu = 1
query_rewrite_enabled    = "false"
_index_join_enabled      = FALSE
_optim_peek_user_binds   = FALSE
pga_aggregate_target     = 14400M
_optimizer_mjc_enabled   = FALSE
diagnostic_dest          = "/oracle/SD1/saptrace"
max_dump_file_size       = "20000"
Deprecated system parameters with specified values:
remote_os_authent
End of deprecated system parameter listing
Mon Aug 27 12:02:44 2012
PMON started with pid=2, OS id=22144
Mon Aug 27 12:02:44 2012
PSP0 started with pid=3, OS id=22146
Mon Aug 27 12:02:45 2012
VKTM started with pid=4, OS id=22148 at elevated priority
VKTM running at (1)millisec precision with DBRM quantum (100)ms
Mon Aug 27 12:02:45 2012
GEN0 started with pid=5, OS id=22152
Mon Aug 27 12:02:45 2012
DIAG started with pid=6, OS id=22154
Mon Aug 27 12:02:45 2012
DBRM started with pid=7, OS id=22156
Mon Aug 27 12:02:45 2012
DIA0 started with pid=8, OS id=22158
Mon Aug 27 12:02:45 2012
MMAN started with pid=9, OS id=22160
Mon Aug 27 12:02:45 2012
DBW0 started with pid=10, OS id=22162
Mon Aug 27 12:02:45 2012
DBW1 started with pid=11, OS id=22164
Mon Aug 27 12:02:45 2012
DBW2 started with pid=12, OS id=22166
Mon Aug 27 12:02:45 2012
DBW3 started with pid=13, OS id=22168
Mon Aug 27 12:02:46 2012
LGWR started with pid=14, OS id=22170
Mon Aug 27 12:02:46 2012
CKPT started with pid=15, OS id=22172
Mon Aug 27 12:02:46 2012
SMON started with pid=16, OS id=22174
Mon Aug 27 12:02:46 2012
RECO started with pid=17, OS id=22176
Mon Aug 27 12:02:46 2012
MMON started with pid=18, OS id=22178
Mon Aug 27 12:02:46 2012
MMNL started with pid=19, OS id=22180
ORACLE_BASE not set in environment. It is recommended
that ORACLE_BASE be set in the environment
Reusing ORACLE_BASE from an earlier startup = /oracle/SD1
Mon Aug 27 12:02:46 2012
ALTER DATABASE   MOUNT
sculkget: failed to lock /oracle/SD1/112_64/dbs/lkSD1 exclusive
sculkget: lock held by PID: 6305
ORA-09968: unable to lock file
Linux-x86_64 Error: 11: Resource temporarily unavailable
Additional information: 6305
ORA-1102 signalled during: ALTER DATABASE   MOUNT...
Mon Aug 27 12:14:58 2012
Incremental checkpoint up to RBA [0xee.387df.0], current log tail at RBA [0xee.38850.0]
Mon Aug 27 12:45:01 2012
Incremental checkpoint up to RBA [0xee.38899.0], current log tail at RBA [0xee.38899.0]
Mon Aug 27 13:15:04 2012
Incremental checkpoint up to RBA [0xee.39106.0], current log tail at RBA [0xee.39106.0]

Ok. Then stop database instance first and paste output

sqlplus / as sysdba

SQL> shutdown immediate

Regards

Roman

Please bring up the database and then execute the brconnect manully to change the password . set the master password as the password for SAPSR3. Then stop the sapinst . Backup the keydb.xml

Edit the keydb.xml . Search for the team ERROR and replace with OK.

Then start SAPINST again.

Regards

Ratnajit

Hi,

I tried as you said but same error:

Execution of the command "/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -o summary -f stats -o SAPSR3 -t all -p 8" finished with return code 3. Output:
BR0801I BRCONNECT 7.20 (20)
BR0805I Start of BRCONNECT processing: cejhpulc.sta 2012-08-27 14.49.28
BR0484I BRCONNECT log file: /oracle/SD1/sapcheck/cejhpulc.sta
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed

BR0806I End of BRCONNECT processing: cejhpulc.sta 2012-08-27 14.49.28
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0804I BRCONNECT terminated with errors

brconnect log (even DB is up stil BRCONNECT says shutdown in progress??)

el1165:orasd1 54> cat brconnect.log
BR0801I BRCONNECT 7.20 (20)
BR0805I Start of BRCONNECT processing: cejhpulc.sta 2012-08-27 14.49.28
BR0484I BRCONNECT log file: /oracle/SD1/sapcheck/cejhpulc.sta
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed

One more this:

the sapinst has stuck at " Create database statistics" now

i.e" Post ora load activities is over now as there is a tick against that.

Now why again the same error at next step ...Please help ?

"/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -o summary -f stats -o SAPSR3 -t all -p 8" finished with return code 3.

Regards

Vishal

Can you run this manually

/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -o summary -f stats -o SAPSR3 -t all -p 8"

Also paste the log if you face issues .

Also paste the log /oracle/SD1/sapcheck/cejhpulc.sta

Regards

Ratnajit

Hi,

After running usr/sap/SD1/SYS/exe/run/brconnect -u / -c -o summary -f stats -o SAPSR3 -t all -p 8

it went successfully:

Also the logs from sapcheck directory:eBR0801I BRCONNECT 7.20 (20)
BR0805I Start of BRCONNECT processing: cejhpulc.sta 2012-08-27 14.49.28
BR0484I BRCONNECT log file: /oracle/SD1/sapcheck/cejhpulc.sta
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed

BR0806I End of BRCONNECT processing: cejhpulc.sta 2012-08-27 14.49.28
BR0280I BRCONNECT time stamp: 2012-08-27 14.49.28
BR0804I BRCONNECT terminated with errors

tail -100 cejhpxwx.sta
BR0801I BRCONNECT 7.20 (20)
BR0805I Start of BRCONNECT processing: cejhpxwx.sta 2012-08-27 15.28.23
BR0484I BRCONNECT log file: /oracle/SD1/sapcheck/cejhpxwx.sta
BR0280I BRCONNECT time stamp: 2012-08-27 15.28.23
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed
BR0280I BRCONNECT time stamp: 2012-08-27 15.28.23
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed

BR0806I End of BRCONNECT processing: cejhpxwx.sta 2012-08-27 15.28.23
BR0280I BRCONNECT time stamp: 2012-08-27 15.28.23
BR0804I BRCONNECT terminated with errors

You can edit the keydb.xml again and change from ERROR to OK and continue.

I hope the database is up and running and you can connect to the database using SQLPLUS.

Regards

Ratnajit

I changed the keydb.xml from entries with 'ERROR' to 'OK'.(It was ERROR at only one place)

After that i again ran it but same error( and DB is open and accessible via SQLPLUS)

Execution of the command "/usr/sap/SD1/SYS/exe/run/brconnect -u / -c -o summary -f stats -o SAPSR3DB -t all -p 8" finished with return code 3. Output:

BR0801I BRCONNECT 7.20 (20)

BR0805I Start of BRCONNECT processing: cejhqail.sta 2012-08-27 15.55.55

BR0484I BRCONNECT log file: /oracle/SD1/sapcheck/cejhqail.sta

BR0280I BRCONNECT time stamp: 2012-08-27 15.55.55

BR0301E SQL error -1033 at location db_connect-2, SQL statement:

'CONNECT /'

ORA-01033: ORACLE initialization or shutdown in progress

BR0310E Connect to database instance SD1 failed

BR0280I BRCONNECT time stamp: 2012-08-27 15.55.55

BR0301E SQL error -1033 at location db_connect-2, SQL statement:

'CONNECT /''

QL> !
el1165:orasd1 51> cat /oracle/SD1/sapcheck/cejhqail.sta
BR0801I BRCONNECT 7.20 (20)
BR0805I Start of BRCONNECT processing: cejhqail.sta 2012-08-27 15.55.55
BR0484I BRCONNECT log file: /oracle/SD1/sapcheck/cejhqail.sta
BR0280I BRCONNECT time stamp: 2012-08-27 15.55.55
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed
BR0280I BRCONNECT time stamp: 2012-08-27 15.55.55
BR0301E SQL error -1033 at location db_connect-2, SQL statement:
'CONNECT /'
ORA-01033: ORACLE initialization or shutdown in progress
BR0310E Connect to database instance SD1 failed

BR0806I End of BRCONNECT processing: cejhqail.sta 2012-08-27 15.55.55
BR0280I BRCONNECT time stamp: 2012-08-27 15.55.55
BR0804I BRCONNECT terminated with errors
el1165:orasd1 52>

Hello All,

The issue was later solved by cleaning all file systems again and restarting the entire installation from scratch.

Thanks to all for you help.

Regards

Vishal

Hi,

Is there an old instance of Oracle running?

Perhaps a complete cleanup of Oracle processes could help.

Also refer http://scn.sap.com/thread/1870379 thread with similar issue.

Regards,

Srikishan

You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.

Comment

Hi Srikishan,

Thanks for your reply .

I tried cleaning memory and killing all oracle processes(even stopped listener) but of no help

There is no othe Oracle or SAP on this seerver..This is the first Installation on this server (Linux)

Also the link http://scn.sap.com/thread/1870379 seems to have issues with SAP processes which in my case doesnt fit as SAP instance is not installed yet. My installation is stuck prior to the step whre sapinst installs CI.

Thanks for your suggestions .

Regards

Vishal Bhat

Ask a Question

Top Q&A Solution Author

User

Count