Failed To Start Instance during Installation

Former Member · ‎11-01-2011

Dear All,

I m doing Solman 7.0 With Ehp1 with database 10g on Linux 64 bit ( Red Hat).facing Error During Start Instance paste log below.Log of W0

ACTIVE TRACE LEVEL 1
ACTIVE TRACE COMPONENTS all, MJ

*

M Sun Oct 30 20:38:42 2011

M kernel runs with dp version 245000(ext=110000) (@(#) DPLIB-INT-VERSION-245000-UC)

M length of sys_adm_ext is 576 bytes

M ThInit: rdisp/cleanup_after_crash = 1

M ***LOG Q01=> ThInit, WPStart (Workproc 0 1 7257) [thxxhead.c 1346]

M ThInit: running on host sapsol

M calling db_connect ...

B Loading DB library '/usr/sap/SOL/DVEBMGS00/exe/dboraslib.so' ...

B Library '/usr/sap/SOL/DVEBMGS00/exe/dboraslib.so' loaded

B Version of '/usr/sap/SOL/DVEBMGS00/exe/dboraslib.so' is "700.08", patchlevel (0.134)

C Oracle Client Version: '10.2.0.2.0', (dbsl 701 090311, UNICODE[2])

C Application info callback registered.

C Client NLS setting (by OCINlsGetInfo): con=0, 'AMERICAN_AMERICA.UTF16'

C Logon as OPS$-user to get SAPSR3's password

C Connecting as /@SOL on connection 0 (nls 0) ... (dbsl 701 090311, UNICODE[2])

C Attaching to DB Server SOL (con_hdl=0,svchp=0x150ee598,srvhp=0x150f1028)

C Starting user session: OCISessionBegin(con_hdl=0, usr='/',svchp=0x150ee598, srvhp=0x150f1028, usrhp=0x150de728)

C CbApplInfoGet() failed (ignored 1).

C Now '/@SOL' is connected: con_hdl=0, nls_hdl=0, session_id=148.

C Got SAPSR3's password from OPS$-user

C Disconnecting from connection 0 ...

C Closing user session (con_hdl=0,svchp=0x150ee598,usrhp=0x150de728)

C Disconnected (con=0) from ORACLE.

C Connecting as SAPSR3/<pwd>@SOL on connection 0 (nls 0) ... (dbsl 701 090311, UNICODE[2])

C Starting user session: OCISessionBegin(con_hdl=0, usr=SAPSR3/<pwd>, svchp=0x150ee598, srvhp=0x150f1028, usrhp=0x150de728)

C Now 'SAPSR3/<pwd>@SOL' is connected: con_hdl=0, nls_hdl=0, session_id=148.

C con=0, V$NLS_PARAMETERS: NLS_LANG=AMERICAN_AMERICA.UTF8, NLS_NCHAR=UTF8, UNI_NON=FALSE

C Nls CharacterSet NationalCharSet EnvHp ErrHp ErrBt

C 0 UTF16 AL16UTF16 0x150d6460 0x150ddf10 0x150ee668

C DB instance SOL is running on sapsol with ORACLE version 10.2.0.4.0 since OCT 30, 2011, 20:38:09

B

B Sun Oct 30 20:38:43 2011

B Connection 0 opened (DBSL handle 0)

M db_connect o.k.

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

I MtxInit: 0 0 0

M SHM_PRES_BUF (addr: 0x2b42dd101000, size: 4400000)

M SHM_ROLL_AREA (addr: 0x2b43f1a7f000, size: 606208000)

M SHM_PAGING_AREA (addr: 0x2b4415c9f000, size: 268435456)

M SHM_ROLL_ADM (addr: 0x2b42dd535000, size: 11116480)

M SHM_PAGING_ADM (addr: 0x2b4425c9f000, size: 3277856)

M ThCreateNoBuffer allocated 560152 bytes for 1000 entries at 0x2b4425fc2000

M ThCreateNoBuffer index size: 3000 elems

M ThCreateVBAdm allocated 12176 bytes (50 server) at 0x2b442ca90000

X EmInit: MmSetImplementation( 2 ).

X MM global diagnostic options set: 0

X <ES> client 0 initializing ....

X Using implementation map

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 maximum dump mem to 96 MB

M Deactivate statistics hyper index locking

I *** INFO Shm 45 in Pool 40 4771 KB estimated 3625 KB real ( -1146 KB -25 %)

B dbntab: NTAB buffers created

B dbntab: Buffer FTAB(hash header) (addr: 0x2b442604c088, size: 392)

B dbntab: Buffer FTAB(anchor array) (addr: 0x2b442604c210, size: 320072)

B dbntab: Buffer FTAB(item array) (addr: 0x2b442609a458, size: 1280000)

B dbntab: Buffer FTAB(data area) (addr: 0x2b44261d2c58, size: 30720000)

B dbntab: Buffer IREC(hash header) (addr: 0x2b4427f20088, size: 392)

B dbntab: Buffer IREC(anchor array) (addr: 0x2b4427f20210, size: 320072)

B dbntab: Buffer IREC(item array) (addr: 0x2b4427f6e458, size: 320000)

B dbntab: Buffer IREC(data area) (addr: 0x2b4427fbc658, size: 6144000)

B dbntab: Buffer STAB(hash header) (addr: 0x2b442859a088, size: 392)

B dbntab: Buffer STAB(anchor array) (addr: 0x2b442859a210, size: 320072)

B dbntab: Buffer STAB(item array) (addr: 0x2b44285e8458, size: 320000)

B dbntab: Buffer STAB(data area) (addr: 0x2b4428636658, size: 3072000)

B dbntab: Buffer TTAB(hash header) (addr: 0x2b4428926088, size: 2160)

B dbntab: Buffer TTAB(anchor array) (addr: 0x2b44289268f8, size: 320072)

B dbntab: Buffer TTAB(item array) (addr: 0x2b4428974b40, size: 800000)

B dbntab: Buffer TTAB(data area) (addr: 0x2b4428a38040, size: 5840000)

B

B Sun Oct 30 20:38:50 2011

B dbstat: table statistics switched on for 35493 tables

B dbstat: TABSTAT buffer created (addr: 0x2b42e6ec1000, size: 17380280)

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

B dbtbxbuf: Buffer TABL (addr: 0x2b442ca93100, size: 30000000, end: 0x2b442e72f480)

B dbtbxbuf: Profile: max_objects = 5000, displace = 1, reorg = 1

B dbtbxbuf: request_unit = 2000, sync_reload = 5, inval_reload = 5

B dbtbxbuf: protect_shm = 0, force_checks = 0

B dbtbxbuf: tsize_retry = 14203392

B ***LOG BB0=> buffer TABL started with length 30000000 bytes [dbtbxbuf#6 @ 16383] [dbtbxbuf1638 3]

B dbtbxbuf: Buffer TABLP (addr: 0x2b442e730100, size: 10240000, end: 0x2b442f0f4100)

B dbtbxbuf: Profile: max_objects = 500, displace = 1, reorg = 1

B dbtbxbuf: request_unit = 2000, sync_reload = 5, inval_reload = 5

B dbtbxbuf: protect_shm = 0, force_checks = 0

B dbtbxbuf: tsize_retry = 5030144

B ***LOG BB0=> buffer TABLP started with length 10240000 bytes [dbtbxbuf#6 @ 16383] [dbtbxbuf1638 3]

B dbtbxbuf: Reading TBX statistics:

B dbtbxbuf: Opening /usr/sap/SOL/DVEBMGS00/data/tbxstat failed (No such file or directory).

B dbtbxbuf: Opening /usr/sap/SOL/DVEBMGS00/data/tbxnew failed (No such file or directory).

B dbtbxbuf: Renaming /usr/sap/SOL/DVEBMGS00/data/tbxnew to /usr/sap/SOL/DVEBMGS00/data/tbxstat failed (No such file or directory).

B dbtbxbuf: 0 object entries precreated

B

B Sun Oct 30 20:38:51 2011

B dbexpbuf[EXP_SHB]: Aquired semaphore 35 in db_exp_init(EXP_INIT)

B dbexpbuf[EXP_SHB]: shared memory layout of buffer EIBUF:

B EYE-CATCHER : 1 * 4 = 4

B ***LOG BB0=> buffer EIBUF started with length 4096k bytes [dbexpbuf#3 @ 2409] [dbexpbuf2409 ]

B dbexpbuf[EXP_SHB]: semaphore 35 released in db_exp_init

B dbexpbuf[EXP_SHM]: Aquired semaphore 56 in db_exp_init(EXP_INIT)

B dbexpbuf[EXP_SHM]: shared memory layout of buffer ESM :

B EYE-CATCHER : 1 * 4 = 4

B ***LOG BB0=> buffer OTR started with length 4096k bytes [dbexpbuf#3 @ 2409] [dbexpbuf2409 ]

B dbexpbuf[EXP_OTR]: semaphore 55 released in db_exp_init

B ***LOG BB0=> buffer CALE started with length 500000 bytes [dbcalbuf#4 @ 2304] [dbcalbuf2304 ]

B dbtran INFO (init_connection '<DEFAULT>' [ORACLE:700.08]):

B max_blocking_factor = 5, max_in_blocking_factor = 5,

B min_blocking_factor = 5, min_in_blocking_factor = 5,

B prefer_union_all = 0, prefer_join = 0,

B prefer_fix_blocking = 0, prefer_in_itab_opt = 1,

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

B escape_as_literal = 1, opt GE LE to BETWEEN = 0,

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

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

B rule_fae->0, concat_fae->0, concat_fae_or->0

M

M Sun Oct 30 20:38:56 2011

M SecAudit(RsauInit): Start init of Security Audit Log for first wp.

M SecAudit(RsauInit): Shared memory for Security Audit Log already exists.

M SecAudit(RsauInit): Re-initialize with new profile parameters

M SecAudit(RsauShmInit): SCSA size................ = 4096

M SecAudit(RsauShmInit): addr of SCSA............. = 0x2b42e196c000

M SecAudit(RsauShmInit): addr of RSAUSHM.......... = 0x2b42e196c768

M SecAudit(RsauShmInit): addr of RSAUSLOTINFO..... = 0x2b42e196c7a0

M SecAudit(RsauShmInit): addr of RSAUSLOTS........ = 0x2b42e196c7ac

M SecAudit(RsauShmInit): SHM version.............. = 5

M SecAudit(RsauShmInit): SHM Slot version......... = 2

M SecAudit(RsauShmInit): RSAU active.............. = 0

M SecAudit(RsauShmInit): number of slots possible. = 10

M SecAudit(RsauShmInit): number of slots requested = 2

M SecAudit(RsauShmInit): number of slots used..... = 2

M SecAudit(RsauShmInit): user selection........... = 0

M SecAudit(RsauShmInit): max size of one file..... = 0 KB

M SecAudit(RsauShmInit): max size of all files.... = 102400 KB

M SecAudit(RsauGetCurrentProfile): Init of shared memory completed

M SecAudit(RsauGetCurrentProfile): Security Audit Log not active

M SsfSapSecin: automatic application server initialization for SAPSECULIB

N SsfSapSecin: Looking for PSE in database

N SsfPseLoad: started...(path=/usr/sap/SOL/DVEBMGS00/sec, AS=sapsol, instanceid=00)

N

N Sun Oct 30 20:38:57 2011

N SsfPseLoad: ended (0 of 0 sucessfully loaded, 0 checked...

N

N Sun Oct 30 20:38:59 2011

N MskiCreateLogonTicketCache: Logon Ticket cache created in shared memory.

N MskiCreateLogonTicketCache: Logon Ticket cache pointer registered in shared memory.

M CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

M CCMS: start to initalize 3.X shared alert area (first segment).

S

S Sun Oct 30 20:39:03 2011

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 0x1526da90

S

S Sun Oct 30 20:39:06 2011

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 2898 KB estimated 1632 KB real ( -1266 KB -44 %)

S found processing queue enabled

S

S Sun Oct 30 20:39:07 2011

S found spool memory service RSPO-RCLOCKS at 0x2b44292bf070

S doing lock recovery

S setting server cache root

S found spool memory service RSPO-SERVERCACHE at 0x2b44292bf6a0

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

A

A Sun Oct 30 20:39:08 2011

A **GENER Trace switched on ***

A

A ~~-PXA~~--

A PXA INITIALIZATION

A PXA: Locked PXA-Semaphore.

A PXA: max_map_count = 1000000: ok

A System page size: 4kb, total admin_size: 20884kb, dir_size: 20764kb.

A Attached to PXA (address 0x2b442fd18000, size 300000K)

A abap/pxa = shared protect gen_remote

A PXA INITIALIZATION FINISHED

A ~~-PXA~~--

A

M -

C-STACK -

(CTrcStack2+0x82)[0x6cdb92]

(SigIGenAction+0x2ad)[0x1a08edd]

/lib64/libpthread.so.0[0x34f7e0e4c0]

/lib64/libc.so.6(gsignal+0x35)[0x34f7230215]

/lib64/libc.so.6(abort+0x110)[0x34f7231cc0]

(ab_init+0x6c8)[0xb8eed8]

(ThrInitRunTime+0x8d)[0x6bbf8d]

(ThStartUp2+0x150)[0x6682f0]

(ThInit+0x1129)[0x55b8b9]

(ThStart+0x11b)[0x55d6bb]

(DpMain+0x228)[0x4bfc08]

/lib64/libc.so.6(__libc_start_main+0xf4)[0x34f721d974]

M PfStatDisconnect: disconnect statistics

M Entering TH_CALLHOOKS

M ThCallHooks: call hook >SAP-Trace buffer write< for event BEFORE_DUMP

M TrThHookFunc: called for WP dump

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 262]

M ThCallHooks: call hook >BtcCallLgCl< for event BEFORE_DUMP

M Entering ThSetStatError

M ThIErrHandle: do not call ThrCoreInfo (no_core_info=0, in_dynp_env=0)

M ThErrPrivHandle: set th_errno (11)

M Entering ThReadDetachMode

M *** ERROR => ThIErrHandle: bad value for th_act_em_hdl (15), detach T0/M0 [thxxhead.c 11202]

M

Former Member · ‎11-05-2011

Thanks deepak for all the help

Did kernel upgrade and it worked fine the installation completed without any problems.

Former Member · ‎11-04-2011

Thanks for the reply Deepak

Will try kernel upgrade to the latest patch level and are there any env variables that needs to be set after kernel upgrade.

Please let me know if there are any variables that needs to be set.

Former Member · ‎11-03-2011

protect_shm = 0

B ***LOG BB0=> buffer OTR started with length 4096k bytes [dbexpbuf#3 @ 2409] [dbexpbuf2409 ]

B dbexpbuf[EXP_OTR]: semaphore 55 released in db_exp_init

B ***LOG BB0=> buffer CALE started with length 500000 bytes [dbcalbuf#4 @ 2304] [dbcalbuf2304 ]

B dbtran INFO (init_connection '<DEFAULT>' [ORACLE:700.08]):

B max_blocking_factor = 5, max_in_blocking_factor = 5,

B min_blocking_factor = 5, min_in_blocking_factor = 5,

B prefer_union_all = 0, prefer_join = 0,

B prefer_fix_blocking = 0, prefer_in_itab_opt = 1,

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

B escape_as_literal = 1, opt GE LE to BETWEEN = 0,

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

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

B rule_fae->0, concat_fae->0, concat_fae_or->0

M

M Wed Nov 2 21:57:25 2011

M SecAudit(RsauInit): Start init of Security Audit Log for first wp.

M SecAudit(RsauInit): Shared memory for Security Audit Log already exists.

M SecAudit(RsauInit): Re-initialize with new profile parameters

M SecAudit(RsauShmInit): SCSA size................ = 4096

M SecAudit(RsauShmInit): addr of SCSA............. = 0x2b4452a0f000

M SecAudit(RsauShmInit): addr of RSAUSHM.......... = 0x2b4452a0f768

M SecAudit(RsauShmInit): addr of RSAUSLOTINFO..... = 0x2b4452a0f7a0

M SecAudit(RsauShmInit): addr of RSAUSLOTS........ = 0x2b4452a0f7ac

M SecAudit(RsauShmInit): SHM version.............. = 5

M SecAudit(RsauShmInit): SHM Slot version......... = 2

M SecAudit(RsauShmInit): RSAU active.............. = 0

M SecAudit(RsauShmInit): number of slots possible. = 10

M SecAudit(RsauShmInit): number of slots requested = 2

M SecAudit(RsauShmInit): number of slots used..... = 2

M SecAudit(RsauShmInit): user selection........... = 0

M SecAudit(RsauShmInit): max size of one file..... = 0 KB

M SecAudit(RsauShmInit): max size of all files.... = 102400 KB

M SecAudit(RsauGetCurrentProfile): Init of shared memory completed

M SecAudit(RsauGetCurrentProfile): Security Audit Log not active

M SsfSapSecin: automatic application server initialization for SAPSECULIB

N SsfSapSecin: Looking for PSE in database

N SsfPseLoad: started...(path=/usr/sap/SOL/DVEBMGS00/sec, AS=sapsol, instanceid=00)

N SsfPseLoad: ended (0 of 0 sucessfully loaded, 0 checked...

N

N Wed Nov 2 21:57:26 2011

N MskiCreateLogonTicketCache: Logon Ticket cache created in shared memory.

N MskiCreateLogonTicketCache: Logon Ticket cache pointer registered in shared memory.

M CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

M CCMS: AlMsUpload called by wp 0.

M CCMS: AlMsUpload successful for /usr/sap/SOL/DVEBMGS00/log/ALMTTREE (249 MTEs).

S

S Wed Nov 2 21:57:27 2011

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 0x14a4ea90

S

S Wed Nov 2 21:57:28 2011

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 2898 KB estimated 1632 KB real ( -1266 KB -44 %)

S found processing queue enabled

S found spool memory service RSPO-RCLOCKS at 0x2b459a362070

S doing lock recovery

S setting server cache root

S found spool memory service RSPO-SERVERCACHE at 0x2b459a3626a0

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

A

A Wed Nov 2 21:57:29 2011

A **GENER Trace switched on ***

A

A ~~-PXA~~--

A PXA INITIALIZATION

A PXA: Locked PXA-Semaphore.

A PXA: max_map_count = 1000000: ok

A System page size: 4kb, total admin_size: 20884kb, dir_size: 20764kb.

A Attached to PXA (address 0x2b45a31f9000, size 300000K)

A abap/pxa = shared protect gen_remote

A PXA INITIALIZATION FINISHED

A ~~-PXA~~--

A

M -

C-STACK -

(CTrcStack2+0x82)[0x6cdb92]

(SigIGenAction+0x2ad)[0x1a08edd]

/lib64/libpthread.so.0[0x34f7e0e4c0]

/lib64/libc.so.6(gsignal+0x35)[0x34f7230215]

/lib64/libc.so.6(abort+0x110)[0x34f7231cc0]

(ab_init+0x6c8)[0xb8eed8]

(ThrInitRunTime+0x8d)[0x6bbf8d]

(ThStartUp2+0x150)[0x6682f0]

(ThInit+0x1129)[0x55b8b9]

(ThStart+0x11b)[0x55d6bb]

(DpMain+0x228)[0x4bfc08]

/lib64/libc.so.6(__libc_start_main+0xf4)[0x34f721d974]

M -

M

M Wed Nov 2 21:57:40 2011

M ***LOG Q0E=> SigIGenAction, signal ( 6) [sigux.c 1067]

M

M Wed Nov 2 21:57:41 2011

M in_ThErrHandle: 1

M ThSigHandler: signal (step 1, th_errno 11, action 4)

M

M Info for wp 0

M

M pid = 6502

M severity = 0

M status = 0

M stat = WP_RUN

M waiting_for = NO_WAITING

M reqtype = DP_RQ_DIAWP

M act_reqtype = NO_REQTYPE

M rq_info = 0

M tid = 0

M mode = 0

M len = -1

M rq_id = 65535

M rq_source =

M last_tid = 0

M last_mode = 0

M semaphore = 0

M act_cs_count = 0

M csTrack = 0

M csTrackRwExcl = 0

M csTrackRwShrd = 0

M mode_cleaned_counter = 0

M control_flag = 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 attachedVm = no VM

M

M Modeinfo for User T0/M0

M

M tm state = 2

M uid = 1

M term type = 0x2

M display = 0x0

M cpic_no = 0

M cpic_idx = -1

M usr = >SAPSYS <

M terminal = > <

M client = > <

M conversation_ID = > <

M appc_tm_conv_idx = -1

M its_plugin = NO

M allowCreateMode = YES

M wp_ca block = 30

M appc_ca block = 10

M blockSoftCanel = NO

M session_id = > <

M ext_session_id = > <

M imode = 0

M mode state = 0x41

M mode clean_state = 1

M task_type = 0

M lastThFc = THFCINVA

M lastAction = TH_IACT_NO_ACTION

M th_errno = 11

M rollout_reason = 0

M last_rollout_level = 0

M async_receives = 0

M cpic_receive = 0

M em handle = 3

M roll state = 1

M abap state = 1

M em state = 3

M eg state = 1

M spa state = 1

M enq state = 0

M softcancel = 0

M cancelInitiator = ?

M clean_state = DP_DEFAULT_CLEANING

M next hook = T-1/U-1/M255

M master hook = T-1/U-1/M255

M slave hook = T-1/U-1/M255

M debug_tid = -1

M debug_mode = 255

M mode type = 0x1

M debug = 0

M msg_count = 0

M tcode = > <

M last_wp = -1

M client conversation_ID = > <

M server conversation_ID = > <

M lock = 0

M max enq infos = 0

M act enq infos = 0

M em_hyper_hdl = 0x2b45521844b0

M plugin_info = NULL

M act_plugin_hdl = -1

M act_plugin_no = 0

M max_plugin_no = 0

M

M PfStatDisconnect: disconnect statistics

M Entering TH_CALLHOOKS

M ThCallHooks: call hook >SAP-Trace buffer write< for event BEFORE_DUMP

M TrThHookFunc: called for WP dump

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 262]

M ThCallHooks: call hook >BtcCallLgCl< for event BEFORE_DUMP

M Entering ThSetStatError

M ThIErrHandle: do not call ThrCoreInfo (no_core_info=0, in_dynp_env=0)

M ThErrPrivHandle: set th_errno (11)

M Entering ThReadDetachMode

M *** ERROR => ThIErrHandle: bad value for th_act_em_hdl (3), detach T0/M0 [thxxhead.c 11202]

M

M Modeinfo for User T0/M0

M

M tm state = 2

M uid = 1

M term type = 0x2

M display = 0x0

M cpic_no = 0

M cpic_idx = -1

M usr = >SAPSYS <

M terminal = > <

M client = > <

M conversation_ID = > <

M appc_tm_conv_idx = -1

M its_plugin = NO

M allowCreateMode = YES

M wp_ca block = 30

M appc_ca block = 10

M blockSoftCanel = NO

M session_id = > <

M ext_session_id = > <

M imode = 0

M mode state = 0x41

M mode clean_state = 1

M task_type = 0

M lastThFc = THFCINVA

M lastAction = TH_IACT_NO_ACTION

M th_errno = 11

M rollout_reason = 0

M last_rollout_level = 0

M async_receives = 0

M cpic_receive = 0

M em handle = 3

M roll state = 1

M abap state = 1

M em state = 2

M eg state = 1

M spa state = 1

M enq state = 0

M softcancel = 0

M cancelInitiator = ?

M clean_state = DP_DEFAULT_CLEANING

M next hook = T-1/U-1/M255

M master hook = T-1/U-1/M255

M slave hook = T-1/U-1/M255

M debug_tid = -1

M debug_mode = 255

M mode type = 0x1

M debug = 0

M msg_count = 0

M tcode = > <

M last_wp = -1

M client conversation_ID = > <

M server conversation_ID = > <

M lock = 0

M max enq infos = 0

M act enq infos = 0

M em_hyper_hdl = 0x2b45521844b0

M plugin_info = NULL

M act_plugin_hdl = -1

M act_plugin_no = 0

M max_plugin_no = 0

M

M ThIErrHandle: Detach all shared memory segments in EM

M ***LOG Q02=> wp_halt, WPStop (Workproc 0 6502) [dpuxtool.c 269]

Former Member · ‎11-03-2011

Thanks deepak for the quick reply,This is the log that u asked for

-

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

-

*

ACTIVE TRACE LEVEL 1
ACTIVE TRACE COMPONENTS all, MJ

*

M sysno 00

M sid SOL

M systemid 390 (AMD/Intel x86_64 with Linux)

M relno 7010

M patchlevel 0

M patchno 137

M intno 20020600

M make: single threaded, Unicode, 64 bit, optimized

M pid 6502

M

M Wed Nov 2 21:57:20 2011

M kernel runs with dp version 245000(ext=110000) (@(#) DPLIB-INT-VERSION-245000-UC)

M length of sys_adm_ext is 576 bytes

M ThInit: rdisp/cleanup_after_crash = 1

M ***LOG Q01=> ThInit, WPStart (Workproc 0 1 6502) [thxxhead.c 1346]

M

M Wed Nov 2 21:57:21 2011

M ThInit: running on host sapsol

M calling db_connect ...

B Loading DB library '/usr/sap/SOL/DVEBMGS00/exe/dboraslib.so' ...

B Library '/usr/sap/SOL/DVEBMGS00/exe/dboraslib.so' loaded

B Version of '/usr/sap/SOL/DVEBMGS00/exe/dboraslib.so' is "700.08", patchlevel (0.134)

C Oracle Client Version: '10.2.0.2.0', (dbsl 701 090311, UNICODE[2])

C Application info callback registered.

C Client NLS setting (by OCINlsGetInfo): con=0, 'AMERICAN_AMERICA.UTF16'

C Logon as OPS$-user to get SAPSR3's password

C Connecting as /@SOL on connection 0 (nls 0) ... (dbsl 701 090311, UNICODE[2])

C Attaching to DB Server SOL (con_hdl=0,svchp=0x148cf598,srvhp=0x148d2028)

C Starting user session: OCISessionBegin(con_hdl=0, usr='/',svchp=0x148cf598, srvhp=0x148d2028, usrhp=0x148bf728)

C CbApplInfoGet() failed (ignored 1).

C Now '/@SOL' is connected: con_hdl=0, nls_hdl=0, session_id=139.

C Got SAPSR3's password from OPS$-user

C Disconnecting from connection 0 ...

C Closing user session (con_hdl=0,svchp=0x148cf598,usrhp=0x148bf728)

C Disconnected (con=0) from ORACLE.

C Connecting as SAPSR3/<pwd>@SOL on connection 0 (nls 0) ... (dbsl 701 090311, UNICODE[2])

C Starting user session: OCISessionBegin(con_hdl=0, usr=SAPSR3/<pwd>, svchp=0x148cf598, srvhp=0x148d2028, usrhp=0x148bf728)

C Now 'SAPSR3/<pwd>@SOL' is connected: con_hdl=0, nls_hdl=0, session_id=139.

C con=0, V$NLS_PARAMETERS: NLS_LANG=AMERICAN_AMERICA.UTF8, NLS_NCHAR=UTF8, UNI_NON=FALSE

C Nls CharacterSet NationalCharSet EnvHp ErrHp ErrBt

C 0 UTF16 AL16UTF16 0x148b7460 0x148bef10 0x148cf668

C DB instance SOL is running on sapsol with ORACLE version 10.2.0.4.0 since NOV 02, 2011, 21:56:49

B Connection 0 opened (DBSL handle 0)

M db_connect o.k.

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

I MtxInit: 0 0 0

M SHM_PRES_BUF (addr: 0x2b444c138000, size: 4400000)

M SHM_ROLL_AREA (addr: 0x2b4562b22000, size: 606208000)

M SHM_PAGING_AREA (addr: 0x2b4586d42000, size: 268435456)

M SHM_ROLL_ADM (addr: 0x2b444c56c000, size: 11116480)

M SHM_PAGING_ADM (addr: 0x2b4596d42000, size: 3277856)

M ThCreateNoBuffer allocated 560152 bytes for 1000 entries at 0x2b4597065000

M ThCreateNoBuffer index size: 3000 elems

M ThCreateVBAdm allocated 12176 bytes (50 server) at 0x2b459ff71000

X EmInit: MmSetImplementation( 2 ).

X MM global diagnostic options set: 0

X <ES> client 0 initializing ....

X Using implementation map

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 maximum dump mem to 96 MB

M Deactivate statistics hyper index locking

I *** INFO Shm 45 in Pool 40 4771 KB estimated 3625 KB real ( -1146 KB -25 %)

B dbntab: NTAB buffers created

B dbntab: Buffer FTAB(hash header) (addr: 0x2b45970ef088, size: 392)

B dbntab: Buffer FTAB(anchor array) (addr: 0x2b45970ef210, size: 320072)

B dbntab: Buffer FTAB(item array) (addr: 0x2b459713d458, size: 1280000)

B dbntab: Buffer FTAB(data area) (addr: 0x2b4597275c58, size: 30720000)

B dbntab: Buffer IREC(hash header) (addr: 0x2b4598fc3088, size: 392)

B dbntab: Buffer IREC(anchor array) (addr: 0x2b4598fc3210, size: 320072)

B dbntab: Buffer IREC(item array) (addr: 0x2b4599011458, size: 320000)

B dbntab: Buffer IREC(data area) (addr: 0x2b459905f658, size: 6144000)

B dbntab: Buffer STAB(hash header) (addr: 0x2b459963d088, size: 392)

B dbntab: Buffer STAB(anchor array) (addr: 0x2b459963d210, size: 320072)

B dbntab: Buffer STAB(item array) (addr: 0x2b459968b458, size: 320000)

B dbntab: Buffer STAB(data area) (addr: 0x2b45996d9658, size: 3072000)

B dbntab: Buffer TTAB(hash header) (addr: 0x2b45999c9088, size: 2160)

B dbntab: Buffer TTAB(anchor array) (addr: 0x2b45999c98f8, size: 320072)

B dbntab: Buffer TTAB(item array) (addr: 0x2b4599a17b40, size: 800000)

B dbntab: Buffer TTAB(data area) (addr: 0x2b4599adb040, size: 5840000)

B

B Wed Nov 2 21:57:23 2011

B dbstat: table statistics switched on for 35493 tables

B dbstat: TABSTAT buffer created (addr: 0x2b4457f64000, size: 17380280)

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

B dbtbxbuf: Buffer TABL (addr: 0x2b459ff74100, size: 30000000, end: 0x2b45a1c10480)

B dbtbxbuf: Profile: max_objects = 5000, displace = 1, reorg = 1

B dbtbxbuf: request_unit = 2000, sync_reload = 5, inval_reload = 5

B dbtbxbuf: protect_shm = 0, force_checks = 0

B dbtbxbuf: tsize_retry = 14203392

B ***LOG BB0=> buffer TABL started with length 30000000 bytes [dbtbxbuf#6 @ 16383] [dbtbxbuf1638 3]

B dbtbxbuf: Buffer TABLP (addr: 0x2b45a1c11100, size: 10240000, end: 0x2b45a25d5100)

B dbtbxbuf: Profile: max_objects = 500, displace = 1, reorg = 1

B dbtbxbuf: request_unit = 2000, sync_reload = 5, inval_reload = 5

B dbtbxbuf: protect_shm = 0, force_checks = 0

B dbtbxbuf: tsize_retry = 5030144

B ***LOG BB0=> buffer TABLP started with length 10240000 bytes [dbtbxbuf#6 @ 16383] [dbtbxbuf1638 3]

B dbtbxbuf: Reading TBX statistics:

B dbtbxbuf: Opening /usr/sap/SOL/DVEBMGS00/data/tbxstat failed (No such file or directory).

B dbtbxbuf: Opening /usr/sap/SOL/DVEBMGS00/data/tbxnew failed (No such file or directory).

B dbtbxbuf: Renaming /usr/sap/SOL/DVEBMGS00/data/tbxnew to /usr/sap/SOL/DVEBMGS00/data/tbxstat failed (No such file or directory).

B dbtbxbuf: 0 object entries precreated

B dbexpbuf[EXP_SHB]: Aquired semaphore 35 in db_exp_init(EXP_INIT)

B dbexpbuf[EXP_SHB]: shared memory layout of buffer EIBUF:

B EYE-CATCHER : 1 * 4 = 4

B ADMIN : 1 * 472 = 472

B RECOVERY : 17 * 40 = 680

B MUTEX VECTOR : 4001 * 64 = 256064

B OBJECT DIRECTORY : 2000 * 256 = 512000

B HASH VECTOR : 4001 * 8 = 32008

B STATISTICS : 1 * 200 = 200

B FREELIST : 65 * 8 = 520

B DATA SPACE : 13251 * 256 = 3392256

B dbexpbuf[EXP_SHB]: buffer EIBUF installed with

B semkey = 35

B shmkey = 54

B wp_n = 17

B sclass = 0

B block_length = 256

B max_objects = 2000

B max_obj_size = 847904

B pref_obj_size = 0

B est_large_obj_size = 16384

B free_vec_lg = 65

B hash_vec_size = 4001

B buffer_l = 4194304

B max_blocks = 13251

B free_blocks = 13251

B mutex_n = 4001

B recovery_delay = 500000

B tracing = 0

B force_checks = 0

B protect_shm = 0

B ***LOG BB0=> buffer EIBUF started with length 4096k bytes [dbexpbuf#3 @ 2409] [dbexpbuf2409 ]

B dbexpbuf[EXP_SHB]: semaphore 35 released in db_exp_init

B dbexpbuf[EXP_SHM]: Aquired semaphore 56 in db_exp_init(EXP_INIT)

B dbexpbuf[EXP_SHM]: shared memory layout of buffer ESM :

B EYE-CATCHER : 1 * 4 = 4

B ADMIN : 1 * 472 = 472

B RECOVERY : 17 * 40 = 680

B MUTEX VECTOR : 4001 * 64 = 256064

B OBJECT DIRECTORY : 2000 * 256 = 512000

B HASH VECTOR : 4001 * 8 = 32008

B STATISTICS : 1 * 200 = 200

B FREELIST : 65 * 8 = 520

B DATA SPACE : 13251 * 256 = 3392256

B dbexpbuf[EXP_SHM]: buffer ESM installed with

B semkey = 56

B shmkey = 65

B wp_n = 17

B sclass = 0

B block_length = 256

B max_objects = 2000

B max_obj_size = 847904

B pref_obj_size = 0

B est_large_obj_size = 16384

B free_vec_lg = 65

B hash_vec_size = 4001

B buffer_l = 4194304

B max_blocks = 13251

B free_blocks = 13251

B mutex_n = 4001

B recovery_delay = 500000

B tracing = 0

B force_checks = 0

continued in next post.........................thanks in advance

Former Member · ‎11-03-2011

This is the new sap inst log after correcting the shared memory error

INFO 2011-10-30 20:33:12.786

Creating file /tmp/dev_sap_kernel_test_30_Oct_2011_20_33_12.

INFO 2011-10-30 20:33:12.786

Removed file /tmp/dev_sap_kernel_test_30_Oct_2011_20_33_12.

INFO 2011-10-30 20:33:12.788

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:33:12.791

Working directory changed to /sapdump/51040437/IM_LINUX_X86_64.

INFO 2011-10-30 20:33:12.895

Creating file /tmp/dev_sap_kernel_test_30_Oct_2011_20_33_12.

INFO 2011-10-30 20:33:12.895

Removed file /tmp/dev_sap_kernel_test_30_Oct_2011_20_33_12.

INFO 2011-10-30 20:33:12.898

Real group ID set to 501.

INFO 2011-10-30 20:33:14.172

Creating file /tmp/sapinst_instdir/x.

INFO 2011-10-30 20:33:14.172

Removed file /tmp/sapinst_instdir/x.

INFO 2011-10-30 20:33:14.179

Authorizations set for /tmp/sapinst_instdir.

INFO 2011-10-30 20:33:14.180

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:33:14.209

Working directory changed to /sapdump/51040437/IM_LINUX_X86_64.

INFO 2011-10-30 20:33:32.377

Creating file /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/x.

INFO 2011-10-30 20:33:32.378

Removed file /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/x.

INFO 2011-10-30 20:33:36.107

Creating file /tmp/sapinst_instdir/x.

INFO 2011-10-30 20:33:36.108

Removed file /tmp/sapinst_instdir/x.

INFO 2011-10-30 20:33:36.116

Authorizations set for /tmp/sapinst_instdir.

INFO 2011-10-30 20:33:36.123

Removed file /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/dev_sap_kernel.

INFO 2011-10-30 20:33:36.126

Removed file /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/dev_sap_kernel.

INFO 2011-10-30 20:33:36.128

Copied file '/sapdump/51040437/IM_LINUX_X86_64/dev_sap_kernel' to '/tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/dev_sap_kernel'.

INFO 2011-10-30 20:33:36.130

Authorizations set for /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/dev_sap_kernel.

INFO 2011-10-30 20:33:36.130

Removed file /sapdump/51040437/IM_LINUX_X86_64/dev_sap_kernel.

INFO 2011-10-30 20:33:38.419

Copied file '/tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/keydb.xml' to '/tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/keydb.2.xml'.

INFO 2011-10-30 20:33:40.980

Copied file '/tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/statistic.xml' to '/tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/statistic.1.xml'.

INFO 2011-10-30 20:37:07.197

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:37:07.200

Working directory changed to /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS.

INFO 2011-10-30 20:37:07.203

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:37:07.206

Working directory changed to /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS.

INFO 2011-10-30 20:37:15.690

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:37:15.692

Working directory changed to /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS.

INFO 2011-10-30 20:37:21.765

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:37:21.768

Working directory changed to /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS.

WARNING[E] 2011-10-30 20:37:22.804

FSL-02053 Read error in /proc/sys/net/ipv6/route/flush.

WARNING[E] 2011-10-30 20:37:23.393

FSL-02053 Read error in /proc/sys/net/ipv4/route/flush.

WARNING[E] 2011-10-30 20:37:23.836

FSL-02053 Read error in /proc/sys/fs/binfmt_misc/register.

INFO 2011-10-30 20:37:24.347

Copied file '/tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/inifile.xml' to '/tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/inifile.1.xml'.

INFO 2011-10-30 20:37:36.664

Execute step start of component |NW_Onehost|ind|ind|ind|ind|0|0|NW_Onehost_System|ind|ind|ind|ind|onehost|0|NW_CI_Instance|ind|ind|ind|ind|ci|0|NW_CI_Instance_Start|ind|ind|ind|ind|2|0

INFO 2011-10-30 20:38:04.471

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:38:04.473

Working directory changed to /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS.

INFO 2011-10-30 20:38:04.485

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:38:04.490

Working directory changed to /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS.

INFO 2011-10-30 20:38:05.18

Working directory changed to /tmp/sapinst_exe.6611.1319986088.

INFO 2011-10-30 20:38:05.706

Working directory changed to /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS.

INFO 2011-10-30 20:38:05.939

Starting instance SOL/DVEBMGS00...

WARNING 2011-10-30 20:38:06.024

Connect to message server (sapsol/3900) failed: MSENILAYER.

INFO 2011-10-30 20:38:08.24

Creating file /tmp/sapinst_instdir/SOLMAN/SYSTEM/ORA/CENTRAL/AS/start_SOL_DVEBMGS00.log.

INFO 2011-10-30 20:38:08.216

Output of /usr/sap/SOL/SYS/exe/run/startsap all DVEBMGS00 sapsol is written to the logfile start_SOL_DVEBMGS00.log.

INFO 2011-10-30 20:39:10.649

Execution of the command "/usr/sap/SOL/SYS/exe/run/startsap all DVEBMGS00 sapsol" finished with return code 0. Output:

Checking SOL Database

-

Database is not available via R3trans

Running /usr/sap/SOL/SYS/exe/run/startdb

Trying to start SOL database ...

Log file: /home/soladm/startdb.log

SOL database started

/usr/sap/SOL/SYS/exe/run/startdb completed successfully

Starting Startup Agent sapstartsrv

-

Instance Service on host sapsol started

Starting SAP Instance DVEBMGS00

-

Startup-Log is written to /home/soladm/startsap_DVEBMGS00.log

Instance on host sapsol started

Starting SAP-Collector Daemon

-

20:39:10 30.10.2011 LOG: Effective User Id is root

***********************************************************************

This is Saposcol Version COLL 20.95 701 - v2.00, AMD/Intel x86_64 with Linux, 2007/02/16
Usage: saposcol -l: Start OS Collector
saposcol -k: Stop OS Collector
saposcol -d: OS Collector Dialog Mode
saposcol -s: OS Collector Status
The OS Collector (PID 5550) is already running .....

************************************************************************

saposcol already running

INFO 2011-10-30 20:39:10.990