disp+work stopped after emergency shutdown

Former Member · ‎11-02-2007

Hi All,

I had a emergency shutdown of the server and noticed that the disp+work did not start. I have checked the Syslog within MMC and noticed that the workprocess got killed...

I could login to oracle with conn / as sysdba and cannot see any problem with oracle.

I have copied the following files. Could anybody tell me what has gone wrong after a emergency shutdown without proper sap shutdown in place.

I have done a system copy from BOS to WS1. system name: CABSPROD to WEBSERVER

-

trc file: "dev_disp", trc level: 1, release: "620"

-

Thu Nov 01 12:41:12 2007

kernel runs with dp version 3(ext=1) (@(#) DPLIB-INT-VERSION-3)

length of sys_adm_ext is 304 bytes

systemid 560 (PC with Windows NT)

relno 6200

patchlevel 0

patchno 674

intno 20020600

pid 132

***LOG Q00=> DpSapEnvInit, DPStart (00 132) [dpxxdisp.c 978]

shared lib "dw_xml.dll" version 674 successfully loaded

shared lib "dw_xtc.dll" version 674 successfully loaded

shared lib "dw_stl.dll" version 674 successfully loaded

Thu Nov 01 12:41:24 2007

WARNING => DpNetCheck: NiHostToAddr(www.doesnotexist0001.qqq.nxst) took 12 seconds

Thu Nov 01 12:41:40 2007

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

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

MtxInit: -2 0 0

DpShMCreate: sizeof(wp_adm) 12784 (752)

DpShMCreate: sizeof(tm_adm) 1690816 (8412)

DpShMCreate: sizeof(wp_ca_adm) 18000 (60)

DpShMCreate: sizeof(appc_ca_adm) 6000 (60)

DpShMCreate: sizeof(comm_adm) 192000 (384)

DpShMCreate: sizeof(wall_adm) (22440/34344/56/100)

DpShMCreate: SHM_DP_ADM_KEY (addr: 04430040, size: 1977824)

DpShMCreate: allocated sys_adm at 04430040

DpShMCreate: allocated wp_adm at 04430560

DpShMCreate: allocated tm_adm_list at 04433750

DpShMCreate: allocated tm_adm at 04433778

DpShMCreate: allocated wp_ca_adm at 045D0438

DpShMCreate: allocated appc_ca_adm at 045D4A88

DpShMCreate: allocated comm_adm_list at 045D61F8

DpShMCreate: allocated comm_adm at 045D6210

DpShMCreate: allocated ca_info at 04605010

DpShMCreate: allocated wall_adm at 04605018

MBUF state OFF

Thu Nov 01 12:41:41 2007

EmInit: MmSetImplementation( 2 ).

<ES> client 0 initializing ....

<ES> InitFreeList

<ES> block size is 1024 kByte.

Using implementation std

<EsNT> Memory Reset enabled as NT default

<EsNT> EsIUnamFileMapInit: Initialize the memory 2458 MB

<ES> 2457 blocks reserved for free list.

ES initialized.

Thu Nov 01 12:41:42 2007

***LOG Q0K=> DpMsAttach, mscon ( CABSPROD) [dpxxdisp.c 9115]

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

CCMS: start to initalize 3.X shared alert area (first segment).

ERROR => platform patchno is 1773 [dpxxdisp.c 13280]
ERROR => but my patchno is 674 [dpxxdisp.c 13281]
DP_FATAL_ERROR => Release inconsistent with running system
DISPATCHER EMERGENCY SHUTDOWN ***

DpModState: change server state from STARTING to SHUTDOWN

Thu Nov 01 12:42:17 2007

***LOG Q0M=> DpMsDetach, ms_detach () [dpxxdisp.c 9341]

***LOG Q05=> DpHalt, DPStop ( 132) [dpxxdisp.c 7883]

-

dev_w0

-

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

-

*

ACTIVE TRACE LEVEL 1
ACTIVE TRACE COMPONENTS all, M

*

B

B Thu Nov 01 13:10:14 2007

B create_con (con_name=R/3)

B Loading DB library 'D:\usr\sap\WS1\SYS\exe\run\dboraslib.dll' ...

B Library 'D:\usr\sap\WS1\SYS\exe\run\dboraslib.dll' loaded

B Version of 'D:\usr\sap\WS1\SYS\exe\run\dboraslib.dll' is "620.02", patchlevel (0.549)

B New connection 0 created

M systemid 560 (PC with Windows NT)

M relno 6200

M patchlevel 0

M patchno 674

M intno 20020600

M pid 1224

M

M ***LOG Q0Q=> tskh_init, WPStart (Workproc 0 1224) [dpxxdisp.c 1028]

I

I Thu Nov 01 13:10:17 2007

I MtxInit: -2 0 0

M DpShMCreate: sizeof(wp_adm) 12784 (752)

M DpShMCreate: sizeof(tm_adm) 1690816 (8412)

M DpShMCreate: sizeof(wp_ca_adm) 18000 (60)

M DpShMCreate: sizeof(appc_ca_adm) 6000 (60)

M DpShMCreate: sizeof(comm_adm) 192000 (384)

M DpShMCreate: sizeof(wall_adm) (22440/34344/56/100)

M DpShMCreate: SHM_DP_ADM_KEY (addr: 04C30040, size: 1977824)

M DpShMCreate: allocated sys_adm at 04C30040

M DpShMCreate: allocated wp_adm at 04C30560

M DpShMCreate: allocated tm_adm_list at 04C33750

M DpShMCreate: allocated tm_adm at 04C33778

M DpShMCreate: allocated wp_ca_adm at 04DD0438

M DpShMCreate: allocated appc_ca_adm at 04DD4A88

M DpShMCreate: allocated comm_adm_list at 04DD61F8

M DpShMCreate: allocated comm_adm at 04DD6210

M DpShMCreate: allocated ca_info at 04E05010

M DpShMCreate: allocated wall_adm at 04E05018

X EmInit: MmSetImplementation( 2 ).

X <ES> client 0 initializing ....

X Using implementation std

M <EsNT> Memory Reset enabled as NT default

X ES initialized.

M

M Thu Nov 01 13:10:18 2007

M calling db_connect ...

C Got ORACLE_HOME=D:\oracle\ora92 from environment

C Client NLS settings: AMERICAN_AMERICA.WE8DEC

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

C Connecting as /@WS1 on connection 0 ...

C Attaching to DB Server WS1 (con_hdl=0,svchp=063842FC,svrhp=06384ED4)

C Starting user session (con_hdl=0,svchp=063842FC,srvhp=06384ED4,usrhp=0638D6AC)

C

C Thu Nov 01 13:10:19 2007

C Now I'm connected to ORACLE

C *** ERROR => ORA-1403 when accessing table SAPUSER

[dbsloci.c 9946]

C Disconnecting from connection 0 ...

C Closing user session (con_hdl=0,svchp=063842FC,usrhp=0638D6AC)

C Now I'm disconnected from ORACLE

C Try to connect with default password

C Connecting as SAPBOS/<pwd>@WS1 on connection 0 ...

C Starting user session (con_hdl=0,svchp=063842FC,srvhp=06384ED4,usrhp=0638D6AC)

C Now I'm connected to ORACLE

C Database NLS settings: AMERICAN_AMERICA.WE8DEC

C Database instance ws1 is running on WEBSERVER with ORACLE version 9.2.0.2.1 since 20071101

B Connection 0 opened

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

B 000 000 R/3 000000000 ACTIVE NO YES NO 20071101 131018 WEBSERVER

M db_connect o.k.

I

I Thu Nov 01 13:10:20 2007

I MtxInit: 0 0 0

X EmInit: MmSetImplementation( 2 ).

X <ES> client 0 initializing ....

X Using implementation std

X ES initialized.

B db_con_shm_ini: WP_ID = 0, WP_CNT = 17

B dbtbxbuf: Buffer TABL (addr: 136E00C8, size: 30000000, end: 1537C448)

B dbtbxbuf: Buffer TABLP (addr: 153800C8, size: 10240000, end: 15D440C8)

B dbexpbuf: Buffer EIBUF (addr: 15D500D0, size: 4194304, end: 161500D0)

B dbexpbuf: Buffer ESM (addr: 161600D0, size: 4194304, end: 165600D0)

B dbexpbuf: Buffer CUA (addr: 165700D0, size: 3072000, end: 1685E0D0)

B dbexpbuf: Buffer OTR (addr: 168600D0, size: 4194304, end: 16C600D0)

M rdisp/reinitialize_code_page -> 0

B

B Thu Nov 01 13:10:21 2007

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

B max_blocking_factor = 15, max_in_blocking_factor =1000,

B min_blocking_factor = 10, min_in_blocking_factor = 10,

B prefer_union_all = 0, prefer_union_for_select_all = 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 =SBCS / <none>:-,

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

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

S *** init spool environment

S initialize debug system

T Stack direction is downwards.

T debug control: prepare exclude for printer trace

T new memory block 06529130

S spool kernel/ddic check: Ok

S using table TSP02FX for frontend printing

S 1 spool work process(es) found

S frontend print via spool service enabled

S printer list size is 150

S printer type list size is 50

S queue size (profile) = 300

S hostspool list size = 3000

S option list size is 30

S intervals: query=50, rescan=1800, global=300 info=120

S processing queue enabled

S creating spool memory service RSPO-RCLOCKS at 0FF20098

S doing lock recovery

S setting server cache root

S using server cache size 100 (prof=100)

S creating spool memory service RSPO-SERVERCACHE at 0FF20488

S using messages for server info

S size of spec char cache entry: 164980 bytes (timeout 100 sec)

S size of open spool request entry: 1144 bytes

S immediate print option for implicitely closed spool requests is disabled

A

A ~~-PXA~~--

A PXA INITIALIZATION

A PXA allocated (address 48880040, size 150000K)

A System name

A ORACLE...........................WS1........20071022121026.....................................

A is used for RFC security.

A Sharedbuffer token: 5341...33 (len: 111)====== 15a6256c36175370e38cb943...

A abap/pxa = shared gen_remote

A PXA INITIALIZATION FINISHED

A ~~-PXA~~--

A

A RFC Destination> destination Webserver_WS1_00 host Webserver system WS1 systnr 0 (Webserver_WS1_00)

A RFC Options> H=Webserver,S=00

A RFC FRFC> fallback ist not active.

A RFC rfc/signon_error_log = -1

A RFC rfc/dump_connection_info = 0

A RFC rfc/dump_client_info = 0

A RFC rfc/cp_convert/ignore_error = 1

A RFC rfc/cp_convert/conversion_char = 23

A RFC rfc/wan_compress/threshold = 251

A RFC rfc/recorder_pcs not set, use defaule value: 1

A RFC rfc/no_uuid_check not set, use default value: 0

A RFC Method> initialize RemObjDriver for ABAP Objects

A Hotpackage version: 8

M M ThVBStartUp: restart pending update requests

M ThVBAutoStart: update-auto-delete

M in_ThErrHandle: 1

M ThIErrHandle: I'm during shutdown

M Entering ThSetStatError

M ThIErrHandle: normal shutdown

M Entering ThReadDetachMode

M call ThrShutDown (1)...

M ***LOG Q02=> wp_halt, WPStop (Workproc 0 1224) [dpnttool.c 346]

M SemCleanup: Reset blocking on semaphore (Key:50/Slot:49)

-

SAPSTART.TRC

-

WEBSERVER\ws1adm is starting R/3 System at 2007/11/01 11:56:38

SAP-R/3-Startup Program

Starting at 2007/11/01 11:56:39

Startup Profile: "D:\usr\sap\WS1\SYS\profile\START_DVEBMGS00_Webserver"

Starting Programs

(3536) Execute: D:\usr\sap\WS1\SYS\exe\run\strdbs.cmd WS1

(3732) CreateProcess: D:\usr\sap\WS1\SYS\exe\run\msg_server.exe pf=D:\usr\sap\WS1\SYS\profile\WS1_DVEBMGS00_Webserver

(3752) CreateProcess: D:\usr\sap\WS1\SYS\exe\run\disp+work.exe pf=D:\usr\sap\WS1\SYS\profile\WS1_DVEBMGS00_Webserver

WEBSERVER\ws1adm is stopping R/3 System at 2007/11/01 12:05:33

(1152) Parent Shutdown at 2007/11/01 12:05:33

(1152) kill(3752) failed.

(1152) Parent Shutdown at 2007/11/01 12:07:13

(1152) kill(3752) failed.

(1152) kill(3732) failed.

WEBSERVER\ws1adm is starting R/3 System at 2007/11/01 12:41:08

SAP-R/3-Startup Program

Starting at 2007/11/01 12:41:08

Startup Profile: "D:\usr\sap\WS1\SYS\profile\START_DVEBMGS00_Webserver"

Starting Programs

(3580) Execute: D:\usr\sap\WS1\SYS\exe\run\strdbs.cmd WS1

(444) CreateProcess: D:\usr\sap\WS1\SYS\exe\run\msg_server.exe pf=D:\usr\sap\WS1\SYS\profile\WS1_DVEBMGS00_Webserver

(132) CreateProcess: D:\usr\sap\WS1\SYS\exe\run\disp+work.exe pf=D:\usr\sap\WS1\SYS\profile\WS1_DVEBMGS00_Webserver

-

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

-

[Thr 1696] Thu Nov 01 12:41:09 2007

[Thr 1696] MsSSetTrcLog: trc logging not active

[Thr 1696] relno 6200

[Thr 1696] patchlevel 0

[Thr 1696] patchno 665

[Thr 1696] intno 20020600

[Thr 1696] pid 444

[Thr 1696] ***LOG Q01=> MsSInit, MSStart (Msg Server 1 444) [msxxserv.c 1524]

[Thr 1696] MsHttpPortInsert: set HTTP state to LISTEN

[Thr 1696] *** I listen to port sapmsWS1 (3600) ***

[Thr 1696] *** HTTP LISTEN ***

[Thr 1696] *** I listen to HTTP port 8100 ***

[Thr 1696] CUSTOMER KEY: >H0284666948<

-

stderr4

-

IcmBndMain: handle for "localhost:8000" successfully sent to server

maximum pfclock time: 4294sec (1)