Restoration completed but SAP is not getting started

Question: Dear All,

I am facing problem.Pls help me.Right now i am at client site.

The client has ECC 5.0 which is on windows 2003 server with oracle database.We have taken full offline backup.Now the backup is restored on another server on which ECC 5.0 is installed with same OS and database.

But now after restoration we restarted the server.But SAP is not starting.The dispatcher turns to grey colour.

In SAP MMC i checked for SYSLOG there i am able to see some error regarding"DB Connect failed with error code 000256".

Pls help and let me know what other information you want from me.

Regards,
Anil Shenoy.

Answer:
what is with the sap logs in x:\usr\sap\<SID>\DVEBMGSxx\work ?

like dev_?????

what is the result of "R3trans -d"

is "tnsping <SID>" working ?

is Oracle up and running ?

Where did you learn SAP Basis ?

Answer:
Hi,

My database itself is not getting connected and also in SAP MMC Syslog it is showing SAP Basis operating system collector recvd failed.

I also created a control file from command promt.

Anything else information do you want from my side.

Pls help me..

Regards,
Anil Shenoy.

Answer:
anilks,

please answer all the questions asked by dxaver. If you do not answer those, we will not be able to help you.

1 thing: do you see directories X:\usr\sap\{SID} and X:\oracle\{SID} ?
_________________
SapFans Moderator
NetWeaver ‘04–SAP Web AS for ORACLE certified

Search: /forums/search.php
SAP Notes: http://service.sap.com/notes
SAP Help: http://help.sap.com
Basic Rules: /forums/viewtopic.php?t=222759

Answer:
Anilks,
At lower level, check your Oracle alert file to see if the DB is up ...
Is listener also up?

Answer:
Dear All,


Below i have mentioned log of dev_w0


---------------------------------------------------
trc file: "dev_w0", trc level: 1, release: "640"
---------------------------------------------------
*
* ACTIVE TRACE LEVEL 1
* ACTIVE TRACE COMPONENTS all, M
*
B
B Sat May 13 10:26:34 2006
B create_con (con_name=R/3)
B Loading DB library 'F:\usr\sap\DEV\SYS\exe\run\dboraslib.dll' ...
B Library 'F:\usr\sap\DEV\SYS\exe\run\dboraslib.dll' loaded
B Version of 'F:\usr\sap\DEV\SYS\exe\run\dboraslib.dll' is "640.00", patchlevel (0.39)
B New connection 0 created
M systemid 561 (PC with Windows NT)
M relno 6400
M patchlevel 0
M patchno 43
M intno 20020600
M make: multithreaded, ASCII, 64 bit
M pid 5676
M
M ***LOG Q0Q=> tskh_init, WPStart (Workproc 0 5676) [dpxxdisp.c 1160]
I MtxInit: -2 0 0
M DpSysAdmExtCreate: ABAP is active
M DpSysAdmExtCreate: JAVA is not active
M DpShMCreate: sizeof(wp_adm) 6624 (828)
M DpShMCreate: sizeof(tm_adm) 2847776 (14168)
M DpShMCreate: sizeof(wp_ca_adm) 18000 (60)
M DpShMCreate: sizeof(appc_ca_adm) 6000 (60)
M DpShMCreate: sizeof(comm_adm) 212000 (424)
M DpShMCreate: sizeof(vmc_adm) 0 (400)
M DpShMCreate: sizeof(wall_adm) (25648/36736/64/104)
M DpShMCreate: SHM_DP_ADM_KEY (addr: 000000000A3D0050, size: 3159136)
M DpShMCreate: allocated sys_adm at 000000000A3D0050
M DpShMCreate: allocated wp_adm at 000000000A3D1850
M DpShMCreate: allocated tm_adm_list at 000000000A3D3230
M DpShMCreate: allocated tm_adm at 000000000A3D3260
M DpShMCreate: allocated wp_ca_adm at 000000000A68A680
M DpShMCreate: allocated appc_ca_adm at 000000000A68ECD0
M DpShMCreate: allocated comm_adm_list at 000000000A690440
M DpShMCreate: allocated comm_adm at 000000000A690460
M DpShMCreate: allocated vmc_adm_list at 000000000A6C4080
M DpShMCreate: system runs without vmc_adm
M DpShMCreate: allocated ca_info at 000000000A6C40B0
M DpShMCreate: allocated wall_adm at 000000000A6C40C0
X EmInit: MmSetImplementation( 2 ).
X <ES> client 0 initializing ....
X Using implementation flat
M <EsNT> Memory Reset disabled as NT default
X ES initialized.
M
M Sat May 13 10:26:35 2006
M calling db_connect ...
C Got ORACLE_HOME=F:\oracle\DEV_920_64 from environment
C Client NLS settings: AMERICAN_AMERICA.WE8DEC
C Logon as OPS$-user to get SAPDEV's password
C Connecting as /@DEV on connection 0 ...
C Attaching to DB Server DEV (con_hdl=0,svchp=000000000F9EBFA8,svrhp=000000000F9EE5A8)
C Starting user session (con_hdl=0,svchp=000000000F9EBFA8,srvhp=000000000F9EE5A8,usrhp=000000000F9F82A0)
C *** ERROR => OCI-call 'OCISessionBegin' failed: rc = 1017
[dboci.c 3720]
C *** ERROR => CONNECT failed with sql error '1017'
[dbsloci.c 9750]
C Try to connect with default password
C Connecting as SAPDEV/<pwd>@DEV on connection 0 ...
C Starting user session (con_hdl=0,svchp=000000000F9EBFA8,srvhp=000000000F9EE5A8,usrhp=000000000F9F82A0)
C *** ERROR => OCI-call 'OCISessionBegin' failed: rc = 1017
[dboci.c 3720]
C *** ERROR => CONNECT failed with sql error '1017'
[dbsloci.c 9750]
B ***LOG BY2=> sql error 1017 performing CON [dbsh#2 @ 1200] [dbsh 1200 ]
B ***LOG BY0=> ORA-01017: invalid username/password; logon denied [dbsh#2 @ 1200] [dbsh 1200 ]
B ***LOG BY2=> sql error 1017 performing CON [dblink#1 @ 419] [dblink 0419 ]
B ***LOG BY0=> ORA-01017: invalid username/password; logon denied [dblink#1 @ 419] [dblink 0419 ]
M ***LOG R19=> tskh_init, db_connect ( DB-Connect 000256) [thxxhead.c 1269]
M in_ThErrHandle: 1
M *** ERROR => tskh_init: db_connect (step 1, th_errno 13, action 3, level 1) [thxxhead.c 9413]
M
M Info for wp 0
M
M stat = 4
M reqtype = 1
M act_reqtype = -1
M rq_info = 0
M tid = -1
M mode = 255
M len = -1
M rq_id = 65535
M rq_source = 255
M last_tid = 0
M last_mode = 0
M int_checked_resource(RFC) = 0
M ext_checked_resource(RFC) = 0
M int_checked_resource(HTTP) = 0
M ext_checked_resource(HTTP) = 0
M report = > <
M action = 0
M tab_name = > <
M
M *****************************************************************************
M *
M * LOCATION SAP-Server LUXDEV_DEV_00 on host LUXDEV (wp 0)
M * ERROR tskh_init: db_connect
M *
M * TIME Sat May 13 10:26:35 2006
M * RELEASE 640
M * COMPONENT Taskhandler
M * VERSION 1
M * RC 13
M * MODULE thxxhead.c
M * LINE 9589
M * COUNTER 1
M *
M *****************************************************************************
M
M PfStatDisconnect: disconnect statistics
M Entering TH_CALLHOOKS
M ThCallHooks: call hook >ThrSaveSPAFields< for event BEFORE_DUMP
M *** ERROR => ThrSaveSPAFields: no valid thr_wpadm [thxxrun1.c 730]
M *** ERROR => ThCallHooks: event handler ThrSaveSPAFields for event BEFORE_DUMP failed [thxxtool3.c 252]
M Entering ThSetStatError
M Entering ThReadDetachMode
M call ThrShutDown (1)...
M ***LOG Q02=> wp_halt, WPStop (Workproc 0 5676) [dpnttool.c 357]


Below i have mentioned log of dev_disp


---------------------------------------------------
trc file: "dev_disp", trc level: 1, release: "640"
---------------------------------------------------

Sat May 13 10:26:29 2006
kernel runs with dp version 128(ext=102) (@(#) DPLIB-INT-VERSION-128)
length of sys_adm_ext is 312 bytes
systemid 561 (PC with Windows NT)
relno 6400
patchlevel 0
patchno 43
intno 20020600
make: multithreaded, ASCII, 64 bit
pid 4680

***LOG Q00=> DpSapEnvInit, DPStart (00 4680) [dpxxdisp.c 1100]
shared lib "dw_xml.dll" version 43 successfully loaded
shared lib "dw_xtc.dll" version 43 successfully loaded
shared lib "dw_stl.dll" version 43 successfully loaded
shared lib "dw_gui.dll" version 43 successfully loaded

Sat May 13 10:26:33 2006
*** WARNING => DpNetCheck: NiAddrToHost(1.0.0.0) took 4 seconds
***LOG GZZ=> 1 possible network problems detected - check tracefile and adjust the DNS settings [dpxxtool2.c 3886]
MtxInit: -2 0 0
DpSysAdmExtInit: ABAP is active
DpSysAdmExtInit: JAVA is not active
DpShMCreate: sizeof(wp_adm) 6624 (828)
DpShMCreate: sizeof(tm_adm) 2847776 (14168)
DpShMCreate: sizeof(wp_ca_adm) 18000 (60)
DpShMCreate: sizeof(appc_ca_adm) 6000 (60)
DpShMCreate: sizeof(comm_adm) 212000 (424)
DpShMCreate: sizeof(vmc_adm) 0 (400)
DpShMCreate: sizeof(wall_adm) (25648/36736/64/104)
DpShMCreate: SHM_DP_ADM_KEY (addr: 000000000A3D0050, size: 3159136)
DpShMCreate: allocated sys_adm at 000000000A3D0050
DpShMCreate: allocated wp_adm at 000000000A3D1850
DpShMCreate: allocated tm_adm_list at 000000000A3D3230
DpShMCreate: allocated tm_adm at 000000000A3D3260
DpShMCreate: allocated wp_ca_adm at 000000000A68A680
DpShMCreate: allocated appc_ca_adm at 000000000A68ECD0
DpShMCreate: allocated comm_adm_list at 000000000A690440
DpShMCreate: allocated comm_adm at 000000000A690460
DpShMCreate: allocated vmc_adm_list at 000000000A6C4080
DpShMCreate: system runs without vmc_adm
DpShMCreate: allocated ca_info at 000000000A6C40B0
DpShMCreate: allocated wall_adm at 000000000A6C40C0
MBUF state OFF
EmInit: MmSetImplementation( 2 ).
<ES> client 0 initializing ....
<ES> InitFreeList
<ES> block size is 4096 kByte.
<ES> Info: em/initial_size_MB( 2450MB) not multiple of em/blocksize_KB( 4096KB)
<ES> Info: em/initial_size_MB rounded up to 2452MB
Using implementation flat
<EsNT> Memory Reset disabled as NT default
<ES> 612 blocks reserved for free list.
ES initialized.
rdisp/http_min_wait_dia_wp : 1 -> 1
***LOG Q0K=> DpMsAttach, mscon ( luxdev) [dpxxdisp.c 9736]
CCMS: Initalizing shared memory of size 40000000 for monitoring segment.

Sat May 13 10:26:34 2006
CCMS: start to initalize 3.X shared alert area (first segment).
DpMsgAdmin: Set release to 6400, patchlevel 0
MBUF state PREPARED
MBUF component UP
DpMBufHwIdSet: set Hardware-ID
***LOG Q1C=> DpMBufHwIdSet [dpxxmbuf.c 1025]
DpMsgAdmin: Set patchno for this platform to 43
Release check o.K.

Sat May 13 10:27:13 2006
*** ERROR => W0 (pid 5676) died [dpxxdisp.c 12187]
*** ERROR => W1 (pid 6076) died [dpxxdisp.c 12187]
my types changed after wp death/restart 0xbf --> 0xbe
*** ERROR => W2 (pid 5340) died [dpxxdisp.c 12187]
my types changed after wp death/restart 0xbe --> 0xbc
*** ERROR => W3 (pid 736) died [dpxxdisp.c 12187]
my types changed after wp death/restart 0xbc --> 0xb8
*** ERROR => W4 (pid 2252) died [dpxxdisp.c 12187]
*** ERROR => W5 (pid 548) died [dpxxdisp.c 12187]
my types changed after wp death/restart 0xb8 --> 0xb0
*** ERROR => W6 (pid 4996) died [dpxxdisp.c 12187]
my types changed after wp death/restart 0xb0 --> 0xa0
*** ERROR => W7 (pid 492) died [dpxxdisp.c 12187]
my types changed after wp death/restart 0xa0 --> 0x80
*** DP_FATAL_ERROR => DpWPCheck: no more work processes
*** DISPATCHER EMERGENCY SHUTDOWN ***
increase tracelevel of WPs
killing W0-5676 (SIGUSR2)
*** ERROR => DpWpKill(5676, SIGUSR2) failed [dpxxtool.c 2468]
killing W1-6076 (SIGUSR2)
*** ERROR => DpWpKill(6076, SIGUSR2) failed [dpxxtool.c 2468]
killing W2-5340 (SIGUSR2)
*** ERROR => DpWpKill(5340, SIGUSR2) failed [dpxxtool.c 2468]
killing W3-736 (SIGUSR2)
*** ERROR => DpWpKill(736, SIGUSR2) failed [dpxxtool.c 2468]
killing W4-2252 (SIGUSR2)
*** ERROR => DpWpKill(2252, SIGUSR2) failed [dpxxtool.c 2468]
killing W5-548 (SIGUSR2)
*** ERROR => DpWpKill(548, SIGUSR2) failed [dpxxtool.c 2468]
killing W6-4996 (SIGUSR2)
*** ERROR => DpWpKill(4996, SIGUSR2) failed [dpxxtool.c 2468]
killing W7-492 (SIGUSR2)
*** ERROR => DpWpKill(492, SIGUSR2) failed [dpxxtool.c 2468]
NiWait: sleep (10000 msecs) ...
NiISelect: timeout 10000 ms
NiISelect: maximum fd=1453
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Sat May 13 10:27:23 2006
NiISelect: TIMEOUT occured (10000 ms)
dump system status
Workprocess Table (long) Sat May 13 04:57:23 2006
========================

No Ty. Pid Status Cause Start Err Sem CPU Time Program Cl User Action Table
-----------------------------------------------------------------------------------------------------------------------
0 DIA 5676 Ended no 1 0 0
1 DIA 6076 Ended no 1 0 0
2 UPD 5340 Ended no 1 0 0
3 ENQ 736 Ended no 1 0 0
4 BTC 2252 Ended no 1 0 0
5 BTC 548 Ended no 1 0 0
6 SPO 4996 Ended no 1 0 0
7 UP2 492 Ended no 1 0 0
Dispatcher Queue Statistics Sat May 13 04:57:23 2006
===========================

+------+--------+--------+--------+--------+--------+
| Typ | now | high | max | writes | reads |
+------+--------+--------+--------+--------+--------+
| NOWP | 0 | 3 | 2000 | 10 | 10 |
+------+--------+--------+--------+--------+--------+
| DIA | 5 | 5 | 2000 | 5 | 0 |
+------+--------+--------+--------+--------+--------+
| UPD | 0 | 0 | 2000 | 0 | 0 |
+------+--------+--------+--------+--------+--------+
| ENQ | 0 | 0 | 2000 | 0 | 0 |
+------+--------+--------+--------+--------+--------+
| BTC | 0 | 0 | 2000 | 0 | 0 |
+------+--------+--------+--------+--------+--------+
| SPO | 0 | 0 | 2000 | 0 | 0 |
+------+--------+--------+--------+--------+--------+
| UP2 | 0 | 0 | 2000 | 0 | 0 |
+------+--------+--------+--------+--------+--------+


max_rq_id 13
wake_evt_udp_now 0

wake events total 9, udp 8 ( 88%), shm 1 ( 11%)
since last update total 9, udp 8 ( 88%), shm 1 ( 11%)


Dump of tm_adm structure: Sat May 13 04:57:23 2006
=========================

Term uid man user term lastop mod wp ta a/i (modes)

Workprocess Comm. Area Blocks Sat May 13 04:57:23 2006
=============================

Slots: 300, Used: 1, Max: 0
+------+--------------+----------+-------------+
| id | owner | pid | eyecatcher |
+------+--------------+----------+-------------+
| 0 | DISPATCHER | -1 | *WPCAAD000* |

NiWait: sleep (5000 msecs) ...
NiISelect: timeout 5000 ms
NiISelect: maximum fd=1453
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Sat May 13 10:27:28 2006
NiISelect: TIMEOUT occured (5000 ms)
Shutdown server ...
DpJ2eeDisableRestart
DpModState: buffer in state MBUF_PREPARED

NiBufSend starting
NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete
MsINiWrite: sent 110 bytes
MsIModState: change state to SHUTDOWN
DpModState: change server state from STARTING to SHUTDOWN
Switch off Shared memory profiling
ShmProtect( 57, 3 )
ShmProtect(SHM_PROFILE, SHM_PROT_RW
ShmProtect( 57, 1 )
ShmProtect(SHM_PROFILE, SHM_PROT_RD
DpWakeUpWps: wake up all wp's
Stop work processes...
Stop gateway
killing process (192) (SOFT_KILL)
Stop icman
killing process (1656) (SOFT_KILL)
Terminate gui connections
[DpProcDied] Process lives (PID:192 HANDLE:1428)
waiting for termination of gateway
NiWait: sleep (1000 msecs) ...
NiISelect: timeout 1000 ms
NiISelect: maximum fd=1453
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Sat May 13 10:27:29 2006
NiISelect: TIMEOUT occured (1000 ms)
[DpProcDied] Process died (PID:192 HANDLE:1428)
[DpProcDied] Process died (PID:1656 HANDLE:1420)
DpHalt: cancel all lcom connections
MPI CancelAll 2 -> 0
MPI DeleteAll 2 -> 0
NiIMyHostName: hostname = 'LUXDEV'
AdGetSelfIdentRecord: > <
AdCvtRecToExt: opcode 60 (AD_SELFIDENT), ser 0, ex 0, errno 0
AdCvtRecToExt: opcode 4 (AD_STARTSTOP), ser 0, ex 0, errno 0
DpConvertRequest: net size = 163 bytes

NiBufSend starting
NiIWrite: write 562, 1 packs, MESG_IO, hdl 3, data complete
MsINiWrite: sent 562 bytes
send msg (len 110+452) to name -, type 4, key -
detach from message server
***LOG Q0M=> DpMsDetach, ms_detach () [dpxxdisp.c 9962]

NiBufSend starting
NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete
MsINiWrite: sent 110 bytes
MsIDetach: send logout to msg_server
MsIDetach: call exit function
DpMsShutdownHook called
NiSelClear: removed hdl 3 from selectset
MBUF state OFF
AdGetSelfIdentRecord: > <
AdCvtRecToExt: opcode 60 (AD_SELFIDENT), ser 0, ex 0, errno 0
AdCvtRecToExt: opcode 40 (AD_MSBUF), ser 0, ex 0, errno 0
AdCvtRecToExt: opcode 40 (AD_MSBUF), ser 0, ex 0, errno 0
blks_in_queue/wp_ca_blk_no/wp_max_no = 1/300/8
LOCK WP ca_blk 1
make DISP owner of wp_ca_blk 1
DpRqPutIntoQueue: put request into queue (reqtype 1, prio LOW, rq_id 19)
MBUF component DOWN
NiBufClose: clear extensions for hdl 3
NiBufSetStat: bufstat of hdl 3 changed from OK to OFF
NiICloseHandle: shutdown and close hdl 3 / socket 1440
MsIDetach: detach MS-system
EsCleanup ....
***LOG Q05=> DpHalt, DPStop ( 4680) [dpxxdisp.c 8495]
Good Bye .....

Pls let me know what else information you want from me.

Regards,
Anil Shenoy.

Answer:
Dear All,

I have mentioned R3trans -d logs below

Microsoft Windows [Version 5.2.3790]
(C) Copyright 1985-2003 Microsoft Corp.

C:\Documents and Settings\devadm>r3trans -d
This is r3trans version 6.09 (release 640 - 26.10.04 - 15:45:42).
2EETW169 no connect possible: "DBMS = ORACLE --- dbs_o
ra_tnsname = 'DEV'"
r3trans finished (0012).

C:\Documents and Settings\devadm>

My oracle listener is running file but the problem is my dispatcher turns to grey colour after 15 sec.

Pls tell me the steps to start the database.

Now at present since no one is using this server i have stopped all the services of SAP,Oracle and also SAP MMC.

I would also like to tell you that in this server we have BW but it is installed on seperate drive i.e F: and SAP ECC 5.0 is installed in another drive i.e F:

Pls help me...

Regards,
Anil Shenoy.

Regards,
Anil Shenoy.

Answer:
It looks like a homogenous DB copy.

If you followed the steps for the ORACLE database outlined in the 'Homogenous and heterogenous system copy guide' for WAS 640 you shouldn't have a problem.

The guide is available in the SAP Service marketplace.

W11

Answer:
Hi All,

We have followed the document of www.basisconsultant.com.I downloaded homogenous system copy from this site.We followed the steps given in this document.Restoration was sucessfully completed.After restoration we executed control file script.This control file we are able to see in init<sid>.ora.
But we are not able to analyse the problem.

If anything else you want pls let me know

Pls help me...we are really struggling for this.

Regards,
Anil Shenoy.

Answer:
hi,

I think that your instance installation isn't in the same domain ( ex : one in local domain and the other in entreprise domain). If it is, move your instance in the same domain of the source instance.

best regards

steph
_________________
best regards
s.ami

Answer:
Hi,

Both the source and target system are in workgroup.But while installing Central Instance we have selected local installation.

We are not able to analyse why our dispatcher turns to grey after few seconds.

Pls help me..

Regards,
Anil Shenoy.

Answer:
Please check the correponding error in trans.log. you may have to change the password for the user SAPR3 and then try to connect.
_________________
krivis
Copyright ?2007 - 2008 www.jt77.com