on 03-01-2016 10:00 AM
---------------------------------------------------
trc file: "dev_disp", trc level: 1, release: "720"
---------------------------------------------------
sysno 00
sid PID
systemid 562 (PC with Windows NT)
relno 7200
patchlevel 0
patchno 600
intno 20020600
make multithreaded, Unicode, 64 bit, optimized
profile \\SAPPISERVER\sapmnt\PID\SYS\profile\PID_DVEBMGS00_SAPPISERVER
pid 2124
kernel runs with dp version 140000(ext=121000) (@(#) DPLIB-INT-VERSION-140000-UC)
length of sys_adm_ext is 592 bytes
*** SWITCH TRC-HIDE on ***
***LOG Q00=> DpSapEnvInit, DPStart (00 2124) [dpxxdisp.c 1320]
shared lib "dw_xml.dll" version 600 successfully loaded
shared lib "dw_xtc.dll" version 600 successfully loaded
shared lib "dw_stl.dll" version 600 successfully loaded
shared lib "dw_gui.dll" version 600 successfully loaded
shared lib "dw_mdm.dll" version 600 successfully loaded
shared lib "dw_rndrt.dll" version 600 successfully loaded
shared lib "dw_abp.dll" version 600 successfully loaded
shared lib "dw_sym.dll" version 600 successfully loaded
shared lib "dw_aci.dll" version 600 successfully loaded
rdisp/softcancel_sequence : -> 0,5,-1
use internal message server connection to port 3901
rdisp/dynamic_wp_check : 1
rdisp/calculateLoadAverage : 1
Tue Mar 01 15:07:35 2016
*** 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 6484]
MtxInit: 30000 0 0
DpSysAdmExtInit: ABAP is active
DpSysAdmExtInit: VMC (JAVA VM in WP) is not active
display_tcp_ip: 0
DpIPCInit2: write dp-profile-values into sys_adm_ext
DpIPCInit2: start server >sappiserver_PID_00 <
DpShMCreate: sizeof(wp_adm) 29328 (2256)
DpShMCreate: sizeof(tm_adm) 5517056 (27448)
DpShMCreate: sizeof(wp_ca_adm) 64000 (64)
DpShMCreate: sizeof(appc_ca_adm) 64000 (64)
DpCommTableSize: max/headSize/ftSize/tableSize=500/16/1384064/1384080
DpShMCreate: sizeof(comm_adm) 1384080 (2744)
DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0
DpShMCreate: sizeof(slock_adm) 0 (296)
DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0
DpShMCreate: sizeof(file_adm) 0 (80)
DpShMCreate: sizeof(vmc_adm) 0 (2160)
DpShMCreate: sizeof(wall_adm) (41664/42896/64/192)
DpShMCreate: sizeof(gw_adm) 48
DpShMCreate: sizeof(j2ee_adm) 3952
DpShMCreate: SHM_DP_ADM_KEY (addr: 000000000DB30050, size: 7161328)
DpShMCreate: allocated sys_adm at 000000000DB30060
DpShMCreate: allocated wp_adm_list at 000000000DB330D0
DpShMCreate: allocated wp_adm at 000000000DB332C0
DpShMCreate: allocated tm_adm_list at 000000000DB3A560
DpShMCreate: allocated tm_adm at 000000000DB3A5B0
DpShMCreate: allocated wp_ca_adm at 000000000E07D4C0
DpShMCreate: allocated appc_ca_adm at 000000000E08CED0
DpShMCreate: allocated comm_adm at 000000000E09C8E0
DpShMCreate: system runs without slock table
DpShMCreate: system runs without file table
DpShMCreate: allocated vmc_adm_list at 000000000E1EE780
DpShMCreate: system runs without vmc_adm
DpShMCreate: allocated gw_adm at 000000000E1EE830
DpShMCreate: allocated j2ee_adm at 000000000E1EE870
DpShMCreate: allocated ca_info at 000000000E1EF7F0
DpShMCreate: allocated wall_adm at 000000000E1EF880
DpCommAttachTable: attached comm table (header=000000000E09C8E0/ft=000000000E09C8F0)
DpSysAdmIntInit: initialize sys_adm
rdisp/test_roll : roll strategy is DP_NORMAL_ROLL
dia token check not active (5 token)
MBUF state OFF
DpCommInitTable: init table for 500 entries
DpRqQInit: keep protect_queue / slots_per_queue 0 / 2001 in sys_adm
rdisp/queue_size_check_value : -> on,50,30,40,500,50,500,80
EmInit: MmSetImplementation( 2 ).
MM global diagnostic options set: 0
<ES> client 0 initializing ....
<ES> EsILock: use spinlock for locking
<ES> InitFreeList
<ES> block size is 4096 kByte.
<ES> Info: em/initial_size_MB( 1015MB) not multiple of em/blocksize_KB( 4096KB)
<ES> Info: em/initial_size_MB rounded up to 1016MB
Using implementation view
<EsNT> Using memory model view.
<EsNT> Memory Reset disabled as NT default
<ES> 253 blocks reserved for free list.
ES initialized.
mm.dump: set maximum dump mem to 192 MB
mm.dump: set global maximum dump mem to 192 MB
EsRegisterEmCheck: Register EmGetEsHandles at 0000000140D62E90
DpVmcSetActive: set vmc state DP_VMC_NOT_ACTIVE
MPI: dynamic quotas disabled.
MPI init: pipes=4000 buffers=1279 reserved=383 quota=10%
J2EE server info
start = TRUE
state = STARTED
pid = 5108
argv[0] = D:\usr\sap\PID\DVEBMGS00\exe\jstart.EXE
argv[1] = D:\usr\sap\PID\DVEBMGS00\exe\jstart.EXE
argv[2] = pf=\\SAPPISERVER\sapmnt\PID\SYS\profile\PID_DVEBMGS00_SAPPISERVER
argv[3] = -DSAPSTART=1
argv[4] = -DCONNECT_PORT=65000
argv[5] = -DSAPSYSTEM=00
argv[6] = -DSAPSYSTEMNAME=PID
argv[7] = -DSAPMYNAME=sappiserver_PID_00
argv[8] = -DSAPPROFILE=\\SAPPISERVER\sapmnt\PID\SYS\profile\PID_DVEBMGS00_SAPPISERVER
argv[9] = -DFRFC_FALLBACK=ON
argv[10] = -DFRFC_FALLBACK_HOST=localhost
start_lazy = 0
start_control = SAP J2EE startup framework
DpJ2eeStart: j2ee state = STARTED
rdisp/http_min_wait_dia_wp : 1 -> 1
***LOG CPS=> DpLoopInit, ICU ( 4.0.1 4.0.1 5.1) [dpxxdisp.c 1706]
Tue Mar 01 15:07:40 2016
*** ERROR => MsIAttachEx: NiBufConnect to sappiserver/3901 failed (rc=NIECONN_PENDING) [msxxi.c 718]
***LOG Q0L=> DpLoopInit, nomscon () [dpxxdisp.c 1805]
CCMS: SemInMgt: Semaphore Management initialized by AlAttachShm_Doublestack.
CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.
CCMS: start to initalize 3.X shared alert area (first segment).
DpJ2eeLogin: j2ee state = CONNECTED
DpAppcBlksLow: max appc blocks in queue is 500 (50 %)
DpWpBlksLow: max wp blocks in queue is 800 (80 %)
Tue Mar 01 15:08:25 2016
*** ERROR => MsIAttachEx: NiBufConnect to sappiserver/3901 failed (rc=NIECONN_PENDING) [msxxi.c 718]
*** ERROR => DpHdlDeadWp: W0 (pid 3804) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W1 (pid 2556) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W2 (pid 3536) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W3 (pid 3292) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W4 (pid 620) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W5 (pid 2928) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W6 (pid 4880) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W7 (pid 4916) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W8 (pid 4252) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W9 (pid 4952) died (severity=0, status=0) [dpxxwp.c 1739]
*** ERROR => DpHdlDeadWp: W10 (pid 3576) died (severity=0, status=0) [dpxxwp.c 1739]
*** DP_FATAL_ERROR => DpWPCheck: no more work processes
*** DISPATCHER EMERGENCY SHUTDOWN ***
increase tracelevel of WPs
NiWait: sleep (10000ms) ...
NiISelect: timeout 10000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:35 2016
NiISelect: TIMEOUT occured (10000ms)
dump system status
Workprocess Table (long) Tue Mar 01 09:38:35 2016
========================
No Type Pid Status Cause Start Rstr Err Sem Time Program Cl User Action Table
-------------------------------------------------------------------------------------------------------------------------------
0 DIA -1 Ended no no 1 0 0 NO_ACTION
1 DIA -1 Ended no no 1 0 0 NO_ACTION
2 DIA -1 Ended no no 1 0 0 NO_ACTION
3 DIA -1 Ended no no 1 0 0 NO_ACTION
4 DIA -1 Ended no no 1 0 0 NO_ACTION
5 UPD -1 Ended no no 1 0 0 NO_ACTION
6 BTC -1 Ended no no 1 0 0 NO_ACTION
7 BTC -1 Ended no no 1 0 0 NO_ACTION
8 BTC -1 Ended no no 1 0 0 NO_ACTION
9 SPO -1 Ended no no 1 0 0 NO_ACTION
10 UP2 -1 Ended no no 1 0 0 NO_ACTION
Dispatcher Queue Statistics Tue Mar 01 09:38:35 2016
===========================
+------+--------+--------+--------+------------+------------+
| Typ | now | high | max | writes | reads |
+------+--------+--------+--------+------------+------------+
| NOWP | 0 | 3 | 2000 | 5 | 5 |
+------+--------+--------+--------+------------+------------+
| DIA | 2 | 2 | 2000 | 2 | 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 6
wake_evt_udp_now 0
wake events total 6, udp 3 ( 50%), shm 3 ( 50%)
since last update total 6, udp 3 ( 50%), shm 3 ( 50%)
DpGetLoadAverage: la1[1] = 0.166667 / -1.000000 %
DpGetLoadAverage: la5[1] = 0.033333 / -1.000000 %
DpGetLoadAverage: la15[1] = 0.033333 / -1.000000 %
Dispatcher Load Statistics
+------+---------+---------+---------+
| Typ | LA1 | LA5 | LA15 |
+------+---------+---------+---------+
| DIA | 0.167 | 0.033 | 0.033 |
+------+---------+---------+---------+
DpGetLoadAverage: la1[2] = 0.000000 / -1.000000 %
DpGetLoadAverage: la5[2] = 0.000000 / -1.000000 %
DpGetLoadAverage: la15[2] = 0.000000 / -1.000000 %
| UPD | 0.000 | 0.000 | 0.000 |
+------+---------+---------+---------+
DpGetLoadAverage: la1[3] = 0.000000 / -1.000000 %
DpGetLoadAverage: la5[3] = 0.000000 / -1.000000 %
DpGetLoadAverage: la15[3] = 0.000000 / -1.000000 %
| ENQ | 0.000 | 0.000 | 0.000 |
+------+---------+---------+---------+
DpGetLoadAverage: la1[4] = 0.000000 / -1.000000 %
DpGetLoadAverage: la5[4] = 0.000000 / -1.000000 %
DpGetLoadAverage: la15[4] = 0.000000 / -1.000000 %
| BTC | 0.000 | 0.000 | 0.000 |
+------+---------+---------+---------+
DpGetLoadAverage: la1[5] = 0.000000 / -1.000000 %
DpGetLoadAverage: la5[5] = 0.000000 / -1.000000 %
DpGetLoadAverage: la15[5] = 0.000000 / -1.000000 %
| SPO | 0.000 | 0.000 | 0.000 |
+------+---------+---------+---------+
DpGetLoadAverage: la1[6] = 0.000000 / -1.000000 %
DpGetLoadAverage: la5[6] = 0.000000 / -1.000000 %
DpGetLoadAverage: la15[6] = 0.000000 / -1.000000 %
| UP2 | 0.000 | 0.000 | 0.000 |
+------+---------+---------+---------+
Dump of tm_adm structure: Tue Mar 01 09:38:35 2016
=========================
Term uid man user term lastop mod wp ta a/i (modes)
Workprocess Comm. Area Blocks Tue Mar 01 09:38:35 2016
=============================
Slots: 1000, Used: 0, Max: 1
+------+--------------+----------+-------------+
| id | owner | pid | eyecatcher |
+------+--------------+----------+-------------+
NiWait: sleep (5000ms) ...
NiISelect: timeout 5000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:40 2016
NiISelect: TIMEOUT occured (5000ms)
DpHalt: shutdown server >sappiserver_PID_00 < (normal)
DpJ2eeDisableRestart
DpIJ2eeShutdown: send SIGQUIT to SAP J2EE startup framework (pid=5108)
killing proc (5108) (SOFTSHUTDOWN_KILL)
*** ERROR => DpProcKill: kill failed [dpntdisp.c 409]
DpIJ2eeShutdown: j2ee state = SHUTDOWN
NiBufISelUpdate: new MODE -- (r-) for hdl 33 in set0
SiSelNSet: set events of sock 1120 to: ---
NiBufISelRemove: remove hdl 33 from set0
SiSelNRemove: removed sock 1120 (pos=2)
SiSelNRemove: removed sock 1120
NiSelIRemove: removed hdl 33
DpDelSocketInfo: del info for nihdl 33 (pos/type=4/8)
NiICloseHandle: shutdown and close hdl 33/sock 1120
NiBufIClose: clear extension for hdl 33
DpHalt: 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
DpHalt: stop work processes
DpHalt: stop gateway
killing process (2996) (SOFT_KILL)
DpHalt: stop icman
killing process (3208) (SOFT_KILL)
DpHalt: terminate gui connections
DpHalt: wait for end of work processes
DpHalt: wait for end of gateway
[DpProcDied] Process lives (PID:2996 HANDLE:872)
DpHalt: waiting for termination of gateway ...
NiWait: sleep (1000ms) ...
NiISelect: timeout 1000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:41 2016
NiISelect: TIMEOUT occured (1000ms)
*** ERROR => [DpProcDied] Process died (PID:2996 HANDLE:872 rc:0x0) [dpnttool2.c 147]
DpHalt: wait for end of icman
[DpProcDied] Process lives (PID:3208 HANDLE:880)
DpHalt: waiting for termination of icman ...
NiWait: sleep (1000ms) ...
NiISelect: timeout 1000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:42 2016
NiISelect: TIMEOUT occured (1000ms)
[DpProcDied] Process lives (PID:3208 HANDLE:880)
DpHalt: waiting for termination of icman ...
NiWait: sleep (1000ms) ...
NiISelect: timeout 1000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:43 2016
NiISelect: TIMEOUT occured (1000ms)
[DpProcDied] Process lives (PID:3208 HANDLE:880)
DpHalt: waiting for termination of icman ...
NiWait: sleep (1000ms) ...
NiISelect: timeout 1000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:44 2016
NiISelect: TIMEOUT occured (1000ms)
[DpProcDied] Process lives (PID:3208 HANDLE:880)
DpHalt: waiting for termination of icman ...
NiWait: sleep (1000ms) ...
NiISelect: timeout 1000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:45 2016
NiISelect: TIMEOUT occured (1000ms)
[DpProcDied] Process lives (PID:3208 HANDLE:880)
DpHalt: waiting for termination of icman ...
NiWait: sleep (1000ms) ...
NiISelect: timeout 1000ms
NiISelect: maximum fd=1137
NiISelect: read-mask is NULL
NiISelect: write-mask is NULL
Tue Mar 01 15:08:46 2016
NiISelect: TIMEOUT occured (1000ms)
*** ERROR => [DpProcDied] Process died (PID:3208 HANDLE:880 rc:0x0) [dpnttool2.c 147]
*** ERROR => [DpProcDied] Process died (PID:5108 HANDLE:876 rc:0x2) [dpnttool2.c 147]
DpHalt: not attached to the message server
DpHalt: cleanup EM
EsCleanup( )
EsCleanup ....
EmCleanup() -> 0
Es2Cleanup: Cleanup ES2
***LOG Q05=> DpHalt, DPStop ( 2124) [dpxxdisp.c 11605]
DpHalt: Good Bye .....
Hi Ravi,
A reason for this error is the ms_acl. Check the value of the ms/acl_info because the server could be denied in the try to access to the message server.
Kind regards
Francisco
You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.
Hi Ravi,
Based on provided dispatcher trace file, it's running on SAPPISERVER, instance number 00.
When the dispatcher starts, it tries to connect to internal port of Message Server (39$$) , but it fails:
Tue Mar 01 15:08:25 2016
*** ERROR => MsIAttachEx: NiBufConnect to sappiserver/3901 failed (rc=NIECONN_PENDING) [msxxi.c 718]
Is there an ASCS running on same host (sappiserver), instance number 01? Please attach Message Server trace file (dev_ms).
Which is the value of following parameters:
rdisp/mshost
rdisp/msserv
rdisp/msserv_internal
Regards,
Raquel
You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.
some times sapstartsvr get corrupted . check note 1043592 and try replacing SAPSTARTSRV.EXE FILE as mentioned in note
You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.
Hi Ravi.
Just do the full system restart and then check the SAP startup. or Are you installed any antivirus software? is it blocking the ports?
Regards
SS
You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.
Hi Ravi,
There could be number of reason behind this and you have to check all the logs carefully.
1. Please try to restart the system completely.
2. Please check \etc\services, host file etc. Also check the any change in environment variables.
3. Please check your profile which defines the ports. Also please check rdisp/msserv_internal.
4. Please check if that port is being used by other service/application using netstat command.
5. If above does not work, please attach dev_ms, dev_w0 log files to check further.
Most importantly please try to find out what change has taken place recently. It will be easier to find the root cause then.
Regards,
Sujit Kumar Banerjee.
Hallo Ravi,
*** ERROR => MsIAttachEx: NiBufConnect to sappiserver/3901 failed
Have you checked your Windows firewall settings?
What's the status in the MMC, all grey??
Regards
Thomas
You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.
Hello Ravi,
*** ERROR => MsIAttachEx: NiBufConnect to sappiserver/3901 failed (rc=NIECONN_PENDING) [msxxi.c 718]
This indicates that the Dispatcher could not connect to the Message Server internal port (39$$, where "$$" is the instance number of the instance where the MS is running).
It also indicates that the hostname of the server where the MS is running would be "sappiserver", and this is not listed by you when you posted the SAP MMC status, replying to Thomas' inputs.
Do you have an ASCS instance? Is it started?
If this system runs in High Availability scenario, verify whether the SAP cluster group is fully started.
Regards,
Isaías
User | Count |
---|---|
93 | |
10 | |
10 | |
9 | |
9 | |
7 | |
6 | |
5 | |
5 | |
4 |
You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.