Former Member

Dec 14, 2007 at 07:33 AM

Dispatcher problem

201 Views

Hi,

I am using r/3 4.7EE, oracle 9i, servers are on windows when trying to start the instance, mess server is coming up,

but the dispatcher is getting stopped,

before saying stopped, i am getting messages,

1.Running, but not connected to message server,

2.Running, but dialog queue standstill,

I have tried checking if there is a db problem, using R3trans -d, but the value returned is zero, and when i check services.msc, it shows the instance as started.

dev_ms says this:

[Thr 2744] Thu Dec 13 02:29:37 2007

[Thr 2744] *** ERROR => MsSClientHandle: no server provides service ENQ (4), requested from dlwsdv10_SM6_00 [msxxserv.c 4196]

[Thr 2744] Thu Dec 13 02:39:57 2007

[Thr 2744] *** ERROR => MsSCheckConnections: no response from client C0 (10.20.2.36) after 300 secs received [msxxserv.c 2919]

[Thr 2744] *** ERROR => MsSCheckConnections: disconnect now [10.20.2.36] [msxxserv.c 2923]

dev_w0 file:

-

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

-

*

ACTIVE TRACE LEVEL 1
ACTIVE TRACE COMPONENTS all, M

*

B

B Thu Dec 13 02:28:36 2007

B create_con (con_name=R/3)

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

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

B Version of 'D:\usr\sap\SM6\SYS\exe\run\dboraslib.dll' is "640.00", patchlevel (0.39)

B New connection 0 created

M systemid 560 (PC with Windows NT)

M relno 6400

M patchlevel 0

M patchno 43

M intno 20020600

M make: multithreaded, Unicode

M pid 4476

M

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

I MtxInit: -2 0 0

M DpSysAdmExtCreate: ABAP is active

M DpSysAdmExtCreate: JAVA is not active

M DpShMCreate: sizeof(wp_adm) 10528 (1316)

M DpShMCreate: sizeof(tm_adm) 2780232 (13832)

M DpShMCreate: sizeof(wp_ca_adm) 24000 (80)

M DpShMCreate: sizeof(appc_ca_adm) 8000 (80)

M DpShMCreate: sizeof(comm_adm) 290000 (580)

M DpShMCreate: sizeof(vmc_adm) 0 (372)

M DpShMCreate: sizeof(wall_adm) (38456/34360/64/184)

M DpShMCreate: SHM_DP_ADM_KEY (addr: 04570040, size: 3192688)

M DpShMCreate: allocated sys_adm at 04570040

M DpShMCreate: allocated wp_adm at 04571B58

M DpShMCreate: allocated tm_adm_list at 04574478

M DpShMCreate: allocated tm_adm at 045744A0

M DpShMCreate: allocated wp_ca_adm at 0481B0E8

M DpShMCreate: allocated appc_ca_adm at 04820EA8

M DpShMCreate: allocated comm_adm_list at 04822DE8

M DpShMCreate: allocated comm_adm at 04822E00

M DpShMCreate: allocated vmc_adm_list at 04869AD0

M DpShMCreate: system runs without vmc_adm

M DpShMCreate: allocated ca_info at 04869AF8

M DpShMCreate: allocated wall_adm at 04869B00

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 Thu Dec 13 02:28:37 2007

M calling db_connect ...

C Got ORACLE_HOME=D:\oracle\sm6\924 from environment

C *** ERROR => OCI-call 'OCIInitialize' failed: rc = -1

[dboci.c 1848]

C *** ERROR => OCI-call 'OCIErrorGet' failed: rc = -2

[dboci.c 1742]

B ***LOG BY2=> sql error -1 performing CON [dbsh#2 @ 1200] [dbsh 1200 ]

B ***LOG BY0=> Cannot get Oracle error text. [dbsh#2 @ 1200] [dbsh 1200 ]

B ***LOG BY2=> sql error -1 performing CON [dblink#1 @ 419] [dblink 0419 ]

B ***LOG BY0=> Cannot get Oracle error text. [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 dlwsdv10_SM6_00 on host dlwsdv10 (wp 0)

M * ERROR tskh_init: db_connect

M *

M * TIME Thu Dec 13 02:28:37 2007

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 4476) [dpnttool.c 357]

disp trace file:

-

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

-

Wed Dec 12 22:51:37 2007

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

length of sys_adm_ext is 524 bytes

systemid 560 (PC with Windows NT)

relno 6400

patchlevel 0

patchno 43

intno 20020600

make: multithreaded, Unicode

pid 3612

***LOG Q00=> DpSapEnvInit, DPStart (00 3612) [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

Wed Dec 12 22:51:42 2007

WARNING => DpNetCheck: NiAddrToHost(1.0.0.0) took 5 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) 10528 (1316)

DpShMCreate: sizeof(tm_adm) 2780232 (13832)

DpShMCreate: sizeof(wp_ca_adm) 24000 (80)

DpShMCreate: sizeof(appc_ca_adm) 8000 (80)

DpShMCreate: sizeof(comm_adm) 290000 (580)

DpShMCreate: sizeof(vmc_adm) 0 (372)

DpShMCreate: sizeof(wall_adm) (38456/34360/64/184)

DpShMCreate: SHM_DP_ADM_KEY (addr: 04570040, size: 3192688)

DpShMCreate: allocated sys_adm at 04570040

DpShMCreate: allocated wp_adm at 04571B58

DpShMCreate: allocated tm_adm_list at 04574478

DpShMCreate: allocated tm_adm at 045744A0

DpShMCreate: allocated wp_ca_adm at 0481B0E8

DpShMCreate: allocated appc_ca_adm at 04820EA8

DpShMCreate: allocated comm_adm_list at 04822DE8

DpShMCreate: allocated comm_adm at 04822E00

DpShMCreate: allocated vmc_adm_list at 04869AD0

DpShMCreate: system runs without vmc_adm

DpShMCreate: allocated ca_info at 04869AF8

DpShMCreate: allocated wall_adm at 04869B00

MBUF state OFF

Wed Dec 12 22:51:43 2007

EmInit: MmSetImplementation( 2 ).

<ES> client 0 initializing ....

<ES> InitFreeList

<ES> block size is 1024 kByte.

Using implementation flat

<EsNT> Memory Reset disabled as NT default

<ES> 2457 blocks reserved for free list.

ES initialized.

rdisp/http_min_wait_dia_wp : 1 -> 1

Wed Dec 12 22:51:44 2007

***LOG CPS=> DpLoopInit, ICU ( 2.6.1 2.6 4.0) [dpxxdisp.c 1462]

***LOG Q0K=> DpMsAttach, mscon ( dlwsdv10) [dpxxdisp.c 9736]

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

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.

Wed Dec 12 22:52:24 2007

ERROR => W0 (pid 3668) died [dpxxdisp.c 12187]
ERROR => W1 (pid 2040) died [dpxxdisp.c 12187]

my types changed after wp death/restart 0xbf --> 0xbe

ERROR => W2 (pid 1868) died [dpxxdisp.c 12187]

my types changed after wp death/restart 0xbe --> 0xbc

ERROR => W3 (pid 1484) died [dpxxdisp.c 12187]

my types changed after wp death/restart 0xbc --> 0xb8

ERROR => W4 (pid 2132) died [dpxxdisp.c 12187]
ERROR => W5 (pid 2308) died [dpxxdisp.c 12187]

my types changed after wp death/restart 0xb8 --> 0xb0

ERROR => W6 (pid 3320) died [dpxxdisp.c 12187]

my types changed after wp death/restart 0xb0 --> 0xa0

ERROR => W7 (pid 3636) 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-3668 (SIGUSR2)

ERROR => DpWpKill(3668, SIGUSR2) failed [dpxxtool.c 2468]

killing W1-2040 (SIGUSR2)

ERROR => DpWpKill(2040, SIGUSR2) failed [dpxxtool.c 2468]

killing W2-1868 (SIGUSR2)

ERROR => DpWpKill(1868, SIGUSR2) failed [dpxxtool.c 2468]

killing W3-1484 (SIGUSR2)

ERROR => DpWpKill(1484, SIGUSR2) failed [dpxxtool.c 2468]

killing W4-2132 (SIGUSR2)

ERROR => DpWpKill(2132, SIGUSR2) failed [dpxxtool.c 2468]

killing W5-2308 (SIGUSR2)

ERROR => DpWpKill(2308, SIGUSR2) failed [dpxxtool.c 2468]

killing W6-3320 (SIGUSR2)

ERROR => DpWpKill(3320, SIGUSR2) failed [dpxxtool.c 2468]

killing W7-3636 (SIGUSR2)

ERROR => DpWpKill(3636, SIGUSR2) failed [dpxxtool.c 2468]

NiWait: sleep (10000 msecs) ...

NiISelect: timeout 10000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:34 2007

NiISelect: TIMEOUT occured (10000 ms)

dump system status

Workprocess Table (long) Thu Dec 13 04:52:34 2007

========================

No Ty. Pid Status Cause Start Err Sem CPU Time Program Cl User Action Table

-

0 DIA 3668 Ended no 1 0 0

1 DIA 2040 Ended no 1 0 0

2 UPD 1868 Ended no 1 0 0

3 ENQ 1484 Ended no 1 0 0

4 BTC 2132 Ended no 1 0 0

5 BTC 2308 Ended no 1 0 0

6 SPO 3320 Ended no 1 0 0

7 UP2 3636 Ended no 1 0 0

Dispatcher Queue Statistics Thu Dec 13 04:52:34 2007

===========================

--------

+
+
+
+ --

+

Typ

now

high

max

writes

reads

--------

+
+
+
+ --

+

NOWP

0

3

2000

10

--------

+
+
+
+ --

+

DIA

4

2000

4

0

--------

+
+
+
+ --

+

UPD

0

2000

0

--------

+
+
+
+ --

+

ENQ

0

2000

0

--------

+
+
+
+ --

+

BTC

0

2000

0

--------

+
+
+
+ --

+

SPO

0

2000

0

--------

+
+
+
+ --

+

UP2

0

2000

0

--------

+
+
+
+ --

+

max_rq_id 12

wake_evt_udp_now 0

wake events total 8, udp 7 ( 87%), shm 1 ( 12%)

since last update total 8, udp 7 ( 87%), shm 1 ( 12%)

Dump of tm_adm structure: Thu Dec 13 04:52:34 2007

=========================

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

Workprocess Comm. Area Blocks Thu Dec 13 04:52:34 2007

=============================

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=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:39 2007

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 (960) (SOFT_KILL)

Stop icman

killing process (800) (SOFT_KILL)

Terminate gui connections

[DpProcDied] Process lives (PID:960 HANDLE:1540)

waiting for termination of gateway

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:40 2007

NiISelect: TIMEOUT occured (1000 ms)

[DpProcDied] Process died (PID:960 HANDLE:1540)

[DpProcDied] Process lives (PID:800 HANDLE:1532)

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:41 2007

NiISelect: TIMEOUT occured (1000 ms)

[DpProcDied] Process lives (PID:800 HANDLE:1532)

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:42 2007

NiISelect: TIMEOUT occured (1000 ms)

[DpProcDied] Process lives (PID:800 HANDLE:1532)

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:43 2007

NiISelect: TIMEOUT occured (1000 ms)

[DpProcDied] Process lives (PID:800 HANDLE:1532)

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:44 2007

NiISelect: TIMEOUT occured (1000 ms)

[DpProcDied] Process lives (PID:800 HANDLE:1532)

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:45 2007

NiISelect: TIMEOUT occured (1000 ms)

[DpProcDied] Process lives (PID:800 HANDLE:1532)

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=1561

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Wed Dec 12 22:52:46 2007

NiISelect: TIMEOUT occured (1000 ms)

[DpProcDied] Process died (PID:800 HANDLE:1532)

DpHalt: cancel all lcom connections

MPI CancelAll 2 -> 0

MPI DeleteAll 2 -> 0

NiIMyHostName: hostname = 'dlwsdv10'

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

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 17)

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 1544

MsIDetach: detach MS-system

EsCleanup ....

***LOG Q05=> DpHalt, DPStop ( 3612) [dpxxdisp.c 8495]

Good Bye .....

Edited by: arigela sap on Dec 18, 2007 7:49 AM