MAIN_SHADOW/START_SHDI_FIRST during EHP4 Upgradati...

Dear Experts,

we are facing error "Severe error(s) occured in phase MAIN_SHADOW/START_SHDI_FIRST!

Last error code set: Shadow instance couldn't be started, check 'STARTSFI.LOG' and 'DEVTRACE.LOG'

during upgradation of EHP4, please find SAPehpi_troubleticket.log & STARTSFI.LOG

but there is no "DEVTRACE.LOG" anywhere in system.

"startsfi.log"

1 ETQ201 Entering upgrade-phase "START_SHDI_FIRST" ("20110620164552")

4 ETQ399 Set environment for standard connect:

2 ETQ367 Connect variables are set for standard instance access

4 ETQ399 System-nr = '01', GwService = 'sapgw01'

4 ETQ399 Environment variables:

4 ETQ399 dbs_ora_schema=SAPSR3

4 ETQ399 auth_shadow_upgrade=0

1 ETQ200 Executing actual phase 'MAIN_SHADOW/START_SHDI_FIRST'.

1 ETQ399 Phase arguments:

2 ETQ399 Arg[0] = 'DEVTRACE.LOG'

4 ETQ399 Set environment for shadow connect:

4 ETQ399 Set RFC variables for shadow connect:

4 ETQ399 System-nr = '02', GwService = 'sapgw02'

4 ETQ380 computing toolpath for request "TP_SHADOW_CONNECT"

4 ETQ381 request "TP_SHADOW_CONNECT" means "tp needs to connect to shadow system"

4 ETQ382 translates to group "R3UP_TOOL_GROUP_NEW"

4 ETQ383 translates to path "exe"

4 ETQ399 Set tool parameters for shadow connect:

4 ETQ399 default TPPARAM: SHADOW.TPP

4 ETQ380 computing toolpath for request "TP_ALWAYS_NEW"

4 ETQ381 request "TP_ALWAYS_NEW" means "always tp from DIR_PUT/exe, for phase KX_SWITCH"

4 ETQ382 translates to group "R3UP_TOOL_GROUP_NEW"

4 ETQ383 translates to path "exe"

2 ETQ399 Starting shadow instance

4 ETQ359 RFC Login to: System="PE2", Nr="02", GwHost="DBMCI001", GwService="sapgw02"

2 ETQ231 RFC Login failed

4 ETQ359 RFC Login to: System="PE2", Nr="02", GwHost="DBMCI001", GwService="sapgw02"

2 ETQ231 RFC Login failed

2 ETQ399 Stopping shadow instance

4 ETQ399 2011/06/20 16:45:52: put_execute: (sapcontrol) forkpid:19318

2 ETQ353 Starting system

4 ETQ399 2011/06/20 16:46:12: put_execute: (sapstartsrv) forkpid:19319

4 ETQ399 2011/06/20 16:47:53: put_execute: (sapcontrol) forkpid:19323

2EETQ399 Starting shadow instance failed

"SAPehpi_troubleticket.log"

This trouble ticket was created by SAPehpi on 20110620174203

SAPehpi broke during phase START_SHDI_FIRST in module MAIN_SHADOW / Shadow System Operations: Start shadow system

Error Message: Shadow instance couldn't be started, check 'STARTSFI.LOG' and 'DEVTRACE.LOG'

Summary of SAPehpi:

SAPehpi Release: lmt_001

SAPehpi Version: 40.005

Start Release: 701

Target Release: 701

Summary of host system details:

SID: PE2

Host: DBMCI001

MS Host: DBMCI001

GW Host: DBMCI001

Start Path: /usr/sap/PE2/DVEBMGS01/exe

Kernel Path: /usr/sap/PE2/DVEBMGS01/exe

Summary of operating system details:

OS Type: HP-UX IA64

OS Version: 11.31

Summary of database details:

Database Type: ora

Database Version: 10.2.0.4.0

Summary of RFC details:

Host: DBMCI001

GW Host: DBMCI001

Client: 000

Destination: PE2

Language:

System No.: 02

Thanks in advance....

SAP Managed Tags:
Oracle Database,
SAP ERP

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 (1)

Check

<putdir>/<SID>/DVEBMGS01/work

for tracefiles dev_disp, dev_w0

Markus

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

Comment

Hi Guys,

please find dev_disp.. and please advise what should i do...?

trc file: "dev_disp.new", trc level: 1, release: "701"

sysno 01

sid PE2

systemid 274 (HP (IA-64) with HP-UX)

relno 7010

patchlevel 0

patchno 32

intno 20020600

make: single threaded, Unicode, 64 bit, optimized

pid 15581

Mon Jun 20 15:45:40 2011

kernel runs with dp version 241000(ext=110000) (@(#) DPLIB-INT-VERSION-241000-UC)

length of sys_adm_ext is 576 bytes

SWITCH TRC-HIDE on ***

***LOG Q00=> DpSapEnvInit, DPStart (01 15581) [dpxxdisp.c 1282]

shared lib "dw_xml.sl" version 32 successfully loaded

shared lib "dw_xtc.sl" version 32 successfully loaded

shared lib "dw_stl.sl" version 32 successfully loaded

shared lib "dw_gui.sl" version 32 successfully loaded

shared lib "dw_mdm.sl" version 32 successfully loaded

rdisp/softcancel_sequence : -> 0,5,-1

use internal message server connection to port 3900

Mon Jun 20 15:45:46 2011

WARNING => DpNetCheck: NiHostToAddr(www.doesnotexist0136.qqq.nxst) took 6 seconds

Mon Jun 20 15:45:51 2011

WARNING => DpNetCheck: NiAddrToHost(1.0.0.0) took 5 seconds

***LOG GZZ=> 2 possible network problems detected - check tracefile and adjust the DNS settings [dpxxtool2.c 5527]

MtxInit: 30000 0 0

DpSysAdmExtInit: ABAP is active

DpSysAdmExtInit: VMC (JAVA VM in WP) is not active

DpIPCInit2: start server >DBMCI001_PE2_01 <

DpShMCreate: sizeof(wp_adm) 28032 (1752)

DpShMCreate: sizeof(tm_adm) 6009216 (29896)

DpShMCreate: sizeof(wp_ca_adm) 28800 (96)

DpShMCreate: sizeof(appc_ca_adm) 9600 (96)

DpCommTableSize: max/headSize/ftSize/tableSize=500/16/552064/552080

DpShMCreate: sizeof(comm_adm) 552080 (1088)

DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0

DpShMCreate: sizeof(slock_adm) 0 (104)

DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0

DpShMCreate: sizeof(file_adm) 0 (72)

DpShMCreate: sizeof(vmc_adm) 0 (2008)

DpShMCreate: sizeof(wall_adm) (41664/36752/80/192)

DpShMCreate: sizeof(gw_adm) 48

DpShMCreate: SHM_DP_ADM_KEY (addr: 0xc000000015c00000, size: 6715488)

DpShMCreate: allocated sys_adm at 0xc000000015c00000

DpShMCreate: allocated wp_adm at 0xc000000015c02310

DpShMCreate: allocated tm_adm_list at 0xc000000015c09090

DpShMCreate: allocated tm_adm at 0xc000000015c090f0

DpShMCreate: allocated wp_ca_adm at 0xc0000000161c4270

DpShMCreate: allocated appc_ca_adm at 0xc0000000161cb2f0

DpShMCreate: allocated comm_adm at 0xc0000000161cd870

DpShMCreate: system runs without slock table

DpShMCreate: system runs without file table

DpShMCreate: allocated vmc_adm_list at 0xc000000016254500

DpShMCreate: allocated gw_adm at 0xc000000016254580

DpShMCreate: system runs without vmc_adm

DpShMCreate: allocated ca_info at 0xc0000000162545b0

DpShMCreate: allocated wall_adm at 0xc0000000162545c0

MBUF state OFF

DpCommInitTable: init table for 500 entries

rdisp/queue_size_check_value : -> off

ThTaskStatus: rdisp/reset_online_during_debug 0

EmInit: MmSetImplementation( 2 ).

MM global diagnostic options set: 0

<ES> client 0 initializing ....

<ES> InitFreeList

<ES> block size is 4096 kByte.

Using implementation std

<ES> Info: use normal pages (no huge table support available)

EsStdUnamFileMapInit: ES base = 0x0xc000000040000000

EsStdInit: Extended Memory 4092 MB allocated

<ES> 1022 blocks reserved for free list.

ES initialized.

Mon Jun 20 15:45:54 2011

rdisp/http_min_wait_dia_wp : 1 -> 1

***LOG CPS=> DpLoopInit, ICU ( 3.0 3.0 4.0.1) [dpxxdisp.c 1678]

***LOG Q0I=> NiPConnect: connect (239: Connection refused) [nixxi.cpp 2518]

ERROR => NiPConnect: SiConnect failed for hdl 2 / sock 11

(SI_ECONN_REFUSE/239; I4; ST; 10.11.225.113:3900) [nixxi.cpp 2518]

ERROR => MsIAttachEx: NiBufConnect to DBMCI001/3900 failed (rc=NIECONN_REFUSED) [msxxi.c 647]

***LOG Q0L=> DpLoopInit, nomscon () [dpxxdisp.c 1815]

CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

Mon Jun 20 15:46:14 2011

ERROR => MsISnd2: not_attached [msxxi.c 1321]

***LOG Q0N=> DpRqNoWpHandle, MsSndName () [dpxxdisp.c 4932]

ERROR => MsISnd2: not_attached [msxxi.c 1321]

***LOG Q0N=> DpRqNoWpHandle, MsSndAdmin () [dpxxdisp.c 5053]

ERROR => MsISnd2: not_attached [msxxi.c 1321]

***LOG Q0N=> DpRqNoWpHandle, MsSndName () [dpxxdisp.c 4932]

ERROR => MsISnd2: not_attached [msxxi.c 1321]

***LOG Q0N=> DpRqNoWpHandle, MsSndName () [dpxxdisp.c 4932]

ERROR => MsISnd2: not_attached [msxxi.c 1321]

***LOG Q0N=> DpRqNoWpHandle, MsSndAdmin () [dpxxdisp.c 5053]

ERROR => MsISnd2: not_attached [msxxi.c 1321]

***LOG Q0N=> DpRqNoWpHandle, MsSndAdmin () [dpxxdisp.c 5053]

ERROR => MsISnd2: not_attached [msxxi.c 1321]

***LOG Q0N=> DpRqNoWpHandle, MsSndName () [dpxxdisp.c 4932]

Mon Jun 20 15:46:34 2011

***LOG Q0K=> DpMsAttach, mscon ( DBMCI001) [dpxxdisp.c 12483]

DpStartStopMsg: send start message (myname is >DBMCI001_PE2_01 <)

DpStartStopMsg: start msg sent to message server o.k.

DpMsgAdmin: Set release to 7010, patchlevel 0

MBUF state PREPARED

MBUF component UP

DpMBufHwIdSet: set Hardware-ID

***LOG Q1C=> DpMBufHwIdSet [dpxxmbuf.c 1048]

DpMsgAdmin: Set patchno for this platform to 32

Release check o.K.

MBUF state ACTIVE

DpModState: change server state from STARTING to ACTIVE

Mon Jun 20 16:34:34 2011

DpHdlDeadWp: restart wp (pid=15604) automatically

Mon Jun 20 16:48:14 2011

DpHdlDeadWp: restart wp (pid=19226) automatically

Regards,

> DpModState: change server state from STARTING to ACTIVE

Well - this looks good.

Any other traces?

dev_ms?

Markus

Dear Guys,

please check again this log .. seems not good...

#----

#---- MASKING file STARTSFI.LOG from /usr/sap/trans/EHPI/abap/log

#---- TIME: 20110620164552 PHASE: START_SHDI_FIRST

#----

1 ETQ201 Entering upgrade-phase "START_SHDI_FIRST" ("20110620164132")

4 ETQ399 Set environment for standard connect:

2 ETQ367 Connect variables are set for standard instance access

4 ETQ399 System-nr = '01', GwService = 'sapgw01'

4 ETQ399 Environment variables:

4 ETQ399 dbs_ora_schema=SAPSR3

4 ETQ399 auth_shadow_upgrade=0

1 ETQ200 Executing actual phase 'MAIN_SHADOW/START_SHDI_FIRST'.

1 ETQ399 Phase arguments:

2 ETQ399 Arg[0] = 'DEVTRACE.LOG'

4 ETQ399 Set environment for shadow connect:

4 ETQ399 Set RFC variables for shadow connect:

4 ETQ399 System-nr = '02', GwService = 'sapgw02'

4 ETQ380 computing toolpath for request "TP_SHADOW_CONNECT"

4 ETQ381 request "TP_SHADOW_CONNECT" means "tp needs to connect to shadow system"

4 ETQ382 translates to group "R3UP_TOOL_GROUP_NEW"

4 ETQ383 translates to path "exe"

4 ETQ399 Set tool parameters for shadow connect:

4 ETQ399 default TPPARAM: SHADOW.TPP

4 ETQ380 computing toolpath for request "TP_ALWAYS_NEW"

4 ETQ381 request "TP_ALWAYS_NEW" means "always tp from DIR_PUT/exe, for phase KX_SWITCH"

4 ETQ382 translates to group "R3UP_TOOL_GROUP_NEW"

4 ETQ383 translates to path "exe"

2 ETQ399 Starting shadow instance

4 ETQ359 RFC Login to: System="PE2", Nr="02", GwHost="DBMCI001", GwService="sapgw02"

2 ETQ231 RFC Login failed

4 ETQ359 RFC Login to: System="PE2", Nr="02", GwHost="DBMCI001", GwService="sapgw02"

2 ETQ231 RFC Login failed

2 ETQ399 Stopping shadow instance

4 ETQ399 2011/06/20 16:41:32: put_execute: (sapcontrol) forkpid:19302

2 ETQ353 Starting system

4 ETQ399 2011/06/20 16:41:52: put_execute: (sapstartsrv) forkpid:19303

4 ETQ399 2011/06/20 16:43:32: put_execute: (sapcontrol) forkpid:19314

2EETQ399 Starting shadow instance failed

regards..

And plz check this also...

SAPehpi.ECO

20.06.2011 16:45:52

Stop

FAIL: HTTP error, HTTP/1.1 401 Unauthorized

Process with ID 19318 terminated with status 1

BLOCKED SIGNALS: ''

SAPehpi> Starting subprocess 19319 at 20110620164612

ENV: DIR_LIBRARY=/usr/sap/trans/EHPI/abap/exe

ENV: JAVA_HOME=/opt/java1.4/jre

ENV: LD_LIBRARY_PATH=/usr/sap/trans/EHPI/abap/exe:/usr/sap/trans/EHPI/jvm/jre/lib/ia64/server:/usr/sap/trans/EHPI/jvm/jre/lib/ia64:/usr/sap/trans/EHPI/jvm/jre/../lib/ia64

ENV: NLS_LANG=AMERICAN_AMERICA.UTF8

ENV: ORACLE_BASE=/oracle

ENV: ORACLE_SID=PE2

ENV: PATH=/usr/sap/trans/EHPI/abap/exe:/opt/java1.4/jre/bin:/home/pe2adm:/usr/sap/PE2/SYS/exe/run:/usr/bin:/usr/ccs/bin:/usr/contrib/bin:/usr/contrib/Q4/bin:/opt/perl/bin:/opt/gvsd/bin:/opt/ipf/bin:/opt/nettladm/bin:/opt/fcms/bin:/opt/wbem/bin:/opt/wbem/sbin:/opt/sas/bin:/opt/graphics/common/bin:/opt/atok/bin:/usr/bin/X11:/usr/contrib/bin/X11:/opt/sec_mgmt/bastille/bin:/opt/caliper/bin:/opt/drd/bin:/opt/dsau/bin:/opt/dsau/sbin:/opt/resmon/bin:/opt/firefox:/opt/gnome/bin:/opt/ignite/bin:/opt/propplus/bin:/usr/contrib/kwdb/bin:/opt/perl_32/bin:/opt/perl_64/bin:/opt/prm/bin:/opt/sfm/bin:/opt/swm/bin:/opt/sec_mgmt/spc/bin:/opt/ssh/bin:/opt/swa/bin:/opt/hpsmh/bin:/opt/thunderbird:/opt/sentinel/bin:/opt/langtools/bin:/opt/gwlm/bin:/opt/omni/bin:.

ENV: SAPSYSTEMNAME=PE2

ENV: auth_shadow_upgrade=1

ENV: dbms_type=ORA

ENV: dbs_ora_schema=SAPSR3

ENV: dbs_ora_tnsname=PE2

EXECUTING /usr/sap/trans/EHPI/abap/exe/sapstartsrv (/usr/sap/trans/EHPI/abap/exe/sapstartsrv) pf=/usr/sap/trans/EHPI/abap/PE2/SYS/profile/START_DVEBMGS02_DBMCI001 -D

BLOCKED SIGNALS: ''

SAPehpi> Starting subprocess 19323 at 20110620164753

ENV: DIR_LIBRARY=/usr/sap/trans/EHPI/abap/exe

ENV: JAVA_HOME=/opt/java1.4/jre

ENV: LD_LIBRARY_PATH=/usr/sap/trans/EHPI/abap/exe:/usr/sap/trans/EHPI/jvm/jre/lib/ia64/server:/usr/sap/trans/EHPI/jvm/jre/lib/ia64:/usr/sap/trans/EHPI/jvm/jre/../lib/ia64

ENV: NLS_LANG=AMERICAN_AMERICA.UTF8

ENV: ORACLE_BASE=/oracle

ENV: ORACLE_SID=PE2

ENV: SAPSYSTEMNAME=PE2

ENV: auth_shadow_upgrade=1

ENV: dbms_type=ORA

ENV: dbs_ora_schema=SAPSR3

ENV: dbs_ora_tnsname=PE2

EXECUTING /usr/sap/trans/EHPI/abap/exe/sapcontrol (/usr/sap/trans/EHPI/abap/exe/sapcontrol) -prot NI_HTTP -host DBMCI001 -nr 02 -function StartWait 300 10

20.06.2011 16:47:53

Start

FAIL: HTTP error, HTTP/1.1 401 Unauthorized

Process with ID 19323 terminated with status 1

trc file: "sapstartsrv.log", trc level: 0, release: "701"

pid 23312

Thu Jun 16 14:01:02 2011

No halib defined => HA support disabled

Initializing SAPControl Webservice

SapSSLInit failed => https support disabled

Starting WebService thread

Webservice thread started, listening on port 50113

Trusted http connect via Unix domain socket '/tmp/.sapstream50113' enabled.

trusted unix domain socket user is stopping SAP System at 2011/06/19 19:38:40

PLEASE CHECK THIS ALSO..

Hi Rish,

You are installing EhP4 to sysnr "01" and the shadow sysnr is "02"

4 ETQ399 System-nr = '01', GwService = 'sapgw01'

4 ETQ399 Environment variables:

4 ETQ399 dbs_ora_schema=SAPSR3

4 ETQ399 auth_shadow_upgrade=0

1 ETQ200 Executing actual phase 'MAIN_SHADOW/START_SHDI_FIRST'.

1 ETQ399 Phase arguments:

2 ETQ399 Arg[0] = 'DEVTRACE.LOG'

4 ETQ399 Set environment for shadow connect:

4 ETQ399 Set RFC variables for shadow connect:

4 ETQ399 System-nr = '02', GwService = 'sapgw02'

Check the message, below in the dev_disp;

But, regarding to the dev_disp output, the system is trying to connect 3900 which is sysnr "00"

*** ERROR => NiPConnect: SiConnect failed for hdl 2 / sock 11
 (SI_ECONN_REFUSE/239; I4; ST; 10.11.225.113:3900) [nixxi.cpp 2518]
 *** ERROR => MsIAttachEx: NiBufConnect to DBMCI001/3900 failed
 (rc=NIECONN_REFUSED) [msxxi.c 647]

That may be cause to this problem.

Best regards,

Orkun Gedik

Hi,

Please stop the main instance then run cleanipc command to free old semaphores. After that try to start shadow instance manually with below command:

SAPehpi startshd (SAPehpi will be in <DIR_PUT>/EHPI/abap/bin)

If shadow instance will come up. Then stop it again with SAPehpi stopshd. And start main instance and repeat the phase from ehpi screen.

If shadow system does not come up manually as well. Then check shadow instance trace file in the location as mentioned by Markus and paste the fresh log here.

Thanks

Sunny

Dear Guys, i try to start shadow by manually as assist by by sunny but it was not started, plz find fresh logs..

#----

#---- MASKING file STARTSFI.LOG from /usr/sap/trans/EHPI/abap/log

#---- TIME: 20110621115735 PHASE: START_SHDI_FIRST

#----

1 ETQ201 Entering upgrade-phase "START_SHDI_FIRST" ("20110620164552")

4 ETQ399 Set environment for standard connect:

2 ETQ367 Connect variables are set for standard instance access

4 ETQ399 System-nr = '01', GwService = 'sapgw01'

4 ETQ399 Environment variables:

4 ETQ399 dbs_ora_schema=SAPSR3

4 ETQ399 auth_shadow_upgrade=0

1 ETQ200 Executing actual phase 'MAIN_SHADOW/START_SHDI_FIRST'.

1 ETQ399 Phase arguments:

2 ETQ399 Arg[0] = 'DEVTRACE.LOG'

4 ETQ399 Set environment for shadow connect:

4 ETQ399 Set RFC variables for shadow connect:

4 ETQ399 System-nr = '02', GwService = 'sapgw02'

4 ETQ380 computing toolpath for request "TP_SHADOW_CONNECT"

4 ETQ381 request "TP_SHADOW_CONNECT" means "tp needs to connect to shadow system"

4 ETQ382 translates to group "R3UP_TOOL_GROUP_NEW"

4 ETQ383 translates to path "exe"

4 ETQ399 Set tool parameters for shadow connect:

4 ETQ399 default TPPARAM: SHADOW.TPP

4 ETQ380 computing toolpath for request "TP_ALWAYS_NEW"

4 ETQ381 request "TP_ALWAYS_NEW" means "always tp from DIR_PUT/exe, for phase KX_SWITCH"

4 ETQ382 translates to group "R3UP_TOOL_GROUP_NEW"

4 ETQ383 translates to path "exe"

2 ETQ399 Starting shadow instance

4 ETQ359 RFC Login to: System="PE2", Nr="02", GwHost="DBMCI001", GwService="sapgw02"

2 ETQ231 RFC Login failed

4 ETQ359 RFC Login to: System="PE2", Nr="02", GwHost="DBMCI001", GwService="sapgw02"

2 ETQ231 RFC Login failed

2 ETQ399 Stopping shadow instance

4 ETQ399 2011/06/20 16:45:52: put_execute: (sapcontrol) forkpid:19318

2 ETQ353 Starting system

4 ETQ399 2011/06/20 16:46:12: put_execute: (sapstartsrv) forkpid:19319

4 ETQ399 2011/06/20 16:47:53: put_execute: (sapcontrol) forkpid:19323

2EETQ399 Starting shadow instance failed

dev_w0

M Tue Jun 21 11:49:24 2011

M in_ThErrHandle: 1

M ThIErrHandle: new stat of W0 is WP_SHUTDOWN

M ThIErrHandle: I'm during shutdown

M PfStatDisconnect: disconnect statistics

M Entering ThSetStatError

M ThCallDbBreak: use db_sqlbreak

B db_sqlbreak() = 1

M ThIErrHandle: don't try rollback again

M ThShutDownServer: shutdown server

M ThExecShutDown: perform exclusive shutdown actions

M ThCheckComOrRb (event=1, full_commit=1)

M ThCallHooks: call hook >ASTAT-collect commit handling< for event BEFORE_COMMIT

M ThCallHooks: call hook >rsts_before_commit< for event BEFORE_COMMIT

M SosCheckAbapEnv: invalid tid/mode T-1/M255

M ThCheckComOrRb (event=3, full_commit=1)

M ThCallHooks: call hook >ThVBICmRbHook< for event AFTER_COMMIT

M ThVBICmRbHook: called for commit

M ThCallHooks: call hook >ThNoClearPrevErr< for event AFTER_COMMIT

M ThNoClearPrevErr: clear prev no err

M ThCallHooks: call hook >dyKeyTableRest< for event AFTER_COMMIT

M ThCallHooks: call hook >rsts_after_commit< for event AFTER_COMMIT

M SosCheckAbapEnv: invalid tid/mode T-1/M255

M ThCallHooks: call hook >SpoolHandleHook< for event AFTER_COMMIT

M SosCheckAbapEnv: invalid tid/mode T-1/M255

M ThUsrDelEntry (*, *, DBMCI001_PE2_01 ) o.k.

M ThICommit3: full commit, set time, keep resources, redispatch

M ThICommit3: commit and keep resources

M ThCheckComOrRb (event=1, full_commit=0)

M ThCallHooks: call hook >ASTAT-collect commit handling< for event BEFORE_COMMIT

M ThCallHooks: call hook >rsts_before_commit< for event BEFORE_COMMIT

M SosCheckAbapEnv: invalid tid/mode T-1/M255

M ThCheckComOrRb (event=3, full_commit=0)

M ThCallHooks: call hook >ThVBICmRbHook< for event AFTER_COMMIT

M ThVBICmRbHook: called for commit

M ThCallHooks: call hook >ThNoClearPrevErr< for event AFTER_COMMIT

M ThNoClearPrevErr: clear prev no err

M ThCallHooks: call hook >dyKeyTableRest< for event AFTER_COMMIT

M ThCallHooks: call hook >rsts_after_commit< for event AFTER_COMMIT

M SosCheckAbapEnv: invalid tid/mode T-1/M255

M ThCallHooks: call hook >SpoolHandleHook< for event AFTER_COMMIT

M SosCheckAbapEnv: invalid tid/mode T-1/M255

M ThAlarm: set alarm to 600 sec

M ThICommit3 o.k.

M ThExecShutDown: ThUsrDelEntry o.k.

M ThExecShutDown: called rsau_log_system_stop

M SND: <I=10149, M=0xABCDE123, D=ASYNC_ENQUE, T=ASYNC_REQUEST, MF=NO, len=440/440>

M NiIWrite: hdl 0 sent data (wrt=440,pac=1,MESG_IO)

M ThExecShutDown: EnqCleanMyServer ok.

M PfStatIndInit: Initializing Index-Record

M PfWriteIntoFile: copied shared buf (0 bytes) to local buf

M ThIErrHandle: do not call ThrCoreInfo (no_core_info=0, in_dynp_env=0)

M Entering ThReadDetachMode

M call ThrShutDown (1)...

B Disconnecting from ALL connections:

B Wp Hdl ConName ConId ConState TX PRM RCT TIM MAX OPT Date Time DBHost

B 000 000 R/3 000000000 INACTIVE NO YES NO 000 255 255 20110620 154551 DBMDB001

C Disconnecting from connection 0 ...

C Closing user session (con_hdl=0,svchp=0x6000000003529f18,usrhp=0x60000000035a45b0)

C Detaching from DB Server (con_hdl=0,svchp=0x6000000003529f18,srvhp=0x600000000352ccc8)

C Now I'm disconnected from ORACLE

B Disconnected from connection 0

B statistics db_con_commit (com_total=7954, com_tx=2132)

B statistics db_con_rollback (roll_total=0, roll_tx=0)

M ***LOG Q02=> wp_halt, WPStop (Workproc 0 15593) [dpuxtool.c 268]

trc file: "dev_rd", trc level: 1, release: "701"

Tue Jun 21 12:46:23 2011

***LOG S00=> GwInitReader, gateway started ( 27867) [gwxxrd.c 1757]

systemid 274 (HP (IA-64) with HP-UX)

relno 7010

patchlevel 0

patchno 32

intno 20020600

make: single threaded, Unicode, 64 bit, optimized

pid 27867

gateway runs with dp version 241000(ext=110000) (@(#) DPLIB-INT-VERSION-241000-UC)

gateway (version=701.2009.01.21)

gw/local_addr : 0.0.0.0

CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

CCMS: Initalizing shared memory of size 60000000 for monitoring segment.

CCMS: Checking Downtime Configuration of Monitoring Segment.

Tue Jun 21 12:46:24 2011

Bind service sapgw01 (socket) to port 3301

GwPrintMyHostAddr: my host addresses are :

1 : [10.11.225.113] DBMCI001.ashokleyland.com (HOSTNAME)

2 : [127.0.0.1] localhost (LOCALHOST)

Full qualified hostname = DBMCI001.ashokleyland.com

DpSysAdmExtCreate: ABAP is active

DpSysAdmExtCreate: VMC (JAVA VM in WP) is not active

DpShMCreate: sizeof(wp_adm) 28032 (1752)

DpShMCreate: sizeof(tm_adm) 6009216 (29896)

DpShMCreate: sizeof(wp_ca_adm) 28800 (96)

DpShMCreate: sizeof(appc_ca_adm) 9600 (96)

DpCommTableSize: max/headSize/ftSize/tableSize=500/16/552064/552080

DpShMCreate: sizeof(comm_adm) 552080 (1088)

DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0

DpShMCreate: sizeof(slock_adm) 0 (104)

DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0

DpShMCreate: sizeof(file_adm) 0 (72)

DpShMCreate: sizeof(vmc_adm) 0 (2008)

DpShMCreate: sizeof(wall_adm) (41664/36752/80/192)

DpShMCreate: sizeof(gw_adm) 48

DpShMCreate: SHM_DP_ADM_KEY (addr: 0xc000000010c00000, size: 6715488)

DpShMCreate: allocated sys_adm at 0xc000000010c00000

DpShMCreate: allocated wp_adm at 0xc000000010c02310

DpShMCreate: allocated tm_adm_list at 0xc000000010c09090

DpShMCreate: allocated tm_adm at 0xc000000010c090f0

DpShMCreate: allocated appc_ca_adm at 0xc0000000111cb2f0

DpShMCreate: allocated comm_adm at 0xc0000000111cd870

DpShMCreate: system runs without slock table

DpShMCreate: system runs without file table

DpShMCreate: allocated vmc_adm_list at 0xc000000011254500

DpShMCreate: allocated gw_adm at 0xc000000011254580

DpShMCreate: system runs without vmc_adm

DpShMCreate: allocated ca_info at 0xc0000000112545b0

MtxInit: -2 0 0

Tue Jun 21 12:46:31 2011

GwDpInit: attached to gw_adm at 0xc000000011254580

Dear Guys, thanks for your help but it is not working till now... please suggest me what to do....

plz find START_ASCS00.log

Trace of system startup/check of SAP System PE2 on Tue Jun 21 12:47:34 IST 2011

Called command: /usr/sap/PE2/SYS/exe/run/startsap start

Starting Startup Agent sapstartsrv

Instance Service on host DBMCI001 started

Starting SAP Instance ASCS00

SAP-R/3-Startup Program Rel 701 V1.8 (2003/04/24)

Starting at 2011/06/21 12:47:45

Startup Profile: "/usr/sap/PE2/SYS/profile/START_ASCS00_DBMCI001"

Setup Environment Variables

(28000) SETENV LD_LIBRARY_PATH=/usr/sap/PE2/ASCS00/exe:

(28000) SETENV SHLIB_PATH=/usr/sap/PE2/ASCS00/exe:/usr/sap/PE2/ASCS00/exe:/usr/sap/PE2/SYS/exe/run:/oracle/client/10x_64/instantclient

(28000) SETENV LIBPATH=/usr/sap/PE2/ASCS00/exe:

Execute Pre-Startup Commands

(28001) Local: /usr/sap/PE2/SYS/exe/run/sapcpe pf=/usr/sap/PE2/SYS/profile/PE2_ASCS00_DBMCI001 list:/usr/sap/PE2/SYS/exe/run/scs.lst

(28000) system(/usr/sap/PE2/SYS/exe/run/sapcpe pf=/usr/sap/PE2/SYS/profile/PE2_ASCS00_DBMCI001 list:/usr/sap/PE2/SYS/exe/run/scs.lst) returns 4

(28006) Local: rm -f ms.sapPE2_ASCS00

(28009) Local: ln -s -f /usr/sap/PE2/ASCS00/exe/msg_server ms.sapPE2_ASCS00

(28012) Local: rm -f en.sapPE2_ASCS00

(28015) Local: ln -s -f /usr/sap/PE2/ASCS00/exe/enserver en.sapPE2_ASCS00

Starting Programs

21.06.2011 12:47:45

ShmDetach

(28021) Starting: local ms.sapPE2_ASCS00 pf=/usr/sap/PE2/SYS/profile/PE2_ASCS00_DBMCI001

(28022) Starting: local en.sapPE2_ASCS00 pf=/usr/sap/PE2/SYS/profile/PE2_ASCS00_DBMCI001

(28000) Waiting for Child Processes to terminate.

Instance on host DBMCI001 started

thanks in advance.....

regards,,

Rishi

dev_w0 log which you pasted above is not completed, could you please paste complete logs? Also, paste dev_ms and dev_disp.

Thanks

Sunny

pe2adm> tail -100 dev_ms

trc file: "dev_ms", trc level: 1, release: "701"

[Thr 1] Tue Jun 21 15:03:14 2011

[Thr 1] MsSSetTrcLog: trc logging active, max size = 20971520 bytes

systemid 274 (HP (IA-64) with HP-UX)

relno 7010

patchlevel 0

patchno 11

intno 20020600

make: multithreaded, Unicode, 64 bit, optimized

pid 29627

[Thr 1] ***LOG Q01=> MsSInit, MSStart (Msg Server 1 29627) [msxxserv_mt. 1847]

[Thr 1] SigISetDefaultAction : default handling for signal 18

[Thr 1] ***LOG Q0I=> NiIBindSocket: could not delete file '/tmp/.sapstream3900' for hdl 1: unlink (1: Not owner) [nixxi.cpp 3219]

[Thr 1] *** ERROR => NiIBindSocket: could not delete file '/tmp/.sapstream3900' (hdl 1; errno=0) [nixxi.cpp 3224]

[Thr 1] *** WARNING => NiIBind: Could not bind local domain socket, only listening on internet socket [nixxi.cpp 3501]

[Thr 1] load acl file = /usr/sap/PE2/SYS/global/ms_acl_info

[Thr 1] MsGetOwnIpAddr: my host addresses are :

[Thr 1] 1 : [10.11.225.113] DBMCI001 (HOSTNAME)

[Thr 1] 2 : [127.0.0.1] localhost (LOCALHOST)

[Thr 1] MsHttpInit: full qualified hostname = dbmap001.ashokleyland.com

[Thr 1] HTTP logging is switch off

[Thr 1] ***LOG Q0I=> NiIBindSocket: could not delete file '/tmp/.sapstream8100' for hdl 2: unlink (1: Not owner) [nixxi.cpp 3219]

[Thr 1] *** ERROR => NiIBindSocket: could not delete file '/tmp/.sapstream8100' (hdl 2; errno=0) [nixxi.cpp 3224]

[Thr 1] *** WARNING => NiIBind: Could not bind local domain socket, only listening on internet socket [nixxi.cpp 3501]

[Thr 1] set HTTP state to LISTEN

[Thr 1] MsHttpOwnDomain: own domain[1] = ashokleyland.com

[Thr 1] ms/icf_info_server : deleted

[Thr 1] *** I listen to port sapmsPE2 (3600) ***

[Thr 1] *** I listen to internal port 3900 (3900) ***

[Thr 1] *** HTTP port 8100 state LISTEN ***

[Thr 1] CUSTOMER KEY: >J0498511451<

[Thr 1] build version=701.2008.03.06

[Thr 1] Tue Jun 21 15:03:17 2011

[Thr 1] MsHttpReadUrl2: H-1 url = /sap/public/icf_info/urlprefix (403 Forbidden)

[Thr 1] MsHttpReadUrl2: H-1 url = /sap/public/icf_info/logon_groups (403 Forbidden)

[Thr 1] Tue Jun 21 15:04:15 2011

[Thr 1] MsSExit: received SIGINT (2)

[Thr 1] ***LOG Q02=> MsSHalt, MSStop (Msg Server 29627) [msxxserv_mt. 6016]

trc file: "dev_w0", trc level: 1, release: "701"

ACTIVE TRACE LEVEL 1
ACTIVE TRACE COMPONENTS all, MJ

M sysno 01

M sid PE2

M systemid 274 (HP (IA-64) with HP-UX)

M relno 7010

M patchlevel 0

M patchno 32

M intno 20020600

M make: single threaded, Unicode, 64 bit, optimized

M pid 569

M Tue Jun 21 15:22:11 2011

M kernel runs with dp version 241000(ext=110000) (@(#) DPLIB-INT-VERSION-241000-UC)

M length of sys_adm_ext is 576 bytes

M ***LOG Q01=> ThInit, WPStart (Workproc 0 1 569) [thxxhead.c 1290]

M ThInit: running on host DBMCI001

M calling db_connect ...

C Oracle Client Version: '10.2.0.4.0'

C Client NLS setting (OCINlsGetInfo): 'AMERICAN_AMERICA.UTF8'

C Logon as OPS$-user to get SAPSR3's password

C Connecting as /@PE2 on connection 0 (nls_hdl 0) ... (dbsl 700 151208)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 UTF8 0 0x6000000003511cf0 0x6000000003519810 0x6000000003529fe8

C Attaching to DB Server PE2 (con_hdl=0,svchp=0x6000000003529f18,srvhp=0x600000000352ccc8)

C Starting user session: OCISessionBegin(con_hdl=0, usr='/',svchp=0x6000000003529f18, srvhp=0x600000000352ccc8, usrhp=0x60000000035a45b0)

C CbApplInfoGet() failed! Ignore, but uninstall callback to avoid more erroneous calls

C Now '/@PE2' is connected: con_hdl=0, nls_hdl=0, session_id=160.

C Got SAPSR3's password from OPS$-user

C Disconnecting from connection 0 ...

C Closing user session (con_hdl=0,svchp=0x6000000003529f18,usrhp=0x60000000035a45b0)

C Now I'm disconnected from ORACLE

C Connecting as SAPSR3/<pwd>@PE2 on connection 0 (nls_hdl 0) ... (dbsl 700 151208)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 UTF8 0 0x6000000003511cf0 0x6000000003519810 0x6000000003529fe8

C Starting user session: OCISessionBegin(con_hdl=0, usr=SAPSR3/<pwd>, svchp=0x6000000003529f18, srvhp=0x600000000352ccc8, usrhp=0x60000000035a45b0)

C Now 'SAPSR3/<pwd>@PE2' is connected: con_hdl=0, nls_hdl=0, session_id=160.

C Database NLS settings: AMERICAN_AMERICA.UTF8

C DB instance PE2 is running on DBMDB001 with ORACLE version 10.2.0.4.0 since JUN 21, 2011, 15:09:26

B Connection 0 opened (DBSL handle 0)

B Wp Hdl ConName ConId ConState TX PRM RCT TIM MAX OPT Date Time DBHost

B 000 000 R/3 000000000 ACTIVE NO YES NO 000 255 255 20110621 152211 DBMDB001

C build_stmt: reallocating stmt buffer: 256 -> 2000 characters

M db_connect o.k.

M ICT: exclude compression: .zip,.cs,.rar,.arj,.z,.gz,.tar,.lzh,.cab,.hqx,.ace,.jar,.ear,.war,.css,.pdf,.js,.gzip,.uue,.bz2,.iso,.sda,.sar,.gif,.swc,.swf

I MtxInit: 0 0 0

M SHM_PRES_BUF (addr: 0xc00000001b975000, size: 4400000)

M SHM_ROLL_AREA (addr: 0xc000000034000000, size: 134217728)

M SHM_PAGING_AREA (addr: 0xc000000140000000, size: 67108864)

M SHM_ROLL_ADM (addr: 0xc00000001bda9000, size: 2672386)

M SHM_PAGING_ADM (addr: 0xc00000000e040000, size: 656416)

M ThCreateNoBuffer allocated 560152 bytes for 1000 entries at 0xc00000014c002000

M ThCreateNoBuffer index size: 3000 elems

M ThCreateVBAdm allocated 12176 bytes (50 server) at 0xc00000000bf5a000

X EmInit: MmSetImplementation( 2 ).

X MM global diagnostic options set: 0

X <ES> client 0 initializing ....

X Using implementation std

X <ES> Info: use normal pages (no huge table support available)

X ES initialized.

B dbntab: NTAB buffers created

B dbntab: Buffer FTAB(hash header) (addr: 0xc00000014c08c090, size: 584)

B dbntab: Buffer FTAB(anchor array) (addr: 0xc00000014c08c2e0, size: 320072)

B dbntab: Buffer FTAB(item array) (addr: 0xc00000014c0da530, size: 1280000)

B dbntab: Buffer FTAB(data area) (addr: 0xc00000014c212d30, size: 30720000)

B dbntab: Buffer IREC(hash header) (addr: 0xc00000014df60090, size: 584)

B dbntab: Buffer IREC(anchor array) (addr: 0xc00000014df602e0, size: 320072)

B dbntab: Buffer IREC(item array) (addr: 0xc00000014dfae530, size: 320000)

B dbntab: Buffer IREC(data area) (addr: 0xc00000014dffc730, size: 6144000)

B dbntab: Buffer STAB(hash header) (addr: 0xc00000014e5da090, size: 584)

B dbntab: Buffer STAB(anchor array) (addr: 0xc00000014e5da2e0, size: 320072)

B dbntab: Buffer STAB(item array) (addr: 0xc00000014e628530, size: 320000)

B dbntab: Buffer STAB(data area) (addr: 0xc00000014e676730, size: 3072000)

B dbntab: Buffer TTAB(hash header) (addr: 0xc00000014e966090, size: 2248)

B dbntab: Buffer TTAB(anchor array) (addr: 0xc00000014e966960, size: 320072)

B dbntab: Buffer TTAB(item array) (addr: 0xc00000014e9b4bb0, size: 800000)

B dbntab: Buffer TTAB(data area) (addr: 0xc00000014ea780b0, size: 5840000)

B Tue Jun 21 15:22:14 2011

B dbstat: table statistics switched on for 84569 tables

B dbstat: TABSTAT buffer created (addr: 0xc00000003c000000, size: 41326240)

B db_con_shm_ini: WP_ID = 0, WP_CNT = 16, CON_ID = -1

B dbtbxbuf: Buffer TABL (addr: 0xc000000022000110, size: 30000000, end: 0xc000000023c9c490)

B dbtbxbuf: Profile: max_objects = 5000, displace = 1, reorg = 1

B dbtbxbuf: request_unit = 2000, sync_reload = 5, inval_reload = 5

B dbtbxbuf: protect_shm = 0, force_checks = 0

B dbtbxbuf: tsize_retry = 14204416

B ***LOG BB0=> buffer TABL started with length 30000000 bytes [dbtbxbuf#2 @ 16202] [dbtbxbuf1620 2]

B dbtbxbuf: Buffer TABLP (addr: 0xc000000016400110, size: 10240000, end: 0xc000000016dc4110)

B dbtbxbuf: Profile: max_objects = 500, displace = 1, reorg = 1

B dbtbxbuf: request_unit = 2000, sync_reload = 5, inval_reload = 5

B dbtbxbuf: protect_shm = 0, force_checks = 0

B dbtbxbuf: tsize_retry = 5030912

B ***LOG BB0=> buffer TABLP started with length 10240000 bytes [dbtbxbuf#2 @ 16202] [dbtbxbuf1620 2]

B dbtbxbuf: Reading TBX statistics:

B dbtbxbuf: 23 object entries precreated

B dbexpbuf[EXP_SHB]: Aquired semaphore 35 in db_exp_init(EXP_INIT)

B Layout of EIBUF buffer shared memory:

B 0: 1 * 4 = 4

B 1: 1 * 464 = 464

B 2: 16 * 40 = 640

B 3: 4001 * 64 = 256064

B 4: 2000 * 256 = 512000

B 5: 4001 * 8 = 32008

B 6: 1 * 200 = 200

B 7: 65 * 8 = 520

B 8: 13251 * 256 = 3392256

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

B dbexpbuf: Buffer EIBUF (addr: 0xc000000017000120, size: 4194304, end: 0xc000000017400120)

B ***LOG BB0=> buffer EIBUF started with length 4096k bytes [dbexpbuf#2 @ 2379] [dbexpbuf2379 ]

B dbexpbuf[EXP_SHB]: semaphore 35 released in db_exp_init

B dbexpbuf[EXP_SHM]: Aquired semaphore 56 in db_exp_init(EXP_INIT)

B Layout of ESM buffer shared memory:

B 0: 1 * 4 = 4

B 1: 1 * 464 = 464

B 2: 16 * 40 = 640

B 3: 4001 * 64 = 256064

B 4: 2000 * 256 = 512000

B 5: 4001 * 8 = 32008

B 6: 1 * 200 = 200

B 7: 65 * 8 = 520

B 8: 13251 * 256 = 3392256

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

B dbexpbuf: Buffer ESM (addr: 0xc000000017800120, size: 4194304, end: 0xc000000017c00120)

B ***LOG BB0=> buffer ESM started with length 4096k bytes [dbexpbuf#2 @ 2379] [dbexpbuf2379 ]

B dbexpbuf[EXP_SHM]: semaphore 56 released in db_exp_init

B dbexpbuf[EXP_CUA]: Aquired semaphore 30 in db_exp_init(EXP_INIT)

B Layout of CUA buffer shared memory:

B 0: 1 * 4 = 4

B 1: 1 * 464 = 464

B 2: 16 * 40 = 640

B 3: 3001 * 64 = 192064

B 4: 1500 * 256 = 384000

B 5: 3001 * 8 = 24008

B 6: 1 * 200 = 200

B 7: 193 * 8 = 1544

B 8: 4822 * 512 = 2468864

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

B dbexpbuf: Buffer CUA (addr: 0xc00000014f00f120, size: 3072000, end: 0xc00000014f2fd120)

B ***LOG BB0=> buffer CUA started with length 3000k bytes [dbexpbuf#2 @ 2379] [dbexpbuf2379 ]

B dbexpbuf[EXP_CUA]: semaphore 30 released in db_exp_init

B dbexpbuf[EXP_OTR]: Aquired semaphore 55 in db_exp_init(EXP_INIT)

B Layout of OTR buffer shared memory:

B 0: 1 * 4 = 4

B 1: 1 * 464 = 464

B 2: 16 * 40 = 640

B 3: 4001 * 64 = 256064

B 4: 2000 * 256 = 512000

B 5: 4001 * 8 = 32008

B 6: 1 * 200 = 200

B 7: 81 * 8 = 648

B 8: 13251 * 256 = 3392256

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

B dbexpbuf: Buffer OTR (addr: 0xc00000001f800120, size: 4194304, end: 0xc00000001fc00120)

B ***LOG BB0=> buffer OTR started with length 4096k bytes [dbexpbuf#2 @ 2379] [dbexpbuf2379 ]

B dbexpbuf[EXP_OTR]: semaphore 55 released in db_exp_init

B ***LOG BB0=> buffer CALE started with length 500000 bytes [dbcalbuf#3 @ 2303] [dbcalbuf2303 ]

B dbtran INFO (init_connection '<DEFAULT>' [ORACLE:700.08]):

B max_blocking_factor = 5, max_in_blocking_factor = 5,

B min_blocking_factor = 5, min_in_blocking_factor = 5,

B prefer_union_all = 0, prefer_join = 0,

B prefer_fix_blocking = 0, prefer_in_itab_opt = 1,

B convert AVG = 0, alias table FUPD = 0,

B escape_as_literal = 1, opt GE LE to BETWEEN = 0,

B select * =0x0f, character encoding = STD / <none>:-,

B use_hints = abap->1, dbif->0x1, upto->2147483647, rule_in->0,

B rule_fae->0, concat_fae->0, concat_fae_or->0

page -- 1

Sorry guys, my internet is slow.. taking time to upload the logs.....

log dev_w0 is so long so i am sharing in many parts.

page 1 for dev_w0

trc file: "dev_w0", trc level: 1, release: "701"

ACTIVE TRACE LEVEL 1
ACTIVE TRACE COMPONENTS all, MJ