Skip to Content
avatar image
Former Member

disp+work stopped after few seconds

Hello everybody

I have and strange situation actually this happens after I install Java 1.8 in a appserver SAP SLES 12 my dbserver is a HANA 2.0 DB in a SAP SLES 12 this is the trace

--------------------------------------------------- trc file: "dev_disp.new", trc level: 1, release: "745" --------------------------------------------------- sysno 01 sid HBI systemid 390 (AMD/Intel x86_64 with Linux) relno 7450 patchlevel 0 patchno 600 intno 20151301 make multithreaded, Unicode, 64 bit, optimized profile /usr/sap/HBI/SYS/profile/HBI_D01_hananw pid 4418 Thu Jan 4 12:35:13 2018 kernel runs with dp version 7000(ext=117000) (@(#) DPLIB-INT-VERSION-0+7000-UC) length of sys_adm_ext is 500 bytes ***LOG Q00=> DpSapEnvInit, DPStart (01 4418) [dpInit.c 609] DwSLCheckVersion: shared lib "dw_xml.so" version 600, compatibility level 600, SAP release 7450 successfully loaded DwSLCheckVersion: shared lib "dw_xtc.so" version 600, compatibility level 600, SAP release 7450 successfully loaded DwSLCheckVersion: shared lib "dw_stl.so" version 600, compatibility level 600, SAP release 7450 successfully loaded DwSLCheckVersion: shared lib "dw_gui.so" version 600, compatibility level 600, SAP release 7450 successfully loaded DwSLCheckVersion: shared lib "dw_rndrt.so" version 600, compatibility level 600, SAP release 7450 successfully loaded DwSLCheckVersion: shared lib "dw_abp.so" version 600, compatibility level 600, SAP release 7450 successfully loaded rdisp/softcancel_sequence : -> 0,5,-5 use internal message server connection to port 3900 rdisp/shutdown/disable_login : 0 DpCommonParamInit: rdisp/core_file_size = default --> no change MtxInit: 30000 0 0 DpIPCInit2: write dp-profile-values into sys_adm_ext RqQInit: use events to trigger worker DpIPCInit2: start server >hananw_HBI_01 < DpShMCreate: alloate/attach shared memory (mode=CREATE) DpShMCreate: sizeof(wp_adm)23184(1104) DpShMCreate: sizeof(tm_adm)52652600(RDISPTERM=52600,MODEINFO=3008, IMODE_INFO=80) DpShMCreate: sizeof(ca_adm)432000(72) DpCommTableSize: max/headSize/ftSize/tableSize=1500/8/5688056/5979112 DpShMCreate: sizeof(comm_adm)5979112(3784) 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) DpSockTableSize: max/headSize/ftSize/tableSize=1500/8/1536056/1536064 DpShMCreate: sizeof(sock_adm)1536064(1016) DpShMCreate: sizeof(vmc_adm)0(3208) DpShMCreate: sizeof(wall_adm)(ft=200056/fi=205048/hd=64/rec=192) DpShMCreate: sizeof(amc_rec_adm)(ft=1360056/fi=377048/hd=56/rec=672) DpShMCreate: sizeof(websocket_adm)(ft=284056/hd=72/rec=560) DpShMCreate: sizeof(gw_adm)56 DpShMCreate: sizeof(j2ee_adm)3928 DpShMCreate: SHM_DP_ADM_KEY(addr: 7fa9d09bc000, size: 63159960 DpShMCreate: allocated sys_adm at 7fa9d09bc200 DpShMCreate: allocated wp_adm_list at 7fa9d09ce1c0 DpShMCreate: allocated wp_adm at 7fa9d09ce4e0 DpShMCreate: allocated tm_adm_list at 7fa9d09d4170 Thu Jan 4 12:35:13 2018 DpShMCreate: allocated tm_adm at 7fa9d09d43f0 DpShMCreate: allocated ca_adm at 7fa9d3c0b028 DpShMCreate: allocated comm_adm at 7fa9d3c749a8 DpShMCreate: system runs without slock table DpShMCreate: allocated sock_adm at 7fa9d4228790 DpShMCreate: allocated vmc_adm_list at 7fa9d439f9d0 DpShMCreate: system runs without VMC DpShMCreate: allocated gw_adm at 7fa9d439fc70 DpShMCreate: allocated j2ee_adm at 7fa9d439fea8 DpShMCreate: allocated ca_info at 7fa9d43a1000 DpShMCreate: allocated wall_adm (ft) at 7fa9d43a1240 DpShMCreate: allocated wall_adm (fi) at 7fa9d43d21b8 DpShMCreate: allocated wall_adm (head) at 7fa9d44044b0 DpShMCreate: allocated amc_rec_adm (ft) at 7fa9d44046f0 DpShMCreate: allocated amc_rec_adm (fi) at 7fa9d45509a8 DpShMCreate: allocated amc_rec_adm (head) at 7fa9d45acc80 DpShMCreate: allocated websocket_adm (ft) at 7fa9d45aceb8 DpShMCreate: allocated websocket_adm (head) at 7fa9d45f2650 DpShMCreate: initialized 21 eyes DpSysAdmIntInit: initialize sys_adm DpSysAdmIntInit: created queue 0 (DispatcherQueue) DpSysAdmIntInit: created queue 1 (GatewayQueue) DpSysAdmIntInit: created queue 2 (IcmanQueue) DpSysAdmIntInit: created queue 3 (StartServiceQueue) DpSysAdmIntInit: created queue 4 (DpMonQueue) Thu Jan 4 12:35:14 2018 Scheduler info -------------- WP info #dia = 10 #btc = 0 #standby = 0 #max = 21 General Scheduler info preemptionInfo.isActive = true preemptionInfo.timeslice = 500 preemptionInfo.checkLoad = true Prio Class High maxRuntime[RQ_Q_PRIO_HIGH] = 600 sec maxRuntimeHalf[RQ_Q_PRIO_HIGH] = 300 sec Prio Class Normal maxRuntime[RQ_Q_PRIO_NORMAL] = 3600 sec maxRuntimeHalf[RQ_Q_PRIO_NORMAL] = 1800 sec maxTicketsForPrio[RQ_Q_PRIO_NORMAL] = 8 maxTicketsForPrioIncrement[RQ_Q_PRIO_NORMAL] = 0 withPrioTickets[RQ_Q_PRIO_NORMAL] = true Prio Class Low maxRuntime[RQ_Q_PRIO_LOW] = infinite maxRuntimeHalf[RQ_Q_PRIO_LOW] = infinite maxTicketsForPrio[RQ_Q_PRIO_LOW] = 7 maxTicketsForPrioIncrement[RQ_Q_PRIO_LOW] = 0 withPrioTickets[RQ_Q_PRIO_LOW] = true Running requests[RQ_Q_PRIO_NORMAL] = 0 Running requests[RQ_Q_PRIO_LOW] = 0 DpCommAttachTable: attached comm table (header=7fa9d3c749a8/ft=7fa9d3c749b0/fi=7fa9d41e14a8) DpSockAttachTable: attached sock table (header=7fa9d4228790/ft=7fa9d4228798) MBUF state OFF DpCommInitTable: init table for 1500 entries DpSockInitTable: init table for 1500 entries EM: Initializing PROC global storage: quota=0 use_stdheap=0 EM: Initializing PROC local storage: quota=0 use_stdheap=0 EmInit: MmSetImplementation( 2 ). MM global diagnostic options set: 0 EmSetReserve: set EM reserve to 98 EM blocks client 21 initializing .... Thu Jan 4 12:35:14 2018 EsILock: use Mutex for locking InitFreeList block size is 4096 kByte. Info: em/initial_size_MB( 19730MB) not multiple of em/blocksize_KB( 4096KB) Info: em/initial_size_MB rounded up to 19732MB Info: disclaim threshold = 0 MB Info: disclaim coasting/alloc = 300 s Info: disclaim coasting/free = 0 s Info: blockdisclaimsize_KB = 0 KB Using implementation std Info: use mapped file EsStdUnamFileMapInit: ES Segment = 0x7fa4f1090000 - 0x7fa9c2490000 EsStdInit: Extended Memory 19732 MB allocated Linux: Kernel supports shared memory disclaiming Linux: using madvise( , , 9). Linux: disclaiming for shared memory enabled 4932 blocks reserved for free list. ES initialized. EgInit: EG initialized with 3772M in 1 segments mm.dump: set global maximum dump mem to 192 MB EsRegisterEmCheck: Register EmGetEsHandles at 181fc01 rdisp/calculateLoadAverage : 1 rdisp/snapshot(CREATE): DP_CS_RESOURCES_EXHAUSTED rdisp/snapshot(PERIOD):300 sec rdisp/snapshot(LINES):10000 lines rdisp/snapshot(C-STACK):C-STACK = YES ***LOG Q0K=> DpMsAttach, mscon ( hananw) [dpMessageSer 1697] DpStartStopMsg: send start message (myname is >hananw_HBI_01 <) DpStartStopMsg: start msg sent to message server o.k. MBUF state LOADING DpCheckStopStateAfterMsAttach: server supports AMC msg header version 2 MPI: dynamic quotas disabled. MPI init, created: pipes=4000 buffers=2718 reserved=815 quota=10%, buffer size=65536, total size MB=170 DpAsCreate: forked gwrd (pid 4447) DpIcmCreate: forked ICM (pid 4448) DpWpDynCreate: created new work process W0-4449 DpWpDynCreate: created new work process W1-4450 DpWpDynCreate: created new work process W2-4451 DpWpDynCreate: created new work process W3-4452 DpWpDynCreate: created new work process W4-4453 DpWpDynCreate: created new work process W5-4454 Thu Jan 4 12:35:14 2018 DpWpDynCreate: created new work process W6-4455 DpWpDynCreate: created new work process W7-4456 DpWpDynCreate: created new work process W8-4457 DpWpDynCreate: created new work process W9-4458 DpWpDynCreate: created new work process W10-4459 DpWpDynCreate: created new work process W11-4460 DpWpDynCreate: created new work process W12-4461 DpWpDynCreate: created new work process W13-4462 DpWpDynCreate: created new work process W14-4463 DpWpDynCreate: created new work process W15-4464 CGROUPS: changing prio of pid 4418 to medium CGROUPS: disabled **START Linux Memory Parameter Check virtual memory: hard-limit = UNLIMITED virtual memory: soft-limit = UNLIMITED core size: hard-limit = UNLIMITED core size: soft-limit = 0 MB data segment size: hard-limit = UNLIMITED data segment size: soft-limit = UNLIMITED stack size: hard-limit = UNLIMITED stack size: soft-limit = 8 MB max open files: hard-limit = 65536 max open files: soft-limit = 65536 Page Size: 4 KB kernel.shmmax = 17592186044415 MB kernel.shmall = 4503599627370495 MB **END Linux Memory Parameter Check Linux scheduler "SCHED_OTHER" used with prio 0 CCMS: Initialized monitoring segment of size 60000000. CCMS: Initialized CCMS Headers in the shared monitoring segment. CCMS: Checking Downtime Configuration of Monitoring Segment. CCMS: AlMsUpload called by wp 1024. CCMS: AlMsUpload successful for /usr/sap/HBI/D01/log/ALMTTREE (132 MTEs). CCMS: start to initalize 3.X shared alert area (first segment). DpCheckPreemptionTicker: created thread for DpPremptionTicker DpMBufHwIdSet: set Hardware-ID ***LOG Q1C=> DpMBufHwIdSet [dpxxmbuf.c 1215] MBUF state ACTIVE DpMBufTypeMod: MBUF component UP (event=MBUF_DPEVT_UP) DpMsgProcess: 1 server in MBUF DpMsCheckServices() DpMsgProcess: MBUF state is MBUF_ACTIVE DpMBufReset: state = MBUF_PREPARED Thu Jan 4 12:35:24 2018 MBUF state ACTIVE DpMBufTypeMod: MBUF component UP (event=MBUF_DPEVT_UP) DpMsgProcess: 1 server in MBUF DpMsCheckServices() Thu Jan 4 12:35:25 2018 DpMBufTypeMod: MBUF server state ACTIVE (event=MBUF_DPEVT_DSTATE) DpModState: change server state from STARTING to ACTIVE DpMsInfo: MOD for server hananw_HBI_01 Thu Jan 4 12:35:26 2018 *** *** DpRqNoWpHandle: SHUTDOWN request from W7 received (reason=DP_SHUTDOWN_LARGE_TIME_DIFF) *** *** ********** SERVER SNAPSHOT 1 (Reason: Shutdown) - begin ********** Server hananw_HBI_01, Thu Jan 4 12:35:26 2018 Force W6 in state WP_RUN to dump stack Force W7 in state WP_RUN to dump stack Scheduler info -------------- WP info DpNumberOfDiaWps: dia_wps 10, standby_wps 0 #dia = 10 #btc = 3 #standby = 0 #max = 21 General Scheduler info preemptionInfo.isActive = true preemptionInfo.timeslice = 500 preemptionInfo.checkLoad = true Prio Class High maxRuntime[RQ_Q_PRIO_HIGH] = 600 sec maxRuntimeHalf[RQ_Q_PRIO_HIGH] = 300 sec Prio Class Normal maxRuntime[RQ_Q_PRIO_NORMAL] = 3600 sec maxRuntimeHalf[RQ_Q_PRIO_NORMAL] = 1800 sec maxTicketsForPrio[RQ_Q_PRIO_NORMAL] = 8 maxTicketsForPrioIncrement[RQ_Q_PRIO_NORMAL] = 0 withPrioTickets[RQ_Q_PRIO_NORMAL] = true Prio Class Low maxRuntime[RQ_Q_PRIO_LOW] = infinite maxRuntimeHalf[RQ_Q_PRIO_LOW] = infinite maxTicketsForPrio[RQ_Q_PRIO_LOW] = 7 maxTicketsForPrioIncrement[RQ_Q_PRIO_LOW] = 0 withPrioTickets[RQ_Q_PRIO_LOW] = true Actual tickets in use actTicketsInUseForPrio[RQ_Q_PRIO_NORMAL] = 8 actTicketsInUseForPrio[RQ_Q_PRIO_LOW] = 7 Running requests[RQ_Q_PRIO_NORMAL] = 0 Running requests[RQ_Q_PRIO_LOW] = 0 Queue Statistics Thu Jan 4 12:35:26 2018 ------------------------------------------------------------ Number of lost wakeup datagrams: 0 Max. number of queue elements : 14000 DIA : 0 (peak 3, writeCount 26, readCount 26) UPD : 0 (peak 2, writeCount 3, readCount 3) ENQ : 0 (peak 0, writeCount 0, readCount 0) BTC : 0 (peak 1, writeCount 3, readCount 3) SPO : 0 (peak 1, writeCount 3, readCount 3) UP2 : 0 (peak 1, writeCount 1, readCount 1) DISP: 0 (peak 2, writeCount 38, readCount 38) GW : 0 (peak 1, writeCount 2, readCount 2) ICM : 1 (peak 1, writeCount 4, readCount 3) LWP : 0 (peak 14, writeCount 15, readCount 15) Session queue dump (high priority, 0 elements, peak 2): Session queue dump (normal priority, 0 elements, peak 0): Session queue dump (low priority, 0 elements, peak 0): Requests in queue (1 requests, queue in use): - 1 requests for handler REQ_HANDLER_CREATE_SNAPSHOT Infos about some special queues: Queue in slot 0 (port=65258) has no requests Queue in slot 1 (port=26383) has no requests Dump of queue in slot 2 (1 requests, in use, port=13954): -1 <- 9 (rq_id 1152, NOWP, REQ_HANDLER_CREATE_SNAPSHOT) -> -1 Queue in slot 3 (port=0) has no requests Workprocess Table (long) Thu Jan 4 12:35:26 2018 ------------------------------------------------------------ Current snapshot id: 1 DB clean time (in percent of total time) : 12.49 % Number of preemptions : 0 |No |Pid |Type|State |Cause|Err|Prio|Sess-Key |Sess-Type|Locked|Sem|Time |Program |Cli|User |Action |Action-Info | |---|--------|----|-------|-----|---|----|----------------|---------|------|---|-----|----------------------------------------|---|------------|--------------------|--------------------| | 6|4455 |DIA |WP_RUN | | |high|T1_U6_M0 |INTERNAL | | | 2| |000|SAPSYS |READSEQ |REPOLOAD | | 7|4456 |DIA |WP_RUN | | |high|T7_U26_M0 |INTERNAL | | | 0| |000|SAPSYS | | | Found 2 active workprocesses Session Table Thu Jan 4 12:35:26 2018 ------------------------------------------------------------ |Logon-Type |Sess-Key |Cli|User |Terminal |Time |WP |Program |Prio|Tasks|Application-Info |Tcode |ES-Mem(KB)| |------------|----------------|---|------------|--------------------|--------|---|----------------------------------------|----|-----|--------------------------------------------------|----------|----------| |INTERNAL |T1_U6_M0 |000|SAPSYS | |12:35:24|6 | |high| | | |4200 | |INTERNAL |T7_U26_M0 |000|SAPSYS | |12:35:26|7 |SAPMSSY6 |high| | | |4234 | Found 2 logons with 2 sessions Communication Table is empty Thu Jan 4 12:35:26 2018 CA Blocks ------------------------------------------------------------ 0 ca_blk slots of 6000 in use, 0 currently unowned (in request queues) Thu Jan 4 12:35:26 2018 ********** SERVER SNAPSHOT 1 (Reason: Shutdown) - end ********** Thu Jan 4 12:35:36 2018 DpTriggerSapSnapshot: start /usr/sap/HBI/D01/exe/sapcontrol DpTriggerSapSnapshot: sapcontrol runs with pid 4572 ***LOG Q41=> DpDumpInternalTables, () [dpxxdisp.c 3481] DpHalt: shutdown server >hananw_HBI_01 < (normal) DpMBufTypeMod: MBUF server state SHUTDOWN (event=MBUF_DPEVT_DSTATE) DpModState: change server state from ACTIVE to SHUTDOWN DpHalt: stop work processes Thu Jan 4 12:35:37 2018 DpHalt: stop gateway DpHalt: stop icman DpHalt: terminate gui connections DpHalt: wait for end of work processes DpHalt: wait for end of gateway DpHalt: waiting for termination of gateway ... Thu Jan 4 12:35:38 2018 DpHalt: wait for end of icman DpHalt: waiting for termination of icman ... Thu Jan 4 12:35:41 2018 DpStartStopMsg: send stop message (myname is >hananw_HBI_01 <) DpStartStopMsg: stop msg sent to message server o.k. Thu Jan 4 12:35:42 2018 DpHalt: sync with message server o.k. DpHalt: detach from message server ***LOG Q0M=> DpMsDetach, ms_detach () [dpMessageSer 2179] MBUF state OFF DpMBufTypeMod: MBUF component DOWN (event=MBUF_DPEVT_DOWN) DpMsServerDown: wakeup waiting sessions DpHalt: ignore SIGCHLD from now on SigISetIgnoreAction : SIG_IGN for signal SIGCHLD DpHalt: cleanup EM DpHalt: cleanup event management DpHalt: cleanup shared memory/semaphores ***LOG Q05=> DpHalt, DpHalt ( 4418) [dpInit.c 3653] DpHalt: *** shutdown completed - server stopped ***

Add comment
10|10000 characters needed characters exceeded

  • Get RSS Feed

1 Answer

  • Best Answer
    Jan 04 at 09:17 PM

    Can you please check what is the time and date on the application server and the database? According to the error, I'm guessing the time difference is too big.

    Please also attach the dev_w7 log file and check following SAP Note:

    1913285 - Termination ZDATE_LARGE_TIME_DIFF and server shutdown

    Add comment
    10|10000 characters needed characters exceeded