Patch update process is running very long time

[Former Member]

Dear All,

I am applying BASIS 700 patch KB70014.SAR in my Netweaver 2004s system running on oracle DB 10.2.0.2.0 and windows 2003 32 bit server. Kernel patch level was 133. But the process was taking very long time (> 40000 s) and still running. The BTC work process status was as shown below.

11 BTC 4080 Stop RFC Yes 0:00:57 40100 SAPLSTPA 000 KALYAN

But when i applied the patch KB70013.SAR it was completed < 2000 s.

The system was not allowing to login into SAP and getting the runtime error "Syntax error in program SAPLSFES".

can anyone please let me know that whether this process was regular or anything gone wrong in the system?

The BTC work process trace file and SLOG were as shown below.

Work Process TRACE FILE:-

-

---

trc file: "dev_w11", trc level: 1, release: "700"

-

---

• ACTIVE TRACE LEVEL 1

• ACTIVE TRACE COMPONENTS all, MJB

B Thu Mar 11 12:03:05 2010

B create_con (con_name=R/3)

B Loading DB library 'D:\usr\sap\NWX\DVEBMGS01\exe\dboraslib.dll' ...

B Library 'D:\usr\sap\NWX\DVEBMGS01\exe\dboraslib.dll' loaded

B Version of 'D:\usr\sap\NWX\DVEBMGS01\exe\dboraslib.dll' is "700.08", patchlevel (0.115)

B New connection 0 created

M sysno 01

M sid NWX

M systemid 560 (PC with Windows NT)

M relno 7000

M patchlevel 0

M patchno 133

M intno 20050900

M make: multithreaded, Unicode, optimized

M pid 4080M

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

M length of sys_adm_ext is 576 bytes

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

M ThInit: running on host nw2004sxiM

M Thu Mar 11 12:03:07 2010

M calling db_connect ...

C Prepending D:\usr\sap\NWX\DVEBMGS01\exe to Path.C

C Thu Mar 11 12:03:08 2010

C Oracle Client Version: '10.2.0.1.0'

C Client NLS settings: AMERICAN_AMERICA.UTF8

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

C Connecting as /@NWX on connection 0 (nls_hdl 0) ... (dbsl 700 250407)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 UTF8 1 05C71678 05C76C14 05C7649C

C Attaching to DB Server NWX (con_hdl=0,svchp=05C763E8,srvhp=05C87FD4)C

C Thu Mar 11 12:03:58 2010

C Starting user session (con_hdl=0,svchp=05C763E8,srvhp=05C87FD4,usrhp=05C9CD28)

C Now '/@NWX' is connected (con_hdl 0, nls_hdl 0).

C Got SAPSR3's password from OPS$-user

C Disconnecting from connection 0 ...

C Closing user session (con_hdl=0,svchp=05C763E8,usrhp=05C9CD28)

C Now I'm disconnected from ORACLE

C Connecting as SAPSR3/<pwd>@NWX on connection 0 (nls_hdl 0) ... (dbsl 700 250407)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 UTF8 1 05C71678 05C76C14 05C7649C

C Starting user session (con_hdl=0,svchp=05C763E8,srvhp=05C87FD4,usrhp=05C9CD28)

C Now 'SAPSR3/<pwd>@NWX' is connected (con_hdl 0, nls_hdl 0).

C Database NLS settings: AMERICAN_AMERICA.UTF8

C DB instance NWX is running on NW2004SXI with ORACLE version 10.2.0.2.0 since MAR 11, 2010, 12:02:36

B Connection 0 opened (DBSL handle 0)

B Wp Hdl ConName ConId ConState TX PRM RCT TIM MAX OPT Date Time DBHost

B 000 000 R/3 000000000 ACTIVE NO YES NO 000 255 255 20100311 120307 NW2004SXI

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

I MtxInit: 11 0 0

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

M SHM_ROLL_AREA (addr: 1A7C0040, size: 268435456)

M SHM_PAGING_AREA (addr: 120C0040, size: 134217728)

M SHM_ROLL_ADM (addr: 0AD00040, size: 2678942)

M SHM_PAGING_ADM (addr: 06470040, size: 525344)

M ThCreateNoBuffer allocated 544152 bytes for 1000 entries at 0AF90040

M ThCreateNoBuffer index size: 3000 elems

M ThCreateVBAdm allocated 12160 bytes (50 server) at 06500040

X EmInit: MmSetImplementation( 2 ).

X MM global diagnostic options set: 0

X <ES> client 11 initializing ....

X Using implementation view

X ES initialized.B

B Thu Mar 11 12:03:59 2010

B db_con_shm_ini: WP_ID = 11, WP_CNT = 18, CON_ID = -1

B dbtbxbuf: Buffer TABL (addr: 5CD000C8, size: 30000000, end: 5E99C448)

B dbtbxbuf: Buffer TABLP (addr: 0E8500C8, size: 10240000, end: 0F2140C8)

B dbexpbuf: Buffer EIBUF (addr: 0F2200D0, size: 4194304, end: 0F6200D0)

B dbexpbuf: Buffer ESM (addr: 0F6300D0, size: 4194304, end: 0FA300D0)

B dbexpbuf: Buffer CUA (addr: 0FA400D0, size: 3072000, end: 0FD2E0D0)

B dbexpbuf: Buffer OTR (addr: 108200D0, size: 4194304, end: 10C200D0)

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 10CBAEA0

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

S found processing queue enabled

S found spool memory service RSPO-RCLOCKS at 115F00A8

S doing lock recovery

S setting server cache root

S found spool memory service RSPO-SERVERCACHE at 115F0570

S using messages for server info

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

S size of open spool request entry: 2236 bytes

S immediate print option for implicitely closed spool requests is disabledA

A -PXA--

---

A PXA INITIALIZATION

A System page size: 4kb, total admin_size: 10364kb, dir_size: 10292kb.

A Attached to PXA (address 2A7F0040, size 300000K)

A abap/pxa = shared protect gen_remote

A PXA INITIALIZATION FINISHED

A -PXA--

---

A

A ABAP ShmAdm attached (addr=53A18000 leng=20955136 end=54E14000)

A >> Shm MMADM area (addr=53E8DEF8 leng=126176 end=53EACBD8)

A >> Shm MMDAT area (addr=53EAD000 leng=16150528 end=54E14000)

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

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

A RFC Method> initialize RemObjDriver for ABAP Objects

M ThrCreateShObjects allocated 37432 bytes at 06520040

N SsfSapSecin: putenv(SECUDIR=D:\usr\sap\NWX\DVEBMGS01\sec): okN

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

N === SSF INITIALIZATION:

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

N ===...SSF trace level is 0 .

N ===...SSF library is D:\usr\sap\NWX\DVEBMGS01\exe\sapsecu.dll .

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

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

N ===...sucessfully completed.

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

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

N MskiInitLogonTicketCacheHandle: Workprocess runs with Logon Ticket cache.

M JrfcVmcRegisterNativesDriver o.k.

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

W === ipl_Init() called

W ipl_Init(): wp task type is 4:BTC, skip initialization

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

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

E Replication is disabledE

E Thu Mar 11 12:04:00 2010

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 Thu Mar 11 12:08:25 2010

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 SecAudit(RsauShmInit): WP attached to existing shared memory.

M SecAudit(RsauShmInit): addr of SCSA........... = 05D60040

M SecAudit(RsauShmInit): addr of RSAUSHM........ = 05D607A8

M SecAudit(RsauShmInit): addr of RSAUSLOTINFO... = 05D607E0

M SecAudit(RsauShmInit): addr of RSAUSLOTS...... = 05D607ECA

A Thu Mar 11 12:12:56 2010

A GENER request remote generation: SAPLSCWT.A

A Thu Mar 11 12:12:57 2010

A GENER request remote generation: SAPLSCWE.A

A Thu Mar 11 12:12:58 2010

A GENER request remote generation: SAPLSCWC.A

A Thu Mar 11 12:13:02 2010

A GENER request remote generation: CL_CWB_DELTA==================CP.A

A Thu Mar 11 12:13:03 2010

A GENER request remote generation: CL_CWB_DELTA_ROOT=============CP.

A GENER request remote generation: SAPLSCWN.A

A Thu Mar 11 12:16:36 2010

A GENER request remote generation: SAPLSUMO.

A Thu Mar 11 12:16:39 2010

A GENER request remote generation: SAPLSVRS.

A Thu Mar 11 12:16:41 2010

A GENER request remote generation: SAPLSVRI.

A Thu Mar 11 12:16:59 2010

A GENER request remote generation: SAPLSTRJ.

A Thu Mar 11 12:17:01 2010

A GENER request remote generation: SAPLSEMM.

A Thu Mar 11 12:19:42 2010

A GENER request remote generation: SAPLSTOR.

A Thu Mar 11 12:19:53 2010

A GENER request remote generation: SAPLO2_CTS_SERVICES.

A Thu Mar 11 12:20:17 2010

A GENER request remote generation: CL_O2_API_PAGES===============CP.

A Thu Mar 11 12:20:20 2010

A GENER request remote generation: SAPLWDY_MD_VERSION_MANAGEMENT.

B Thu Mar 11 12:26:04 2010

B table logging switched off for all clients

M Thu Mar 11 13:01:13 2010

M *** WARNING => ThHdlReconnect: no reconnect in hold mode

M *** WARNING => ThHdlReconnect: no reconnect in hold mode

B RECONNECT: rsdb/reco_trials: 3

B RECONNECT: rsdb/reco_sleep_time: 5

B RECONNECT: rsdb/reco_sync_all_server: OFF

B ***LOG BV4=> reconnect state is set for the work process [dblink#5 @ 1958] [dblink 1958 ]

B ***LOG BYY=> work process left reconnect status [dblink#5 @ 1959] [dblink 1959 ]

++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

SLOG FILE:-

++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

START CMDIMPORT NWX L 20100311121314 KALYAN nw2004sxi 20100311121313

STOP CMDIMPORT NWX L 20100311121409 KALYAN nw2004sxi 20100311121313

START DD IMPORT NWX H 20100311122116 KALYAN nw2004sxi 20100311122115

STOP DD IMPORT NWX H 20100311122603 KALYAN nw2004sxi 20100311122115

START DD ACTIVATION NWX A 20100311122606 KALYAN nw2004sxi

START tp_getprots NWX J 20100311122607 KALYAN nw2004sxi

STOP tp_getprots NWX J 20100311124644 KALYAN nw2004sxi

STOP DD ACTIVATION NWX A 20100311124644 KALYAN nw2004sxi

START imp all NWX 20100311124658 KALYAN nw2004sxi 20100311124655

INFO: event SAP_IMPORT_START triggered successfully

START DISTRIBUTION OF DD-O NWX S 20100311124659 KALYAN nw2004sxi 20100311124655

START tp_getprots NWX S 20100311124659 KALYAN nw2004sxi 20100311124655

STOP tp_getprots NWX S 20100311124855 KALYAN nw2004sxi 20100311124655

STOP DISTRIBUTION OF DD-O NWX S 20100311124855 KALYAN nw2004sxi 20100311124655

START TBATG CONVERSION OF NWX N 20100311124855 KALYAN nw2004sxi 20100311124655

START tp_getprots NWX N 20100311124855 KALYAN nw2004sxi 20100311124655

STOP tp_getprots NWX N 20100311124917 KALYAN nw2004sxi 20100311124655

STOP TBATG CONVERSION OF NWX N 20100311124917 KALYAN nw2004sxi 20100311124655

START MOVE NAMETABS NWX 6 20100311124917 KALYAN nw2004sxi 20100311124655

START tp_getprots NWX P 20100311124918 KALYAN nw2004sxi 20100311124655

STOP tp_getprots NWX P 20100311130113 KALYAN nw2004sxi 20100311124655

STOP MOVE NAMETABS NWX 6 20100311130113 KALYAN nw2004sxi 20100311124655

START MAIN IMPORT NWX I 20100311130113 KALYAN nw2004sxi 20100311124655

+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

[Former Member]

Run DB statistics

[Former Member]

Hi Amber,

As you mentioned i waited for long time (> 70000 s) the patch was completed successfully. FYI i already running with latest SAINT and Kernel patches.

Thank you very much for your information.

Regards,

Kalyan.

[Former Member]

Hi Kalyan,

As suggested by Amber let the process go on and check what he asked to.

In addition please let us know how many background processes are configured in your system. Might be the case that there are not enough number of background wps in your system.

Regards

Sourabh Majumdar

[Former Member]

Hi,

Also, check if there is sufficient space in database and there should be sufficient space for log files.

Thanks & Regards,

Sharath

[former_member198270]

Hello Kalyan, Let the aptch update go on for some more time as from logs i can see there are no errors occured till now.

However, I would like to know that before starting patching have you applied latest spam/saint version and applied latest kernel ?

Hope it helps.

Regards, Amber S | ITL