The message server starts but the dispatcher doesn’t

Tanggal 4 Mei 2008 saya hendak konek ke SAP melalui SAP GUI tiba2 tidak jalan …. wah hari yang kurang menyenangkan ketika terganggu dengan server SAP yg ngadat hmmmm. Saya coba cek ke server ternyata R/3 instance berwarna kuning dikarenakan disp+work servicenya tidak jalan/down/tewas/die/mampus wow, pernah saya alami sebelumnya lalu saya kasih resep install ulang …ohohohh ya iyalah. Kali ini instinct saya tidak akan membiarkan ini terjadi lagi hmmm repot harus isntall ulang terus, harus diusut sampai tuntas. Saya coba diagnosa dengan mencari kejadian yang sama diinternet ternyata banyak yg mengalami hal ini, ada beberapa solusi/obat yang saya coba:

1. matikan service SAP, install ulang SAP instance (C:\usr\sap\SAI\SYS\exe\run — SID=SAI, NR=00, Profiles=C:\usr\sap\SAI\SYS\profile\START_DVEBMGS00_sap, user=.\SAPServices[SID]), restart windows — tidak berhasil.

2. eksekusi file Z:\471ides\kernel\NT\I386\NTPATCH\R3DLLINS.EXE dgn asumsi dll rusak karena windows mati tiba-tiba. — tidak berhasil.

3. Update MMC — tidak berhasil.

4. analisa kurang tepat ? mungkin kondisi berbeda.

Cek ke log untuk mencari keluhan secara mendalam agar analisa mantab

Log : C:\usr\sap\SAI\DVEBMGS00\work\dev_disp

—————————————————
trc file: “dev_disp”, trc level: 1, release: “620″
—————————————————

Tue Jun 03 09:48:19 2008
kernel runs with dp version 3(ext=1) (@(#) DPLIB-INT-VERSION-3)
length of sys_adm_ext is 304 bytes

Tue Jun 03 09:48:20 2008
systemid 560 (PC with Windows NT)
relno 6200
patchlevel 0
patchno 674
intno 20020600
pid 4036

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

Tue Jun 03 09:48:26 2008
*** 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 3212]
MtxInit: -2 0 0
DpShMCreate: sizeof(wp_adm) 12032 (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: 042F0040, size: 1977072)
DpShMCreate: allocated sys_adm at 042F0040
DpShMCreate: allocated wp_adm at 042F0560
DpShMCreate: allocated tm_adm_list at 042F3460
DpShMCreate: allocated tm_adm at 042F3488
DpShMCreate: allocated wp_ca_adm at 04490148
DpShMCreate: allocated appc_ca_adm at 04494798
DpShMCreate: allocated comm_adm_list at 04495F08
DpShMCreate: allocated comm_adm at 04495F20
DpShMCreate: allocated ca_info at 044C4D20
DpShMCreate: allocated wall_adm at 044C4D28
MBUF state OFF
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 899 MB
<ES> 898 blocks reserved for free list.
ES initialized.

Tue Jun 03 09:48:27 2008
***LOG Q0K=> DpMsAttach, mscon ( sap) [dpxxdisp.c 9115]

Tue Jun 03 09:48:49 2008
CCMS: Initalizing shared memory of size 20000000 for monitoring segment.

Tue Jun 03 09:49:05 2008
CCMS: start to initalize 3.X shared alert area (first segment).
DpMsgAdmin: Set release to 6200, patchlevel 0
MBUF state PREPARED
MBUF component UP
DpMBufHwIdSet: set Hardware-ID
***LOG Q1C=> DpMBufHwIdSet [dpxxmbuf.c 941]
DpMsgAdmin: Set patchno for this platform to 674
Release check o.K.

Tue Jun 03 09:49:21 2008
*** ERROR => W0 (pid 2976) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W0
*** ERROR => W1 (pid 1372) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W1
*** ERROR => W2 (pid 2984) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W2
*** ERROR => W3 (pid 2992) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W3
*** ERROR => W4 (pid 3004) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W4
*** ERROR => W5 (pid 2804) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W5
*** ERROR => W6 (pid 3020) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W6
*** ERROR => W7 (pid 3916) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W7
*** ERROR => W8 (pid 3032) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W8
*** ERROR => W9 (pid 3044) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W9
*** ERROR => W10 (pid 3056) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W10
*** ERROR => W11 (pid 3072) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W11
*** ERROR => W12 (pid 2692) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W12
*** ERROR => W13 (pid 3084) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W13
*** ERROR => W14 (pid 2000) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W14
*** ERROR => W15 (pid 3096) died [dpxxdisp.c 11523]
force unlock of wp_adm mutex W15
my types changed after wp death/restart 0xbf –> 0×80
*** DP_FATAL_ERROR => DpEnvCheck: no more work processes
*** DISPATCHER EMERGENCY SHUTDOWN ***
DpModState: change server state from STARTING to SHUTDOWN

Tue Jun 03 09:49:31 2008
***LOG Q0M=> DpMsDetach, ms_detach () [dpxxdisp.c 9341]
MBUF state OFF
MBUF component DOWN
***LOG Q05=> DpHalt, DPStop ( 4036) [dpxxdisp.c 7883]

Log : C:\usr\sap\SAI\DVEBMGS00\work\dev_ms

—————————————————
trc file: “dev_ms”, trc level: 1, release: “620″
—————————————————

[Thr 4000] Tue May 20 17:50:06 2008
[Thr 4000] MsSSetTrcLog: trc logging not active
[Thr 4000] relno 6200
[Thr 4000] patchlevel 0
[Thr 4000] patchno 665
[Thr 4000] intno 20020600
[Thr 4000] pid 4040
[Thr 4000] ***LOG Q01=> MsSInit, MSStart (Msg Server 1 4040) [msxxserv.c 1524]
[Thr 4000] MsHttpPortInsert: set HTTP state to LISTEN
[Thr 4000] *** I listen to port sapmsSAI (3600) ***
[Thr 4000] *** HTTP LISTEN ***
[Thr 4000] *** I listen to HTTP port 8100 ***
[Thr 4000] CUSTOMER KEY: >O0259505000<

[Thr 4000] Wed May 21 13:18:15 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Tue May 27 17:55:23 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Tue May 27 21:30:52 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Wed May 28 01:17:18 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Wed May 28 09:29:07 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Wed May 28 14:52:18 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Wed May 28 20:52:04 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Thu May 29 02:51:30 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Thu May 29 08:34:01 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Thu May 29 14:17:16 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

[Thr 4000] Thu May 29 17:29:54 2008
[Thr 4000] ***LOG Q0I=> NiPRead: recv (10053: WSAECONNABORTED: Software caused connection abort) [ninti.c 1094]
[Thr 4000] *** ERROR => MsSRead: NiBufReceive (rc=NIECONN_BROKEN) [msxxserv.c 8523]
[Thr 4000] *** ERROR => MsSClientHandle: MsSRead C0 (192.168.0.5), MSEINTERN [msxxserv.c 3662]

Log : C:\usr\sap\SAI\DVEBMGS00\work\dev_w0
—————————————————
trc file: “dev_w0″, trc level: 1, release: “620″
—————————————————
*
* ACTIVE TRACE LEVEL 1
* ACTIVE TRACE COMPONENTS all, M
*
B
B Tue Jun 03 09:49:05 2008
B create_con (con_name=R/3)
B Loading DB library ‘C:\usr\sap\SAI\SYS\exe\run\dboraslib.dll’ …
B Library ‘C:\usr\sap\SAI\SYS\exe\run\dboraslib.dll’ loaded
B Version of ‘C:\usr\sap\SAI\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 2976
M
M ***LOG Q0Q=> tskh_init, WPStart (Workproc 0 2976) [dpxxdisp.c 1028]
I MtxInit: -2 0 0
M DpShMCreate: sizeof(wp_adm) 12032 (752)
M
M Tue Jun 03 09:49:06 2008
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: 04AF0040, size: 1977072)
M DpShMCreate: allocated sys_adm at 04AF0040
M DpShMCreate: allocated wp_adm at 04AF0560
M DpShMCreate: allocated tm_adm_list at 04AF3460
M DpShMCreate: allocated tm_adm at 04AF3488
M DpShMCreate: allocated wp_ca_adm at 04C90148
M DpShMCreate: allocated appc_ca_adm at 04C94798
M DpShMCreate: allocated comm_adm_list at 04C95F08
M DpShMCreate: allocated comm_adm at 04C95F20
M DpShMCreate: allocated ca_info at 04CC4D20
M DpShMCreate: allocated wall_adm at 04CC4D28
X
X Tue Jun 03 09:49:08 2008
X EmInit: MmSetImplementation( 2 ).
X <ES> client 0 initializing ….
X
X Tue Jun 03 09:49:09 2008
X Using implementation std
M <EsNT> Memory Reset enabled as NT default
X ES initialized.
M
M Tue Jun 03 09:49:10 2008
M calling db_connect …
C Got ORACLE_HOME=C:\oracle\ora92 from environment
C Client NLS settings: AMERICAN_AMERICA.WE8DEC
C Logon as OPS$-user to get SAPSAI’s password
C Connecting as /@SAI on connection 0 …
C Attaching to DB Server SAI (con_hdl=0,svchp=0624150C,svrhp=06243764)
C
C Tue Jun 03 09:49:11 2008
C *** ERROR => OCI-call ‘OCIServerAttach’ failed: rc = 12541
[dboci.c 3566]
C *** ERROR => CONNECT failed with sql error ‘12541′
[dboci.c 9601]
C Try to connect with default password
C Connecting as SAPSAI/<pwd>@SAI on connection 0 …
C Attaching to DB Server SAI (con_hdl=0,svchp=0624150C,svrhp=06247860)
C
C Tue Jun 03 09:49:12 2008
C *** ERROR => OCI-call ‘OCIServerAttach’ failed: rc = 12541
[dboci.c 3566]
C *** ERROR => CONNECT failed with sql error ‘12541′
[dboci.c 9601]
B ***LOG BY2=> sql error 12541 performing CON [dbsh#2 @ 962] [dbsh 0962 ]
B ***LOG BY0=> ORA-12541: TNS:no listener [dbsh#2 @ 962] [dbsh 0962 ]
B ***LOG BY2=> sql error 12541 performing CON [dblink#1 @ 419] [dblink 0419 ]
B ***LOG BY0=> ORA-12541: TNS:no listener [dblink#1 @ 419] [dblink 0419 ]
M ***LOG R19=> tskh_init, db_connect ( DB-Connect 000256) [thxxhead.c 1102]
M in_ThErrHandle: 1
M *** ERROR => tskh_init: db_connect (step 1, th_errno 13, action 3, level 1) [thxxhead.c 8437]
M
M Info for wp 0
M
M stat = 4
M reqtype = 1
M act_reqtype = -1
M tid = -1
M mode = 255
M len = -1
M rq_id = -1
M rq_source = 255
M last_tid = 0
M last_mode = 0
M rfc_req = 0
M report = > <
M action = 0
M tab_name = > <
M
M *****************************************************************************
M *
M * LOCATION SAP-Server sap_SAI_00 on host sap (wp 0)
M * ERROR tskh_init: db_connect
M *
M * TIME Tue Jun 03 09:49:12 2008
M * RELEASE 620
M * COMPONENT Taskhandler
M * VERSION 1
M * RC 13
M * MODULE thxxhead.c
M * LINE 8569
M * COUNTER 1
M *
M *****************************************************************************
M
M Entering TH_CALLHOOKS
M ThCallHooks: call hook >ThrSaveSPAFields< for event BEFORE_DUMP
M *** ERROR => ThrSaveSPAFields: no valid thr_wpadm [thxxrun1.c 672]
M *** ERROR => ThCallHooks: event handler ThrSaveSPAFields for event BEFORE_DUMP failed [thxxtool3.c 237]
M Entering ThSetStatError
M Entering ThReadDetachMode
M call ThrShutDown (1)…
M ***LOG Q02=> wp_halt, WPStop (Workproc 0 2976) [dpnttool.c 346]

analisa lebih lanjut lihat “sapstart.log”

SAP-R/3-Startup Program

Starting at 2008/06/03 20:13:14
Startup Profile: “C:\usr\sap\SAI\SYS\profile\START_DVEBMGS00_sap”
Starting Programs
(2904) Execute: C:\usr\sap\SAI\SYS\exe\run\strdbs.cmd SAI
(3476) CreateProcess: C:\usr\sap\SAI\SYS\exe\run\msg_server.exe pf=C:\usr\sap\SAI\SYS\profile\SAI_DVEBMGS00_sap
(3744) CreateProcess: C:\usr\sap\SAI\SYS\exe\run\disp+work.exe pf=C:\usr\sap\SAI\SYS\profile\SAI_DVEBMGS00_sap

service disp+work me-load profile C:\usr\sap\SAI\SYS\profile\SAI_DVEBMGS00_sap

lihat profile SAI_DVEBMGS00_sap

SAPSYSTEMNAME=SAI
INSTANCE_NAME=DVEBMGS00
SAPSYSTEM=00
rdisp/wp_no_dia=6
rdisp/wp_no_btc=2
rdisp/wp_no_vb=4
rdisp/wp_no_vb2=2
rdisp/wp_no_enq=1
rdisp/wp_no_spo=1
SAPGLOBALHOST=sap
PHYS_MEMSIZE=899
DIR_TRANS=C:\usr\sap\trans
icm/server_port_0=PROT=HTTP,PORT=8000,EXTBIND=1
DIR_ORAHOME=C:\oracle\ora92

dari informasi diatas bahwa message service gagal mengirim pesan ke service disp+work lalu disp+work gagal melakukan koneksi ke database dengan meload profile diatas hmmm sudah ada titik temu yaitu gagal melakukan koneksi ke database.

Mari kita cek apakah database berjalan dengan normal, saya login ke database oracle sql*plus hasilnya tidak ada masalah kemudian koneksi via odbc ke database dan ternyata error. Berarti jelas ada service oracle yang tidak jalan/ngadat/mati/tewas/mampus. Buka service cari service oracle yang tidak jalan … wow “OracleOraHome92TNSListener” mampus …… klik kanan lalu start .. ahahaaa SAP Instance sudah berwarna hijau sukses.

Semoga bermanfaat bagi pembaca, silahkan bagi yang mau kasih masukan.

SAP R/3 IDES 4.7.1 with Oracle9.2 on Windows Server 2003