WP die but R3trans is ok

[Former Member]

My sap system is 46D-ext running on Win2003/ora10.2

oracle start with no error, but SAP don't.

I get message "db connect failed" and all the wp die.

R3trans -d is ok :

C:\Documents and Settings\bcpadm>R3trans -d

This is R3trans version 6.05 (release 46D - 30.09.03 - 16:30:42).

R3trans finished (0000).

here is a trace file of wp, they all look like the same.

Please, help.

Thank you.

-

---

trc file: "dev_w78", trc level: 1, release: "46D"

-

---

*

• ACTIVE TRACE LEVEL 1

• ACTIVE TRACE COMPONENTS all, M

*

B

B Fri Apr 18 09:01:24 2008

B create_con (con_name=R/3)

B Loading DB library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' ...

B Library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' loaded

B Version of library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' is "46D.00", patchlevel (0.2311)

B New connection 0 created

M relno 4640

M patchlevel 0

M patchno 2356

M intno 0

M pid 7104

M

M ***LOG Q0Q=> tskh_init, WPStart (Workproc78 7104) [dpxxdisp.c 976]

I MtxInit: -2 0 0

X EmInit: MmSetImplementation( 2 ).

X <ES> client 78 initializing ....

X Using implementation std

M <ES> Memory Reset enabled

X ES initialized.

M ThSysUsrCheck: clear T78

M calling db_connect ...

C Got NLS_LANG=AMERICAN_AMERICA.WE8DEC from environment

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

C Connecting as /@BCP on connection 0 ...

C

C Fri Apr 18 09:01:25 2008

C *** ERROR => OCI-call 'olog' failed: rc = 12537

[dboci.c 2460]

C *** ERROR => CONNECT failed with sql error '12537'

[dboci.c 7034]

C Try to connect with default password

C Connecting as SAPR3/<pwd>@BCP on connection 0 ...

C *** ERROR => OCI-call 'olog' failed: rc = 12537

[dboci.c 2460]

C *** ERROR => CONNECT failed with sql error '12537'

[dboci.c 7034]

C Got NLS_LANG=AMERICAN_AMERICA.WE8DEC from environment

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

C Connecting as /@BCP on connection 0 ...

C *** ERROR => OCI-call 'olog' failed: rc = 12537

[dboci.c 2460]

C *** ERROR => CONNECT failed with sql error '12537'

[dboci.c 7034]

C Try to connect with default password

C Connecting as SAPR3/<pwd>@BCP on connection 0 ...

C

C Fri Apr 18 09:01:26 2008

C *** ERROR => OCI-call 'olog' failed: rc = 12537

[dboci.c 2460]

C *** ERROR => CONNECT failed with sql error '12537'

[dboci.c 7034]

B ***LOG BY2=> sql error 12537 performing CON [dbsh 0707 ]

B ***LOG BY0=> [dbsh 0707 ]

B ***LOG BY2=> sql error 12537 performing CON [dblink 0627 ]

B ***LOG BY0=> [dblink 0627 ]

M ***LOG R19=> tskh_init, db_connect ( DB-Connect 000256) [thxxhead.c 1187]

M in_ThErrHandle: 1

M *** ERROR => tskh_init: db_connect (step 1, th_errno 13, action 3) [thxxhead.c 8517]

M

M Info for wp 78

M

M stat = 4

M reqtype = 6

M act_reqtype = -1

M tid = -1

M mode = 255

M len = -1

M rq_id = -1

M rq_source = 255

M rq_info = 0

M last_tid = 0

M last_mode = 0

M int_checked_resource = 0

M ext_checked_resource = 0

M report = > <

M action = 0

M tab_name = > <

M

M *****************************************************************************

M *

M * LOCATION SAP-Server S64LMWBIBV6_BCP_00 on host S64LMWBIBV6 (wp 78)

M * ERROR tskh_init: db_connect

M *

M * TIME Fri Apr 18 09:01:26 2008

M * RELEASE 46D

M * COMPONENT Taskhandler

M * VERSION 1

M * RC 13

M * MODULE thxxhead.c

M * LINE 8628

M * COUNTER 1

M *

M *****************************************************************************

M

M Entering ThSetStatError

M call ThrShutDown (1) ...

M ***LOG Q02=> wp_halt, WPStop (Workproc78 7104) [dpnttool.c 310]

[former_member433984]

what are the Oracle patch(minipatch) and Oracle instant client versions?

[Former Member]

Oracle patch installed: 10.2.0.1.0 / 10.2.0.2.0

SAP R/3 Kernel : 46D_EXT

Database library OCI_920_SHARE_OCI_7_API

Support pack # : 2356

I have reduce the number of WPs : I still get half of them starting.

[former_member433984]

any Oracle minipatches (latest released are 16 and 17)?

[Former Member]

Hello Yaroslav

I have le opatch mentionned above.

I installed a CPU patch as well.

Do you have a command to check the latest "mini patch" ?

Thanks a lot for your help.

[former_member433984]

Run OUI from Programs -> Oracle -> Oracle Installation Products, check "installed products" for your Oracle SID, there should be the list of installed patches and minipatches

[markus_doehr2]

> Do you have a command to check the latest "mini patch" ?

- login as <sid>adm

- open a cmd.exe

- %ORACLE_HOME%\OPatch\opatch lsinventory

This will show you a list of installed patches.

Markus

[Former Member]

opatch lsinventory is the one i did and it gave me the patch mention previously (10.2.0.2)

There is also "Patch 6397028" (CPU patch)

with oracle installer, i don't find other patch installed.

[markus_doehr2]

You should install the latest interim patch:

http://service.sap.com/swcenter-3pmain

--> Oracle

--> Oracle 32bit (or Oracle 64bit)

--> Oracle 10.2.0 (32/64bit)

--> Oracle 10.2.0.2

--> NT_I386 (or Win_X86_64 or Win_IA64)

--> 10202_Patch17_WINNT.zip

Unzip that patch and install it using "opatch apply <directory>".

Markus

[former_member433984]

exactly, and one more question - how many WPs are configured to start and how many CPU does the hardware have?

[Former Member]

CPU : 16 Intel(R) Xeon(TM) CPU at 2992 Mhz

RAM : 8190 Mo

OS : Microsoft(R) Windows(R) Server 2003, Enter En

Version OS : Service Pack 1 Build OS : 5.2.3790

I start with 26 WPs ( i decreased the number of wp to 26 to check if it would be better, but i still have the same problem)

Thank you all !

[Former Member]

I have decreased the WP to 16

SAP start with no problem.

On the other server, it runs with more WPs.

How can i know how many WP can i have ?

It's strange because on my others servers, i have more WPs.

I don't know what is wrong here...

An idea ?

Thanks and Regards,

Djam

[markus_doehr2]

Is this a 32bit Windows?

Markus

[Former Member]

Yes Markus It's a 32bit Windows

[markus_doehr2]

ORA-12537 is "TNS connection closed".

check if you listener is up and running, restart it and try again.

Markus

[Former Member]

Thank you for replying

Listener is up and running.

I stopped/start it but i get the same errors.

I can connect to database with "R3trans -d"

When i start oracle, i get no error.

When I start SAP, i have some WP running and others die with the error i posted.

When I restart a died WP individually, it starts ok.

It is as if all WP can't start together. (i have 30 WP)

This is a new SAP server that has been refresh with another DB (prod).

I have also copied the SAP profil of that prod to to this new server.

[Former Member]

Pls run at OS level :

sappfpar check pf=<instance profile name>

-

---

try reduce the no. of WP.

-

---

Pls past dev_disp and dev_w0

[stefan_koehler]

Hello Djam,

at first i have to say, that we are only guessing, because without seeing this problem live on your system it is really hard to analyze.

But this information is very interesting:

> When I start SAP, i have some WP running and others die with the error i posted.

> When I restart a died WP individually, it starts ok.

I am not very familiar with Oracle on Windows, but i have simulated a similar situation by doing some test on AIX / Linux. Please take a look at this documentation:

http://download.oracle.com/docs/cd/B19306_01/network.102/b14213/listener.htm#sthref783

> QUEUESIZE

> Use the parameter QUEUESIZE to specify the number of concurrent connection requests that the listener can accept on a TCP/IP or IPC listening endpoint (protocol address).

While starting SAP many work processes try to establish a connection at the same time - maybe your listener is not able to handle this many requests under windows (take a look at the default values).

Try to set QUEUESIZE to a higher value (your work processes * 2) and start your sap again.

Sapnote #131561 is confirming my guess ( For some platforms (e.g. NT/Windows 2000), this is set to the small number of 5.)

Regards

Stefan

[Former Member]

I have 32 WP on the central instance.

I have set quesize parameter to 60.

Then i have set SQLNET.INBOUND_CONNECT_TIMEOUT to 120 in listner.ora and sqlnet.ora

Sap still start with half of processes running. (better than before)

when a died process is restarted individually, it runs ok.

I post dev_disp and dev_w0 (this one started ok) as requested.

I also post the dev_w11 that failed in error but started ok when restarted in sm51

-

---

trc file: "dev_disp", trc level: 1, release: "46D"

-

---

Tue Apr 22 09:22:40 2008

relno 4640

patchlevel 0

patchno 2356

intno 0

pid 5756

***LOG Q00=> DpSapEnvInit, DPStart (00 5756) [dpxxdisp.c 921]

Tue Apr 22 09:22:45 2008

• WARNING => DpNetCheck: NiAddrToHost(1.0.0.0) took 4 seconds

***LOG GZZ=> 1 possible network problems detected - check tracefile and adjust your DNS settings [dpxxtool2.c 3171]

MtxInit: -2 0 0

DpIPCInit: start server >S64LMWBIBV6_BCP_00 <

MBUF state OFF

MBUF opmode USE

EmInit: MmSetImplementation( 2 ).

<ES> client 0 initializing ....

<ES> InitFreeList

<ES> block size is 1024 kByte.

Using implementation std

<ES> Memory Reset enabled as NT default

<EsNT> EsIUnamFileMapInit: Initialize the memory 5600 MB

<ES> 5599 blocks reserved for free list.

ES initialized.

Tue Apr 22 09:22:48 2008

***LOG Q0K=> DpMsAttach, mscon ( S64LMWBIBV6) [dpxxdisp.c 8384]

MBUF set hwid_state to HWID_PENDING

DpStartStopMsg: send start message (myname is >S64LMWBIBV6_BCP_00 <)

DpStartStopMsg: start msg sent

CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

CCMS: Initalizing shared memory of size 10000000 for monitoring segment.

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

DpMsgAdmin: Set release to 4640, patchlevel 0

MBUF state PREPARED

MBUF component UP

MBUF set hwid_state to SAP_O_K (V1509971809 )

DpMsgAdmin: Set patchno for this platform to 2356

Release check o.K.

Tue Apr 22 09:22:51 2008

MBUF state ACTIVE

Tue Apr 22 09:23:28 2008

• ERROR => W7 (pid 6540) died [dpxxdisp.c 10701]

my types changed after wp death/restart 0x3f --> 0x3b

• ERROR => W8 (pid 8136) died [dpxxdisp.c 10701]

• ERROR => W10 (pid 6500) died [dpxxdisp.c 10701]

• ERROR => W11 (pid 7556) died [dpxxdisp.c 10701]

• ERROR => W12 (pid 7880) died [dpxxdisp.c 10701]

• ERROR => W13 (pid 6084) died [dpxxdisp.c 10701]

• ERROR => W16 (pid 6444) died [dpxxdisp.c 10701]

• ERROR => W19 (pid 324) died [dpxxdisp.c 10701]

• ERROR => W21 (pid 5952) died [dpxxdisp.c 10701]

• ERROR => W25 (pid 6888) died [dpxxdisp.c 10701]

• ERROR => W26 (pid 6948) died [dpxxdisp.c 10701]

• ERROR => W27 (pid 6608) died [dpxxdisp.c 10701]

• ERROR => W28 (pid 2084) died [dpxxdisp.c 10701]

• ERROR => W30 (pid 800) died [dpxxdisp.c 10701]

• ERROR => W31 (pid 5664) died [dpxxdisp.c 10701]

• ERROR => W32 (pid 584) died [dpxxdisp.c 10701]

my types changed after wp death/restart 0x3b --> 0x1b

-

---

-

---

trc file: "dev_w0", trc level: 1, release: "46D"

-

---

*

• ACTIVE TRACE LEVEL 1

• ACTIVE TRACE COMPONENTS all, M

*

B

B Tue Apr 22 09:22:48 2008

B create_con (con_name=R/3)

B Loading DB library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' ...

B Library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' loaded

B Version of library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' is "46D.00", patchlevel (0.2311)

B New connection 0 created

M relno 4640

M patchlevel 0

M patchno 2356

M intno 0

M pid 6188

M

M ***LOG Q0Q=> tskh_init, WPStart (Workproc 0 6188) [dpxxdisp.c 976]

I MtxInit: -2 0 0

X EmInit: MmSetImplementation( 2 ).

X <ES> client 0 initializing ....

X Using implementation std

M <ES> Memory Reset enabled

X ES initialized.

M ThSysUsrCheck: clear T0

M calling db_connect ...

C Got NLS_LANG=AMERICAN_AMERICA.WE8DEC from environment

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

C Connecting as /@BCP on connection 0 ...

C Now I'm connected to ORACLE using OCI_7 API

C Got SAPR3's password from table SAPUSER

C Disconnecting from connection 0 ...

C Now I'm disconnected from ORACLE

C Try to connect with the password I got from OPS$-user

C Connecting as SAPR3/<pwd>@BCP on connection 0 ...

C

C Tue Apr 22 09:22:49 2008

C Now I'm connected to ORACLE using OCI_7 API

C Database instance bcp is running on S64LMWBIBV6 with ORACLE version 10.2.0.2.0 since 20080422

B Connection 0 opened

B Wp Hdl ConName ConCnt ConState TX PRM RCT Date Time

B 000 000 R/3 000000 ACTIVE NO YES NO 20080422 092248

M db_connect o.k.

I MtxInit: 0 0 0

X EmInit: MmSetImplementation( 2 ).

X <ES> client 0 initializing ....

X Using implementation std

X ES initialized.

B db_con_shm_ini: WP_ID = 0, WP_CNT = 33

B dbtbxbuf: Buffer TABL (addr: 2B4700C8, size: 60000256, end: 2EDA88C8)

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

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

B dbtbxbuf: protect_shm = 0

B ***LOG BB0=> buffer TABL started with length 60000256 bytes [dbtbxbuf1695 7]

B ***LOG BB0=> buffer TABLP started with length 030720000 bytes [dbbfe 0973 ]

B Layout of EIBUF buffer shared memory:

B 0: 1 * 4 = 4

B 1: 1 * 268 = 268

B 2: 33 * 20 = 660

B 3: 40009 * 40 = 1600360

B 4: 20000 * 128 = 2560000

B 5: 40009 * 4 = 160036

B 6: 1 * 100 = 100

B 7: 65 * 4 = 260

B 8: 222236 * 128 = 28446208

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

B dbexpbuf: Buffer EIBUF (addr: 30B000D0, size: 32768000, end: 32A400D0)

B ***LOG BB0=> buffer EIBUF started with length 32000k bytes [dbexpbuf2050 ]

B Layout of CUA buffer shared memory:

B 0: 1 * 4 = 4

B 1: 1 * 268 = 268

B 2: 33 * 20 = 660

B 3: 6521 * 40 = 260840

B 4: 3250 * 128 = 416000

B 5: 6521 * 4 = 26084

B 6: 1 * 100 = 100

B 7: 193 * 4 = 772

B 8: 23247 * 256 = 5951232

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

B dbexpbuf: Buffer CUA (addr: 0F6200D0, size: 6656000, end: 0FC790D0)

B ***LOG BB0=> buffer CUA started with length 6500k bytes [dbexpbuf2050 ]

B ***LOG BB0=> buffer CALE started with length 500000 bytes [dbcalbuf2166 ]

B dbtran INFO (init_connection '<DEFAULT>' [ORACLE:46D.00]):

B max_blocking_factor = 5, max_in_blocking_factor =1000,

B min_blocking_factor = 5, min_in_blocking_factor = 10,

B prefer_union_all = 0, prefer_union_for_select_all = 0,

B prefer_fix_blocking = 0, prefer_in_itab_opt = 0,

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

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

B select * = 0x3,

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

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

M

M Tue Apr 22 09:22:50 2008

M PfReadDBVersion: use compatibility mode for stat-files

M PfInitShm: use compatibility mode for stat-files

M SecAudit(rsaucscsa_create): SCSA memory usage: SecAudit = 48, total = 1320

M *** ERROR => SecAudit(rsaucscsa_create): parameter too small rsau/max_diskspace/local; replaced [rsauwr1.c 506]

M SecAudit(init_sel_info): SCSA memory usage: SecAudit = 200, total = 1520

M SecAudit(rsaucscsa_create): addr of RSAUSHM........ = 036A0538

M SecAudit(rsaucscsa_create): addr of RSAUSLOTINFO... = 036A0568

M SecAudit(rsaucscsa_create): addr of RSAUSLOTS...... = 036A0574

M SecAudit(rsaucscsa_create): SHM version............ = 4

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

M SecAudit(rsaucscsa_create): max size of all files.. = 1024 KB

M SecAudit(rsaucscsa_create): number of slots........ = 2

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

M SecAudit(RsauGetCurrentProfile): active audit profile PROFIL01

M SecAudit(check_daily_file): audit file opened C:\usr\sap\BCP\DVEBMGS00\log\20080422.AUD

M SsfSapSecin: automatic application server initialization for SAPSECULIB

M SsfSapSecin: Looking for PSE in database

M SsfPseLoad: started...(path=C:\usr\sap\BCP\DVEBMGS00\sec, AS=S64LMWBIBV6, instanceid=00)

M SsfPseLoad: Downloading file C:\usr\sap\BCP\DVEBMGS00\sec\SAPSYS.pse (client: , key: SYSPSE, len: 1579)

M SsfPseLoad: ended (1 of 1 sucessfully loaded, 1 checked...

M rdisp/reinitialize_code_page -> 0

M rdisp/hold_keeps_time_slice -> 0

M rdisp/no_hooks_for_sqlbreak -> 0

M CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

M CCMS: AlMsUpload called by wp 0.

I *** ERROR => New Process with the same PID

[semnt.c 1513]

I *** ERROR => New Process with the same PID

[semnt.c 1513]

I *** ERROR => New Process with the same PID

[semnt.c 1513]

I *** ERROR => New Process with the same PID

[semnt.c 1513]

M CCMS: AlMsUpload successful for C:\usr\sap\BCP\DVEBMGS00\log\ALMTTREE.DAT.

I *** ERROR => New Process with the same PID

[semnt.c 1513]

I *** ERROR => New Process with the same PID

[semnt.c 1513]

I

I Tue Apr 22 09:22:51 2008

I *** ERROR => New Process with the same PID

[semnt.c 1513]

I *** ERROR => New Process with the same PID

[semnt.c 1513]

I *** ERROR => New Process with the same PID

[semnt.c 1513]

S *** init spool environment

S initialize debug system

T Stack direction is downwards.

T debug control: prepare exclude for printer trace

T new memory block 06E163C0

S spool kernel/ddic check: Ok

S using table TSP02FX for frontend printing

S 3 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

S found processing queue enabled

S found spool memory service RSPO-RCLOCKS at 33810098

S doing lock recovery

S setting server cache root

S found spool memory service RSPO-SERVERCACHE at 33810808

S using messages for server info

S immediate print option for implicitely closed spool requests is disabled

A

A -PXA--

---

A PXA INITIALIZATION

A Attached to PXA (address 33930040, size 411788K)

A abap/pxa = shared

A PXA INITIALIZATION FINISHED

A -PXA--

---

A

A RFC Destination> destination S64LMWBIBV6_BCP_00 host S64LMWBIBV6 system BCP systnr 0 (S64LMWBIBV6_BCP_00)

A RFC Options> H=S64LMWBIBV6,S=00

A RFC rfc/dump_client_info not set, use default -1

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

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

A RFC Method> initialize RemObjDriver for ABAP Objects

M SsfSapSecin: putenv(SECUDIR=C:\usr\sap\BCP\DVEBMGS00\sec): ok

M SsfSapSecin: PSE C:\usr\sap\BCP\DVEBMGS00\sec\SAPSYS.pse found!

M

M =================================================

M === SSF INITIALIZATION:

M ===...SSF Security Toolkit name SAPSECULIB .

M ===...SSF trace level is 0 .

M ===...SSF library is C:\usr\sap\BCP\SYS\exe\run\sapsecu.dll .

M ===...SSF hash algorithm is SHA1 .

M ===...SSF symmetric encryption algorithm is DES-CBC .

M ===...sucessfully completed.

M =================================================

E Replication is disabled

E EnqCcInitialize: local lock table initialization o.k.

E EnqId_SuppressIpc: local EnqId initialization o.k.

E EnqCcInitialize: local enqueue client init o.k.

M MBUF info for hooks: MS component UP

M ThSetEnqName: set enqname by profile

M ThISetEnqname: enq name = >S64LMWBIBV6_BCP_00 <

E

E *************** EnqId_EN_ActionAtMsUpHook ***************

E Hook on upcoming Ms (with EnqSrv), get auth EnqId and check it locally

E

E *************** ObjShMem_CheckAuthoritativeEnqId ***************

E Checking authoritative EnqId from EnqSrv into ObjShMem

E ObjShMem_CheckAuthoritativeEnqId: ObjShMem ...

E EnqId.EnqTabCreaTime = -999

E EnqId.RandomNumber = -999

E ReqOrd.TimeInSecs = -999

E ReqOrd.ReqNumberThisSec = -999

E ObjShMem_CheckAuthoritativeEnqId: ObjShMem ...

E EnqId.EnqTabCreaTime = -999

E EnqId.RandomNumber = -999

E ReqOrd.TimeInSecs = -999

E ReqOrd.ReqNumberThisSec = -999

E ObjShMem_CheckAuthoritativeEnqId: EnqId is initial in ShMem

E ObjShMem_CheckAuthoritativeEnqId: Overwrite incoming auth EnqId, continue

E EnqId inscribed into initial ObjShMem: (ObjShMem_CheckAuthoritativeEnqId)

E -SHMEM--

---

E EnqId: EnqTabCreaTime/RandomNumber = 22.04.2008 09:22:50 1208848970 / 4060

E ReqOrd at Srv: TimeInSecs/ReqNumberThisSec = 22.04.2008 09:22:51 1208848971 / 1

E ReqOrd at Cli: TimeInSecs/ReqNumberThisSec = 22.04.2008 09:22:51 1208848971 / 1

E Status: STATUS_OK

E -

---

L Begin of BtcSysStartRaise

L Raise event SAP_SYSTEM_START with parameter <S64LMWBIBV6_BCP_00 >

L End of BtcSysStartRaise

I

I Tue Apr 22 09:22:52 2008

I *** ERROR => New Process with the same PID

[semnt.c 1513]

S

S Tue Apr 22 09:25:08 2008

S found spool memory service RSPO-ACTIONS at 33814A50

-

---

-

---

trc file: "dev_w11", trc level: 1, release: "46D"

-

---

*

• ACTIVE TRACE LEVEL 1

• ACTIVE TRACE COMPONENTS all, M

*

B

B Tue Apr 22 09:22:48 2008

B create_con (con_name=R/3)

B Loading DB library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' ...

B Library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' loaded

B Version of library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' is "46D.00", patchlevel (0.2311)

B New connection 0 created

M relno 4640

M patchlevel 0

M patchno 2356

M intno 0

M pid 7556

M

M ***LOG Q0Q=> tskh_init, WPStart (Workproc11 7556) [dpxxdisp.c 976]

I MtxInit: -2 0 0

X EmInit: MmSetImplementation( 2 ).

X <ES> client 11 initializing ....

X Using implementation std

M <ES> Memory Reset enabled

X ES initialized.

M ThSysUsrCheck: clear T11

M calling db_connect ...

C Got NLS_LANG=AMERICAN_AMERICA.WE8DEC from environment

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

C Connecting as /@BCP on connection 0 ...

C

C Tue Apr 22 09:22:49 2008

C *** ERROR => OCI-call 'olog' failed: rc = 12537

[dboci.c 2460]

C *** ERROR => CONNECT failed with sql error '12537'

[dboci.c 7034]

C Try to connect with default password

C Connecting as SAPR3/<pwd>@BCP on connection 0 ...

C *** ERROR => OCI-call 'olog' failed: rc = 12537

[dboci.c 2460]

C *** ERROR => CONNECT failed with sql error '12537'

[dboci.c 7034]

C Got NLS_LANG=AMERICAN_AMERICA.WE8DEC from environment

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

C Connecting as /@BCP on connection 0 ...

C Now I'm connected to ORACLE using OCI_7 API

C Got SAPR3's password from table SAPUSER

C Disconnecting from connection 0 ...

C Now I'm disconnected from ORACLE

C Try to connect with the password I got from OPS$-user

C Connecting as SAPR3/<pwd>@BCP on connection 0 ...

C Now I'm connected to ORACLE using OCI_7 API

C Database instance bcp is running on S64LMWBIBV6 with ORACLE version 10.2.0.2.0 since 20080422

B Connection 0 opened

B Wp Hdl ConName ConCnt ConState TX PRM RCT Date Time

B 000 000 R/3 000000 ACTIVE NO YES NO 20080422 092249

B ***LOG BY2=> sql error 0 performing CON [dbsh 0707 ]

B ***LOG BY0=> ORA-00000: normal, successful completion

[dbsh 0707 ]

B ***LOG BY2=> sql error 0 performing CON [dblink 0627 ]

B ***LOG BY0=> ORA-00000: normal, successful completion

[dblink 0627 ]

M ***LOG R19=> tskh_init, db_connect ( DB-Connect 000256) [thxxhead.c 1187]

M in_ThErrHandle: 1

M *** ERROR => tskh_init: db_connect (step 1, th_errno 13, action 3) [thxxhead.c 8517]

M

M Info for wp 11

M

M stat = 4

M reqtype = 4

M act_reqtype = -1

M tid = -1

M mode = 255

M len = -1

M rq_id = -1

M rq_source = 255

M rq_info = 0

M last_tid = 0

M last_mode = 0

M int_checked_resource = 0

M ext_checked_resource = 0

M report = > <

M action = 0

M tab_name = > <

M

M *****************************************************************************

M *

M * LOCATION SAP-Server S64LMWBIBV6_BCP_00 on host S64LMWBIBV6 (wp 11)

M * ERROR tskh_init: db_connect

M *

M * TIME Tue Apr 22 09:22:49 2008

M * RELEASE 46D

M * COMPONENT Taskhandler

M * VERSION 1

M * RC 13

M * MODULE thxxhead.c

M * LINE 8628

M * COUNTER 1

M *

M *****************************************************************************

M

M Entering ThSetStatError

M call ThrShutDown (1) ...

M ***LOG Q02=> wp_halt, WPStop (Workproc11 7556) [dpnttool.c 310]

-

---

trc file: "dev_w11", trc level: 1, release: "46D"

-

---

*

• ACTIVE TRACE LEVEL 1

• ACTIVE TRACE COMPONENTS all, M

*

B

B Tue Apr 22 09:25:48 2008

B create_con (con_name=R/3)

B Loading DB library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' ...

B Library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' loaded

B Version of library 'C:\usr\sap\BCP\SYS\exe\run\dboraslib.dll' is "46D.00", patchlevel (0.2311)

B New connection 0 created

M relno 4640

M patchlevel 0

M patchno 2356

M intno 0

M pid 6224

M

M ***LOG Q0Q=> tskh_init, WPStart (Workproc11 6224) [dpxxdisp.c 976]

I MtxInit: -2 0 0

X EmInit: MmSetImplementation( 2 ).

X <ES> client 11 initializing ....

X Using implementation std

M <ES> Memory Reset enabled

X ES initialized.

M calling db_connect ...

C Got NLS_LANG=AMERICAN_AMERICA.WE8DEC from environment

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

C Connecting as /@BCP on connection 0 ...

C Now I'm connected to ORACLE using OCI_7 API

C Got SAPR3's password from table SAPUSER

C Disconnecting from connection 0 ...

C Now I'm disconnected from ORACLE

C Try to connect with the password I got from OPS$-user

C Connecting as SAPR3/<pwd>@BCP on connection 0 ...

C Now I'm connected to ORACLE using OCI_7 API

C Database instance bcp is running on S64LMWBIBV6 with ORACLE version 10.2.0.2.0 since 20080422

B Connection 0 opened

B Wp Hdl ConName ConCnt ConState TX PRM RCT Date Time

B 000 000 R/3 000000 ACTIVE NO YES NO 20080422 092548

M db_connect o.k.

I MtxInit: 11 0 0

X EmInit: MmSetImplementation( 2 ).

X <ES> client 11 initializing ....

X Using implementation std

X ES initialized.

B db_con_shm_ini: WP_ID = 11, WP_CNT = 33

B dbtbxbuf: Buffer TABL (addr: 2B4700C8, size: 60000256, end: 2EDA88C8)

B dbexpbuf: Buffer EIBUF (addr: 30B000D0, size: 32768000, end: 32A400D0)

B dbexpbuf: Buffer CUA (addr: 0F6200D0, size: 6656000, end: 0FC790D0)

M rdisp/reinitialize_code_page -> 0

M rdisp/hold_keeps_time_slice -> 0

M rdisp/no_hooks_for_sqlbreak -> 0

M PfReadDBVersion: use compatibility mode for stat-files

M CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

S *** init spool environment

S initialize debug system

T Stack direction is downwards.

T debug control: prepare exclude for printer trace

T new memory block 06DF51D0

S spool kernel/ddic check: Ok

S using table TSP02FX for frontend printing

S 3 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

S found processing queue enabled

S found spool memory service RSPO-RCLOCKS at 0FE30098

S doing lock recovery

S setting server cache root

S found spool memory service RSPO-SERVERCACHE at 0FE30808

S using messages for server info

S immediate print option for implicitely closed spool requests is disabled

A

A -PXA--

---

A PXA INITIALIZATION

A Attached to PXA (address 336E0040, size 411788K)

A abap/pxa = shared

A PXA INITIALIZATION FINISHED

A -PXA--

---

A

A RFC rfc/dump_client_info not set, use default -1

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

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

A RFC Method> initialize RemObjDriver for ABAP Objects

M SsfSapSecin: putenv(SECUDIR=C:\usr\sap\BCP\DVEBMGS00\sec): ok

M

M =================================================

M === SSF INITIALIZATION:

M ===...SSF Security Toolkit name SAPSECULIB .

M ===...SSF trace level is 0 .

M ===...SSF library is C:\usr\sap\BCP\SYS\exe\run\sapsecu.dll .

M ===...SSF hash algorithm is SHA1 .

M ===...SSF symmetric encryption algorithm is DES-CBC .

M ===...sucessfully completed.

M =================================================

E Replication is disabled

E EnqCcInitialize: local lock table initialization o.k.

E EnqId_SuppressIpc: local EnqId initialization o.k.

E EnqCcInitialize: local enqueue client init o.k.

B

B Tue Apr 22 09:28:08 2008

B dbtran INFO (init_connection '<DEFAULT>' [ORACLE:46D.00]):

B max_blocking_factor = 5, max_in_blocking_factor =1000,

B min_blocking_factor = 5, min_in_blocking_factor = 10,

B prefer_union_all = 0, prefer_union_for_select_all = 0,

B prefer_fix_blocking = 0, prefer_in_itab_opt = 0,

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

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

B select * = 0x3,

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

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

M SecAudit(check_daily_file): audit file opened C:\usr\sap\BCP\DVEBMGS00\log\20080422.AUD

[stefan_koehler]

Hello Djam,

>I have set quesize parameter to 60.

>Then i have set SQLNET.INBOUND_CONNECT_TIMEOUT to 120 in listner.ora and sqlnet.ora

>Sap still start with half of processes running. (better than before)

• Have you restarted your listener after you have set the queuesize to 60?

• Can you run 32 parallel "R3trans -d" to simulate the same problem? (in a .bat file)

• Can you please post your listener.ora?

Regards

Stefan

[Former Member]

Hello Stephan,

Thank you for your help.

Yes, i have restarted the listner.

I tried as suggested to lunch a .bat file with 30 R3trans -d.

I'am not sure the 30 "R3trans -d" were lunch at the same time or one after the other. I just created a file with 30 "R3trans -d".

But i belive that each command was executed one after the other without waiting the end of the previous.

-

---

> I get no error

Here is the listner.ora:

################

1. Filename......: listener.ora

2. Created.......: created by SAP AG, R/3 Rel. >= 4.0A

3. Name..........:

4. Date..........:

################

LISTENER =

(ADDRESS_LIST =

(ADDRESS=

(PROTOCOL=IPC)

(KEY= BCP.WORLD)

)

(ADDRESS=

(PROTOCOL=IPC)

(KEY= BCP)

)

(ADDRESS =

(COMMUNITY = SAP.WORLD)

(PROTOCOL = TCP)

(HOST = S64LMWBIBV6)

(PORT = 1527)

(QUEUESIZE = 60)

)

)

STARTUP_WAIT_TIME_LISTENER = 0

CONNECT_TIMEOUT_LISTENER = 10

TRACE_LEVEL_LISTENER = OFF

SID_LIST_LISTENER =

(SID_LIST =

(SID_DESC =

(SDU = 32768)

(SID_NAME = BCP)

(ORACLE_HOME = c:\oracle\BCP\102)

)

)

INBOUND_CONNECT_TIMEOUT_LISTENER_BCP=120