Installation of SAP Netweaver 7.5 AS ABAP on Hana gets Timout - Start Instance

[Former Member]

Hi Experts,

I am new to this topic and have some problems with the installation of SAP Netweaver 7.5 AS ABAP on Hana.
I cant figure out the Errors with the generated logs.
The Prerequisite checks says everything is fine and DB connection is succesfull.

The instance stays on ACTIVE when running into timeout.

Does anyone have a solution for this or knows how to fix it?

Thanks and best regards,

Dominik

Dev_disp log:

---------------------------------------------------

trc file: "dev_disp.new", trc level: 1, release: "745"

---------------------------------------------------

sysno 01

sid NWX

systemid 390 (AMD/Intel x86_64 with Linux)

relno 7450

patchlevel 0

patchno 300

intno 20151301

make multithreaded, Unicode, 64 bit, optimized

profile /usr/sap/NWX/SYS/profile/NWX_D01_static

pid 2921

Thu Nov 3 14:31:48 2016

kernel runs with dp version 6000(ext=117000) (@(#) DPLIB-INT-VERSION-0+6000-UC)

length of sys_adm_ext is 500 bytes

***LOG Q00=> DpSapEnvInit, DPStart (01 2921) [dpInit.c 605]

shared lib "dw_xml.so" version 300 successfully loaded

shared lib "dw_xtc.so" version 300 successfully loaded

shared lib "dw_stl.so" version 300 successfully loaded

shared lib "dw_gui.so" version 300 successfully loaded

shared lib "dw_rndrt.so" version 300 successfully loaded

shared lib "dw_abp.so" version 300 successfully loaded

rdisp/softcancel_sequence : -> 0,5,-5

use internal message server connection to port 3902

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 >static_NWX_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: 7f9211f08000, size: 63159960

DpShMCreate: allocated sys_adm at 7f9211f08200

DpShMCreate: allocated wp_adm_list at 7f9211f1a1c0

DpShMCreate: allocated wp_adm at 7f9211f1a4e0

DpShMCreate: allocated tm_adm_list at 7f9211f20170

Thu Nov 3 14:31:48 2016

DpShMCreate: allocated tm_adm at 7f9211f203f0

DpShMCreate: allocated ca_adm at 7f9215157028

DpShMCreate: allocated comm_adm at 7f92151c09a8

DpShMCreate: system runs without slock table

DpShMCreate: allocated sock_adm at 7f9215774790

DpShMCreate: allocated vmc_adm_list at 7f92158eb9d0

DpShMCreate: system runs without VMC

DpShMCreate: allocated gw_adm at 7f92158ebc70

DpShMCreate: allocated j2ee_adm at 7f92158ebea8

DpShMCreate: allocated ca_info at 7f92158ed000

DpShMCreate: allocated wall_adm (ft) at 7f92158ed240

DpShMCreate: allocated wall_adm (fi) at 7f921591e1b8

DpShMCreate: allocated wall_adm (head) at 7f92159504b0

DpShMCreate: allocated amc_rec_adm (ft) at 7f92159506f0

DpShMCreate: allocated amc_rec_adm (fi) at 7f9215a9c9a8

DpShMCreate: allocated amc_rec_adm (head) at 7f9215af8c80

DpShMCreate: allocated websocket_adm (ft) at 7f9215af8eb8

DpShMCreate: allocated websocket_adm (head) at 7f9215b3e650

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)

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

Thu Nov 3 14:31:48 2016

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=7f92151c09a8/ft=7f92151c09b0/fi=7f921572d4a8)

DpSockAttachTable: attached sock table (header=7f9215774790/ft=7f9215774798)

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

<ES> client 21 initializing ....

<ES> EsILock: use Mutex for locking

<ES> InitFreeList

<ES> block size is 4096 kByte.

<ES> Info: em/initial_size_MB( 9023MB) not multiple of em/blocksize_KB( 4096KB)

<ES> Info: em/initial_size_MB rounded up to 9024MB

<ES> Info: disclaim threshold = 0 MB

<ES> Info: disclaim coasting/alloc = 300 s

<ES> Info: disclaim coasting/free = 0 s

<ES> Info: blockdisclaimsize_KB = 0 KB

Using implementation std

<ES> Info: use mapped file

EsStdUnamFileMapInit: ES Segment = 0x7f8fcf9dd000 - 0x7f92039dd000

EsStdInit: Extended Memory 9024 MB allocated

Linux: Kernel supports shared memory disclaiming

Linux: using madvise(<pointer>, <size>, 9).

Linux: disclaiming for shared memory enabled

Thu Nov 3 14:31:48 2016

<ES> 2255 blocks reserved for free list.

ES initialized.

EgInit: EG initialized with 1724M in 1 segments

mm.dump: set global maximum dump mem to 192 MB

EsRegisterEmCheck: Register EmGetEsHandles at 17b28f2

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 ( static) [dpMessageSer 1685]

DpStartStopMsg: send start message (myname is >static_NWX_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 2923)

DpIcmCreate: forked ICM (pid 2924)

DpWpDynCreate: created new work process W0-2925

DpWpDynCreate: created new work process W1-2926

DpWpDynCreate: created new work process W2-2927

DpWpDynCreate: created new work process W3-2928

DpWpDynCreate: created new work process W4-2929

DpWpDynCreate: created new work process W5-2930

DpWpDynCreate: created new work process W6-2931

DpWpDynCreate: created new work process W7-2932

DpWpDynCreate: created new work process W8-2933

DpWpDynCreate: created new work process W9-2934

DpWpDynCreate: created new work process W10-2935

DpWpDynCreate: created new work process W11-2936

DpWpDynCreate: created new work process W12-2938

DpWpDynCreate: created new work process W13-2939

DpWpDynCreate: created new work process W14-2940

Thu Nov 3 14:31:49 2016

DpWpDynCreate: created new work process W15-2941

CGROUPS: changing prio of pid 2921 to medium

CGROUPS: disabled

*** WARNING: resource limit value for 'max open files' too low (4096 < 32800)

**START Linux Memory Parameter Check

virtual memory: hard-limit = UNLIMITED

virtual memory: soft-limit = UNLIMITED

core size: hard-limit = UNLIMITED

core size: soft-limit = UNLIMITED

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

max open files: soft-limit = 4096

Page Size: 4 KB

kernel.shmmax = 132995 MB

kernel.shmall = 4503599627370495 MB

**END Linux Memory Parameter Check

Linux scheduler "SCHED_OTHER" used with prio 0

CCMS: SemInMgt: Initializing Semaphore Management in AlAttachShm_Doublestack.

CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.

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 Nov 3 14:31:55 2016

MBUF state ACTIVE

DpMBufTypeMod: MBUF component UP (event=MBUF_DPEVT_UP)

DpMsgProcess: 1 server in MBUF

DpMsCheckServices()

Thu Nov 3 14:31:58 2016

DpWpDynCreate: created new work process W16-3001

Thu Nov 3 14:32:01 2016

DpMBufTypeMod: MBUF server state ACTIVE (event=MBUF_DPEVT_DSTATE)

DpModState: change server state from STARTING to ACTIVE

DpMsInfo: MOD for server static_NWX_01

Thu Nov 3 14:37:09 2016

DpWpCheck: dyn W16, pid 3001 no longer needed, terminate now

DpHdlDeadWp: W16 (pid=3001) terminated automatically

_______________________________________________________________

DEV_W0 log:

---------------------------------------------------

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

---------------------------------------------------

*

* ACTIVE TRACE LEVEL 1

* ACTIVE TRACE COMPONENTS all, MJ

*

M sysno 01

M sid NWX

M systemid 390 (AMD/Intel x86_64 with Linux)

M relno 7450

M patchlevel 0

M patchno 300

M intno 20151301

M make multithreaded, Unicode, 64 bit, optimized

M profile /usr/sap/NWX/SYS/profile/NWX_D01_static

M pid 2925

M

M

M Thu Nov 3 14:31:48 2016

M kernel runs with dp version 6000(ext=117000) (@(#) DPLIB-INT-VERSION-0+6000-UC)

M length of sys_adm_ext is 500 bytes

M ThStart: taskhandler started

M ThInit: initializing DIA work process W0

X MMX: use precise segment size globally

M ThStopHeapLockChecker: stop heap lock checker

M ***LOG Q01=> ThInit, WPStart (Workp. 0 1 2925) [thxxhead.c 1084]

M

M Thu Nov 3 14:31:49 2016

M ThInit: running on host static

I MtxInit: 0 0 0

M calling db_connect ...

B Loading DB library '/usr/sap/NWX/D01/exe/dbhdbslib.so' ...

B Library '/usr/sap/NWX/D01/exe/dbhdbslib.so' loaded

B Version of '/usr/sap/NWX/D01/exe/dbhdbslib.so' is "745.04", patchlevel (0.300)

C Callback functions for dynamic profile parameter registered

C

C DBHDBSLIB : version 745.04, patch 0.300 (Make PL 0.300)

C HDB shared library (dbhdbslib) patchlevels (last 10)

C (0.300) Suspend database connection (note 2364819)

C (0.300) SAP Support Package Stack Kernel 7.45 Patch Level 300 (note 2361598)

C (0.211) Disable UPSERT (note 2323253)

C (0.211) Db metadata of a CALL statement (note 2333629)

C (0.210) Wrong marker map (note 2305430)

C (0.210) Suspend connection with webgui application (note 2307859)

C (0.200) SAP Support Package Stack Kernel 7.45 Patch Level 200 (note 2299971)

C (0.116) Call of macro DBST_ENTER_EXEC_STATIC corrected (note 2299198)

C (0.113) Set enable_select_into_scalar_UDF only for SP8 and SP9 (note 2105480)

C (0.113) Distinction between SQL error and warning 2 (note 2288531)

C

C

C Loading SQLDBC client runtime ...

C SQLDBC Module : /usr/sap/NWX/hdbclient/libSQLDBCHDB.so

C SQLDBC Runtime : libSQLDBCHDB 1.00.120.024.1461933179

C SQLDBC client runtime is 1.00.120.024.1461933179

C

C Try to connect via secure store (DEFAULT) on connection 0 ...

C Attach to HDB : 1.00.120.00.1462275491 (fa/hana1sp12)

C fa/hana1sp12 : build_weekstone=0000.00.0

C fa/hana1sp12 : build_time=2016-05-03 14:19:33

C Database release is HDB 1.00.120.00.1462275491

C INFO : Database 'H01/00' instance is running on 'localhost'

C INFO : Connect to DB as 'SAPABAP6', connection_id=301789

C INFO : DATA AGING feature is supported by HANA

C INFO : FDA feature is supported by HANA

C INFO : FAE with FDA feature is supported by HANA

C INFO : DEFERRED LOB WRITING is activated

C DB max. input host variables : 32767

C DB max. statement length : 104857600

C DB max. array size : 100000

C use decimal precision as length

C ABAPVARCHARMODE is used

C

C Thu Nov 3 14:31:49 2016

C use DBSL marker map

C use DBSL session variable handling

C INFO : DBSL buffer size = 1048576

C Command info enabled

C Now I'm connected to HDB

C 00: localhost-H01/00, since=20161103143149, ABAP= <unknown> (0)

B Connection 0 opened (DBSL handle 0)

B *-- dbmetadata DbVendorMetaData --------------*

B Connection : R/3[0]

B DB vendor : HDB,745.04

B DB vendor : 7

B DBSL version : 745.04

B DBSL features

B maxstmtsize : 104857600

B maxmarkercnt : 32767

B maxincnt : 1024

B maxlongsize : 32768

B maxhistlongsize : 32768

B minhistlongsize : 0

B maxmarkersize : 1

B autorpad : 0

B stringspadded : 0

B blk1obj1mod : 1

B haverownum : 0

B havepermanentstmts : 0

B fupdlockperm : 1

B fupdorder : 1

B preferunionall : 0

B prefer_join : 0

B prefer_join_with_fda : 1

B preferinitabopt : 1

B useinputsize : 0

B preferfixblocking : 0

B maxblockingfactor : 50

B minblockingfactor : 0

B maxinblockingfactor : 1024

B mininblockingfactor : 0

B maxunionblockingfactor : 0

B minunionblockingfactor : 0

B prefer_asterisk : 15

B

B Thu Nov 3 14:31:49 2016

B deferred_lob_writing : 1

B prefer_count_big : 0

B supports_fda_prot : 1

B integer_operation_cast : 0

B modulo_operator : 0

B smallint_cast : 0

B integer_division_mode : 2

B set_session_variables_always: 1

B FAE controls

B supports_fda_prot : 1

B prefer_in_itab_opt : 1

B prefer_union_all : 0

B prefer_join : 0

B prefer_join_with_fda : 1

B prefer_fix_blocking : 0

B max_blocking_factor : 50

B min_blocking_factor : 0

B max_in_blocking_factor : 1024

B min_in_blocking_factor : 0

B max_union_blocking_factor : 0

B min_union_blocking_factor : 0

B DB feature mode

B views_parameters_unnamed: 0

B views_with_parameters : 1

B release restricion : true

B external_views : 1

B release restricion : true

B TABLE_KEYCNT_MAX1 : 1

B release restricion : true

B TABLE_KEYLEN_MAX1 : 1

B release restricion : true

B TABLE_LEN_MAX1 : 1

B release restricion : true

B AMDP_TABLE_FUNCTION : 1

B release restricion : true

B CALL_DATABASE_PROCEDURE : 1

B release restricion : true

B CALL_AMDP_METHOD : 1

B release restricion : true

B Hint settings

B

B Thu Nov 3 14:31:49 2016

B ABAP hints enabled : 1

B DBI hints enabled : 1

B DBI hints enforced : 0

B upto limit : 0

B Misc settings

B DB buffer size: 1048576

B Reserved characters: {null}

B UPTO handling: 3

B Marker Map: 1

B *------------- DbVendorMetaData --------------*

C INFO : SAP RELEASE (DB) = 750

M ThDbConnect: db_connect o.k.

X

X Thu Nov 3 14:31:53 2016

X EmInit: MmSetImplementation( 2 ).

X MM global diagnostic options set: 0

X <ES> client 0 initializing ....

X Using implementation std

X <ES> Info: use mapped file

X Linux: Kernel supports shared memory disclaiming

X Linux: using madvise(<pointer>, <size>, 9).

X Linux: disclaiming for shared memory enabled

X ES initialized.

X mm.dump: set global maximum dump mem to 192 MB

X EsRegisterEmCheck: Register EmGetEsHandles at 17b28f2

B hook function dbdt_trace_lvl_callback registered. Current trace level is 1

B db_con_shm_ini: WP_ID = 0, WP_CNT = 21, CON_ID = -1

B Start CursorInit

B TBI initializing semaphore lock manager ...

B tbi_get_buffer_size_MB: rsdb/tbi_buffer_area_MB set to default 1

B => table buffer size 1092(MB) derived from rtbb/buffer_length=92396(KB) and zcsa/table_buffer_area=946130125(B)

B tbi_get_dir_entries: rsdb/tbi_dir_entries set to default 100

B => directory size 203270 derived from rtbb/max_tables=18479 and zcsa/db_max_buftab=184791

B tbx_set_req: table buffer is configured with

B overall size = 1145044992 bytes

B directory = 203270 entries

B protect_shm = off

B displacement = on

B request_unit = 2000

B displ_timeout = 100000

B inval_with_clear = on

B sync_reload = 5

B inval_reload = 5

B free_displ_perc = 3

B req_displ_perc = 5

B max_displ_perc = 10

B dbtbxbuf: Buffer TBI64 (addr: 7f8fa80c90e8, size: 1145044992, end: 7f8fec4c90e8)

B dbtbxbuf: TBI_ALL: Time recording variables are reset.

B DBI: init TBI BUFFER (data): start=7f8fcfede000 length=1106284032 end=7f9011de6e00

B DbSqlObjectFactory created, max = 1000, useCache true

B DbStatementManager created, max = 1500, useCache true

B dbprotocol: table logging switched off for all clients

B DbDmlCursor::maxItabInvalidationThreshold = 20

B DB FDA shm initialized: ptr=7f8faa6232b0 total_size_wp=1048576lu used_size_wp=1048576

B dbsync[db_syinit]: successfully attached to shared memory, sync_adm_p = 7f8fbf559000

B dbsync[db_syinit]: Buffer synchronisation started with

B sync_concept = SEQ_NR

B sendon = 0

B bufreftime = 120

B max_gap_wait_time = 60

B ddlog_del_time = 60

B last_counter = -2147483648

B oldest_gap = (2147483647,19700101010000)

B time_of_last_sync = 20161103143149

B MySysId = 'static 01'

B dbexpbuf[EXP_SHB]: successfully attached to shared memory

B dbexpbuf[EXP_SHB]: buffer installed with the following properties

B semKey = 35

B shmKey = 54

B wpCnt = 21

B blockSize = 512

B maxObjects = 32998

B maxObjectSize = 30686208

B freeListVectorSize = 489

B hashVectorSize = 66029

B bufferSize = 135161856

B maxBlocks = 239736

B freeBlocksCnt = 239736

B mutexVectorSize = 66031, ADMIN_MUTEX = 66029, INVAL_MUTEX = 66030

B tracing = 0

B forceChecks = 0

B protectShm = 0

B dbexpbuf[EXP_SHM]: successfully attached to shared memory

B dbexpbuf[EXP_SHM]: buffer installed with the following properties

B semKey = 56

B shmKey = 65

B wpCnt = 21

B blockSize = 512

B maxObjects = 2000

B maxObjectSize = 859648

B freeListVectorSize = 17

B hashVectorSize = 4001

B bufferSize = 4194304

B maxBlocks = 6716

B freeBlocksCnt = 6716

B mutexVectorSize = 4003, ADMIN_MUTEX = 4001, INVAL_MUTEX = 4002

B tracing = 0

B forceChecks = 0

B protectShm = 0

B dbexpbuf[EXP_CUA]: successfully attached to shared memory

B dbexpbuf[EXP_CUA]: buffer installed with the following properties

B semKey = 30

B shmKey = 47

B wpCnt = 21

B blockSize = 512

B maxObjects = 2000

B maxObjectSize = 577920

B freeListVectorSize = 587

B hashVectorSize = 4001

B bufferSize = 3072000

B maxBlocks = 4515

B freeBlocksCnt = 4515

B mutexVectorSize = 4003, ADMIN_MUTEX = 4001, INVAL_MUTEX = 4002

B tracing = 0

B forceChecks = 0

B protectShm = 0

B dbexpbuf[EXP_OTR]: successfully attached to shared memory

B dbexpbuf[EXP_OTR]: buffer installed with the following properties

B semKey = 55

B shmKey = 64

B wpCnt = 21

B blockSize = 256

B maxObjects = 2000

B maxObjectSize = 859584

B freeListVectorSize = 33

B hashVectorSize = 4001

B bufferSize = 4194304

B maxBlocks = 13431

B freeBlocksCnt = 13431

B mutexVectorSize = 4003, ADMIN_MUTEX = 4001, INVAL_MUTEX = 4002

B tracing = 0

B forceChecks = 0

B protectShm = 0

B NTAB: Structure of NTAB on DB is VERS_B, unicodelg of executable is 2, unicodelg of SVERS in nametab is 2

M SHM_PRES_BUF(addr: 7f9216c64000, size: 4400000)

M ThCreateNoBufferallocated 548152 bytes for 1000 entries at 7f8fbf852000

M ThCreateNoBufferindex size: 3000 elems

M ThCreateVBAdmallocated 33376 bytes (50 server) at 7f9226dcc000

M

M Thu Nov 3 14:31:53 2016

M ICT: exclude compression: *.zip,*.rar,*.arj,*.z,*.gz,*.tar,*.lzh,*.cab,*.hqx,*.ace,*.jar,*.ear,*.war,*.jpg,*.jpeg,*.pdf,*.gzip,*.uue,*.bz2,*.iso,*.sda,*.sar,*.gif,*.png,*.swc,*.swf

G RelWritePermissionForShm( pLocation = 120, pEnforce = 0 )

G GetWritePermissionForShm( pLocation = 99, pEnforce = 1 )

G RelWritePermissionForShm( pLocation = 100, pEnforce = 1 )

S *** init spool environment

S TSPEVJOB updates outside critical section: event_update_nocsec = 1

S initialize debug system

T Stack direction is downwards.

T debug control: prepare exclude for printer trace

T new memory block 7f8faa723330

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

I *** INFO SHM 49 in POOL 40 2172 KB estimated 1632 KB real ( -540 KB -25 %)

S found processing queue enabled

S found spool memory service RSPO-RCLOCKS at 7f8fbf8d9070

S doing lock recovery

S setting server cache root

S found spool memory service RSPO-SERVERCACHE at 7f8fbf8d96b0

S using messages for server info

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

S size of open spool request entry: 2512 bytes

S immediate print option for implicitely closed spool requests is disabled

B dbtran INFO (init_connection '<DEFAULT>' [HDB:745.04]):

B max_blocking_factor = 50, min_blocking_factor = 50,

B max_in_blocking_factor =1024, min_in_blocking_factor =1024,

B max_union_blocking_factor = 20, min_union_blocking_factor = 20,

B prefer_union_all = 0, prefer_join = 0,

B prefer_fix_blocking = 0, prefer_in_itab_opt = 1,

B convert AVG = 1, alias table FUPD = 0,

B escape_as_literal = 0,

B select * =0x0f, character encoding = STD / <none>:-,

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

M rscpInstLangInit: logon_languages found in TCP0I, value = DE

M rscpInstLangInit called rscpSetZCSAInstalledLanguages->0 and rscpInstLangSet->0.

M ThrCreateShObjectsallocated 43684 bytes at 7f9216bd4000

I MPI: dynamic quotas disabled.

M CCMS: SemInMgt: Initializing Semaphore Management in AlAttachShm_Doublestack.

M CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.

A PXA: using abap/buffersize 1851392

A abStoreSetDiagMode: set ABAP mm diag mode to OFF

O PXA: max_map_count = 13600000: ok

O ***GENER* Trace switched on ***

O

O Thu Nov 3 14:31:53 2016

O

O ---PXA-------------------------------------------

O PXA INITIALIZATION

O PXA: Locked PXA-Semaphore.

O System page size: 4kb, total admin_size: 137348kb, dir_size: 44612kb.

O Attached to PXA (address 7f8f1d975000 - 7f8f8e975000, size 1851392K, 3 fragments of 571348K )

O *** using a guard page at PXA start to protect parameters and release info ***

O abap/pxa = shared protect gen_remote

O PXA: checking structure sizes: 848|264|16

O PXA INITIALIZATION FINISHED

O ---PXA-------------------------------------------

O

A ATRA: pfclock execution time = 0

A abap/force_local_update_task=0

A ABAP ShmAdm attached (addr=7f9012585000 leng=188727296 end=7f901d981000)

A >> Shm MMADM area (addr=7f90150085f0 leng=2217664 end=7f9015225cb0)

A >> Shm MMDAT area (addr=7f9015226000 leng=141930496 end=7f901d981000)

A RFC Destination> destination static_NWX_01 host static system NWX systnr 1 (static_NWX_01)

A RFC Options> H=static,S=01,d=2,

B dbrda: Redirect database access is disabled via rsdb/rda profile parameter setting.

B table logging switched off for all clients

A RFC FRFC> fallback ist not active. Do nothing.

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: 2

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

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

H HTTP> Parameter icf/ssocookie_mandatory set to 0

Y dyWpInit

Y ztta/dynpro_area 1000000

Y ztta/cua_area 500000

Y ztta/diag_area 250000

Y Diag resize output area 0 250000

N MskiInitLogonTicketCacheHandle: Logon Ticket cache pointer retrieved from shared memory.

N MskiInitLogonTicketCacheHandle: Workprocess runs with Logon Ticket cache.

W =================================================

W === ipl_Init() called

W ITS Plugin: Path dw_gui

W

W Thu Nov 3 14:31:53 2016

W ITS Plugin: Description ITS Plugin - ITS rendering DLL

W ITS Plugin: sizeof(SAP_UC) 2

W ITS Plugin: Release: 745, [7450.0.300.20151301]

W ITS Plugin: Int.version, [35]

W ITS Plugin: Feature set: [34]

W ===... Calling itsp_Init in external dll ===>

W === ipl_Init() returns 0, ITSPE_OK: OK

W =================================================

N VSI: WP init in ABAP VM completed with rc=0

N SsfSapSecin: getenv(SECUDIR)=="/usr/sap/NWX/D01/sec"

N

N =================================================

N === SSF INITIALIZATION:

N ===...SSF Security Toolkit name SAPSECULIB .

N ===...SSF library is /usr/sap/NWX/D01/exe/libsapcrypto.so .

N ===...SSF default hash algorithm is SHA1 .

N ===...SSF default symmetric encryption algorithm is AES128-CBC .

N ===...SSF lib codepage is set to 1 .

N ===...SSF FIPS 140-2 mode is disabled .

N ===...SECUDIR="/usr/sap/NWX/D01/sec"

N ===...loading of Security Toolkit successfully completed.

N === CommonCryptoLib 8.5.4 (Sep 5 2016) [AES-NI,CLMUL,SSE3,SSSE3]

N =================================================

N

N Thu Nov 3 14:31:55 2016

N SignInit: successfully obtained handle for Security Context cache

N SPNegoInit: SPNego disabled ("spnego/enable" not set to 1)

M JrfcVmcRegisterNativesDriver o.k.

M CGROUPS: ThSetProcessPriority workerType=1

M CGROUPS: changing prio of pid 2925 to medium

M CGROUPS: disabled

E EnqId_Initialize: local EnqId initialization o.k.

M ThrtAmcInit: use DB table AMC_RECEIVER2 for AMC dispatching

M Set restart flag on 1024 MB os heap (val=1G)

A

A Thu Nov 3 14:31:58 2016

A AppServerTimeSync, checkLocalTime():

A Initializing local WP variables:

A zdate_LastLocalTime with 1478179918

A zdate_LastLocalBias with 0

A *GENER* starting remote generation: RSM13000 (requested by W10).

B DBREPO: MAJOR_VERS part of REPOLOAD primary key

B DBREPO: set new access functions for table REPOLOAD

A *GENER* got lock for program RSM13000.

E Enqueue Info: enque/use_pfclock2 = FALSE

E Profile-Parameter: enque/sync_dequeall = 0

C FDA DB protocol version from connection 0 = 1

O *GENER* Performing COMMIT.

C

C Thu Nov 3 14:31:59 2016

C

C Try to connect via secure store (SERVICE) on connection 1 ...

C Attach to HDB : 1.00.120.00.1462275491 (fa/hana1sp12)

C Database release is HDB 1.00.120.00.1462275491

C INFO : Database 'H01/00' instance is running on 'localhost'

C INFO : Connect to DB as 'SAPABAP6', connection_id=301806

C INFO : DEFERRED LOB WRITING is activated

C Command info enabled

C Now I'm connected to HDB

C 00: localhost-H01/00, since=20161103143149, ABAP= <unknown> (0)

C 01: localhost-H01/00, since=20161103143159, ABAP= (0)

B Connection 1 opened (DBSL handle 1)

A *GENER* starting remote generation: CL_OS_TRANSACTION_END_NOTIFIERCP (requested by W10).

A *GENER* got lock for program CL_OS_TRANSACTION_END_NOTIFIERCP.

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: SAPLTHFB (requested by W10).

A *GENER* got lock for program SAPLTHFB.

O

O Thu Nov 3 14:32:00 2016

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: IF_AGENT_TYPES================IP (requested by W7).

A *GENER* got lock for program IF_AGENT_TYPES================IP.

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: CL_ABAP_SYST==================CP (requested by W7).

A *GENER* got lock for program CL_ABAP_SYST==================CP.

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: IF_WORKER_TYPES===============IP (requested by W7).

A *GENER* got lock for program IF_WORKER_TYPES===============IP.

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: SAPLURFC (requested by W7).

A *GENER* got lock for program SAPLURFC.

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: SAPLSPLUGIN (requested by W7).

A *GENER* got lock for program SAPLSPLUGIN.

O *GENER* Performing COMMIT.

Y

Y ===> running separate LUW generation: 20161103143200

Y ===> requesting WP: 7

A *GENER* starting inline generation: SAPLSMPI (reason: additional generation during remote generation).

A *GENER* got lock for program SAPLSMPI.

D GuiStatus generate status prog >MENUSYST< status >INI< ts >20161103133200,589<

D GuiStatus set generate inline ts >20161103133200,589<

A *GENER* starting inline generation: SAPLSEU1 (reason: additional generation during remote generation).

A *GENER* got lock for program SAPLSEU1.

A *GENER* starting inline generation: CL_ABAP_FILE_UTILITIES========CP (reason: additional generation during remote generation).

A *GENER* got lock for program CL_ABAP_FILE_UTILITIES========CP.

A *GENER* starting inline generation: SAPLSMOD (reason: additional generation during remote generation).

A *GENER* got lock for program SAPLSMOD.

A

A Thu Nov 3 14:32:01 2016

A *GENER* starting inline generation: SAPLSEXE (reason: additional generation during remote generation).

A *GENER* got lock for program SAPLSEXE.

A *GENER* starting inline generation: CL_ENH_BADI_RUNTIME_FUNCTIONS=CP (reason: additional generation during remote generation).

A *GENER* got lock for program CL_ENH_BADI_RUNTIME_FUNCTIONS=CP.

A

A Thu Nov 3 14:32:02 2016

A *GENER* starting inline generation: CX_ENH_BADI_NO_CUA_EXTENSION==CP (reason: additional generation during remote generation).

A *GENER* got lock for program CX_ENH_BADI_NO_CUA_EXTENSION==CP.

A *GENER* starting inline generation: CX_ENH_ROOT===================CP (reason: additional generation during remote generation).

A *GENER* got lock for program CX_ENH_ROOT===================CP.

A *GENER* starting inline generation: CX_STATIC_CHECK===============CP (reason: additional generation during remote generation).

A *GENER* got lock for program CX_STATIC_CHECK===============CP.

A *GENER* starting inline generation: CX_ROOT=======================CP (reason: additional generation during remote generation).

A *GENER* got lock for program CX_ROOT=======================CP.

A *GENER* starting inline generation: CX_NO_CHECK===================CP (reason: additional generation during remote generation).

A *GENER* got lock for program CX_NO_CHECK===================CP.

A *GENER* starting inline generation: CL_I18N_LANGUAGES=============CP (reason: additional generation during remote generation).

A *GENER* got lock for program CL_I18N_LANGUAGES=============CP.

O *GENER* Performing COMMIT.

D GuiStatus clear generate inline ts >20161103133202,545<

Y

Y ===> running separate LUW generation: 20161103143202

Y ===> requesting WP: 7

D GuiStatus generate texts prog >MENUSYST< ts >20161103133202,548<

D GuiStatus set generate inline ts >20161103133202,548<

D GuiStatus clear generate inline ts >20161103133202,563<

Y

Y ===> running separate LUW generation: 20161103143202

Y ===> requesting WP: 7

D GuiStatus update texts prog >MENUSYST< ts >20161103133202,564<

D GuiStatus set generate inline ts >20161103133202,564<

D GuiStatus store texts prog >MENUSYST< ts >20161103133202,564<

D GuiStatus clear generate inline ts >20161103133202,574<

A

A Thu Nov 3 14:32:09 2016

A *GENER* starting remote generation: CL_BGRFC_SUPERVISOR_START=====CP (requested by W7).

A *GENER* got lock for program CL_BGRFC_SUPERVISOR_START=====CP.

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: /1BCDWBEN/SAPLSEN0016 (requested by W7).

A *GENER* got lock for program /1BCDWBEN/SAPLSEN0016.

O *GENER* Performing COMMIT.

A *GENER* starting remote generation: CL_BGRFC_KERNEL_TRACE=========CP (requested by W7).

A *GENER* got lock for program CL_BGRFC_KERNEL_TRACE=========CP.

O *GENER* Performing COMMIT.

[MarioDeFelipe]

Hello Dominik

Dev_disp is showing that WP16 died, not WP0, can you please paste WP16 log?

thanks