cancel
Showing results for 
Search instead for 
Did you mean: 

Upgrade: Problems EWIMPORT_UPG PHASE -> 7 Hours!! TP Long runtime

Former Member
0 Kudos

Hi All,

We are upgrading our enviroment from 4.6B to 4.7, we are using the last versions of: <b>TP, R3Trans, R3up</b>

The Instance is UP / Green

The Shadow instance is Down

We arrive to the Phase REQJOBRES so we run the program <b>BTCTRNS2</b> as user ddic with transaction SE38.

After that we continue with the UPGRADE in the phase EWIMPORT_UPG today in the morning.

The problem is that 7 hours after it continues in the state <b>TP</b> Running, we are scared about that because in a previous upgrade in only takes 2 min.

We saw all the logs, and the only one that was modified from that hour is the AVAILABLE.LOG file. Not Errors, Not new Logs, only this:

<b>

>> 08:43:32 UPGRADE/POSTP: START OF PHASE EWIMPORT

running D:\usr\sap\PRD\SYS\exe\run\tp.exe pf=E:\usr\sap\put\bin\DEFAULT.TPP checkimpdp PRD

</b>

In this moment the time is: <b>16:06 PM</b>

The available.log gives a message:

Our system is called <b>PRD</b>

unavailable 2007.....

Please Some Tips,

Best Regards,

Ivonne Villegas

Accepted Solutions (0)

Answers (2)

Answers (2)

Former Member
0 Kudos

Hi IVONNE VILLEGAS ,

As Markus mentioned above, Please run SGEN. After every installation and upgrade, you run SGEN as 1st priority. I hope you have checked TCODE SICK and other post processing.... So, SGEN will clear your performance time... Did you meet the upgrade requirements?

Thanks,

markus_doehr2
Active Contributor
0 Kudos

> We arrive to the Phase REQJOBRES so we run the

> program <b>BTCTRNS2</b> as user ddic with

> transaction SE38.

That means, that you de-suspended the jobs... Usually that programs is run AFTER the upgrade. BTCTRNS1 is run automatically by R3up.

> After that we continue with the UPGRADE in the phase

> EWIMPORT_UPG today in the morning.

>

> >> 08:43:32 UPGRADE/POSTP: START OF PHASE EWIMPORT

>

> running D:\usr\sap\PRD\SYS\exe\run\tp.exe

> pf=E:\usr\sap\put\bin\DEFAULT.TPP checkimpdp PRD

> </b>

>

Check \usr\sap\trans\log the SLOG<DATE>.PRD file for any errors/entries.

--

Markus

Former Member
0 Kudos

Hi Markus,

In fact, the idea of the report is to Re-shedule the Jobs...

I looked for the files in <b> D:\usr\sap\trans\log </b> but the last one modification was on 11/09/2007 or 3 days ago.

Best Regards,

Ivonne Villegas

Former Member
0 Kudos

Hi!

We are having problems with the server:

It goes from <b>Green</b> To <b>Yellow</b> and viceversa.

The dispatcher gives this Error:

<b>Running but bad dialog queue time</b>.

Best Regards,

Ivonne Villegas

markus_doehr2
Active Contributor
0 Kudos

> Hi Markus,

>

> In fact, the idea of the report is to Re-shedule the

> Jobs...

yes - during a certain phase in the upgrade, the jobs are being suspended, you shouldnu00B4t re-schedule them after that phase.

>

> I looked for the files in <b> D:\usr\sap\trans\log

> </b> but the last one modification was on 11/09/2007

> or 3 days ago.

is your filesystem full?

--

Markus

markus_doehr2
Active Contributor
0 Kudos

Check dev_disp and dev_w0 for errors.

--

Markus

Former Member
0 Kudos

Hi Markus,

We have a lot of space in our file systems.

This is our <b>dev_disp</b> file:

Mon Nov 12 08:26:10 2007

rdisp/http_min_wait_dia_wp : 1 -> 1

***LOG Q0K=> DpMsAttach, mscon ( ve-ccs-sapbf) [dpxxdisp.c 9719]

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

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

DpMsgAdmin: Set release to 6400, patchlevel 0

MBUF state PREPARED

MBUF component UP

DpMBufHwIdSet: set Hardware-ID

***LOG Q1C=> DpMBufHwIdSet [dpxxmbuf.c 1025]

DpMsgAdmin: Set patchno for this platform to 21

Release check o.K.

Mon Nov 12 08:26:21 2007

MBUF state ACTIVE

DpModState: change server state from STARTING to ACTIVE

Mon Nov 12 08:26:26 2007

      • WARNING => DpRqServiceQueue: timeout of HIGH PRIO msg, return DP_CANT_HANDLE_REQ

Mon Nov 12 08:36:46 2007

change reqtype of W1 from 1 to 4

***LOG Q0V=> DpAdXWpConf, mod_req ( 1DIABTC) [dpxxdisp.c 11579]

Mon Nov 12 08:43:25 2007

***LOG Q0I=> NiPRead: recv (10054: WSAECONNRESET: Connection reset by peer) [ninti.c 777]

Network error of client T26, NiBufReceive (-6: NIECONN_BROKEN), dp_tm_status=3

Client address of T26 is 10.55.47.199(w-ccs-bdhnk71.ve.ad.ep.corp.local)

***LOG Q04=> DpRTmPrep, NiBufReceive (29 DDIC 26 w-ccs-bdhnk7) [dpxxdisp.c 9436]

RM-T26, U29, 000 DDIC, w-ccs-bdhnk71, 08:39:01, M0, W4, SM37, 2/2

Mon Nov 12 08:50:50 2007

DpHdlDeadWp: restart wp (pid=16384) automatically

Mon Nov 12 09:17:41 2007

DpHdlDeadWp: restart wp (pid=16416) automatically

Mon Nov 12 13:56:34 2007

***LOG Q0I=> NiPRead: recv (10054: WSAECONNRESET: Connection reset by peer) [ninti.c 777]

Network error of client T29, NiBufReceive (-6: NIECONN_BROKEN), dp_tm_status=3

Client address of T29 is 10.55.47.199(w-ccs-bdhnk71.ve.ad.ep.corp.local)

***LOG Q04=> DpRTmPrep, NiBufReceive (92 DDIC 29 w-ccs-bdhnk7) [dpxxdisp.c 9436]

RM-T29, U92, 020 DDIC, w-ccs-bdhnk71, 13:44:03, M0, W9, SESS, 2/0

<b>dev_w0</b>

Mon Nov 12 08:26:10 2007

rdisp/http_min_wait_dia_wp : 1 -> 1

***LOG Q0K=> DpMsAttach, mscon ( ve-ccs-sapbf) [dpxxdisp.c 9719]

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

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

DpMsgAdmin: Set release to 6400, patchlevel 0

MBUF state PREPARED

MBUF component UP

DpMBufHwIdSet: set Hardware-ID

***LOG Q1C=> DpMBufHwIdSet [dpxxmbuf.c 1025]

DpMsgAdmin: Set patchno for this platform to 21

Release check o.K.

Mon Nov 12 08:26:21 2007

MBUF state ACTIVE

DpModState: change server state from STARTING to ACTIVE

Mon Nov 12 08:26:26 2007

      • WARNING => DpRqServiceQueue: timeout of HIGH PRIO msg, return DP_CANT_HANDLE_REQ

Mon Nov 12 08:36:46 2007

change reqtype of W1 from 1 to 4

***LOG Q0V=> DpAdXWpConf, mod_req ( 1DIABTC) [dpxxdisp.c 11579]

Mon Nov 12 08:43:25 2007

***LOG Q0I=> NiPRead: recv (10054: WSAECONNRESET: Connection reset by peer) [ninti.c 777]

Network error of client T26, NiBufReceive (-6: NIECONN_BROKEN), dp_tm_status=3

Client address of T26 is 10.55.47.199(w-ccs-bdhnk71.ve.ad.ep.corp.local)

***LOG Q04=> DpRTmPrep, NiBufReceive (29 DDIC 26 w-ccs-bdhnk7) [dpxxdisp.c 9436]

RM-T26, U29, 000 DDIC, w-ccs-bdhnk71, 08:39:01, M0, W4, SM37, 2/2

Mon Nov 12 08:50:50 2007

DpHdlDeadWp: restart wp (pid=16384) automatically

Mon Nov 12 09:17:41 2007

DpHdlDeadWp: restart wp (pid=16416) automatically

Mon Nov 12 13:56:34 2007

***LOG Q0I=> NiPRead: recv (10054: WSAECONNRESET: Connection reset by peer) [ninti.c 777]

Network error of client T29, NiBufReceive (-6: NIECONN_BROKEN), dp_tm_status=3

Client address of T29 is 10.55.47.199(w-ccs-bdhnk71.ve.ad.ep.corp.local)

***LOG Q04=> DpRTmPrep, NiBufReceive (92 DDIC 29 w-ccs-bdhnk7) [dpxxdisp.c 9436]

RM-T29, U92, 020 DDIC, w-ccs-bdhnk71, 13:44:03, M0, W9, SESS, 2/0

At the end, we will try to restart R3up closing the comand promt window, restarting the server, and repeating R3up again since ourr system is hanged!

Best Regards,

Ivonne Villegas

markus_doehr2
Active Contributor
0 Kudos

This is two times the same file, check dev_w0 again, there you will see the real reason why the workprocess dies.

--

Markus

Former Member
0 Kudos

Hi!

this is the dev_w0

-


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

-


*

  • ACTIVE TRACE LEVEL 1

  • ACTIVE TRACE COMPONENTS all, M

*

B

B Tue Nov 13 09:28:32 2007

B create_con (con_name=R/3)

B Loading DB library 'D:\usr\sap\PRD\SYS\exe\run\dboraslib.dll' ...

B Library 'D:\usr\sap\PRD\SYS\exe\run\dboraslib.dll' loaded

B Version of 'D:\usr\sap\PRD\SYS\exe\run\dboraslib.dll' is "640.00", patchlevel (0.19)

B New connection 0 created

M systemid 560 (PC with Windows NT)

M relno 6400

M patchlevel 0

M patchno 21

M intno 20020600

M make: multithreaded, ASCII

M pid 1288

M

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

I MtxInit: -2 0 0

M DpSysAdmExtCreate: ABAP is active

M DpSysAdmExtCreate: JAVA is not active

M DpShMCreate: sizeof(wp_adm) 21528 (828)

M DpShMCreate: sizeof(tm_adm) 22099048 (11044)

M DpShMCreate: sizeof(wp_ca_adm) 18000 (60)

M DpShMCreate: sizeof(appc_ca_adm) 6000 (60)

M DpShMCreate: sizeof(comm_adm) 192000 (384)

M DpShMCreate: sizeof(vmc_adm) 0 (320)

M DpShMCreate: sizeof(wall_adm) (224040/329544/56/100)

M DpShMCreate: SHM_DP_ADM_KEY (addr: 06B80040, size: 22896320)

M DpShMCreate: allocated sys_adm at 06B80040

M DpShMCreate: allocated wp_adm at 06B817A8

M DpShMCreate: allocated tm_adm_list at 06B86BC0

M DpShMCreate: allocated tm_adm at 06B86BE8

M DpShMCreate: allocated wp_ca_adm at 0809A050

M DpShMCreate: allocated appc_ca_adm at 0809E6A0

M DpShMCreate: allocated comm_adm_list at 0809FE10

M DpShMCreate: allocated comm_adm at 0809FE28

M DpShMCreate: allocated vmc_adm_list at 080CEC28

M DpShMCreate: system runs without vmc_adm

M DpShMCreate: allocated ca_info at 080CEC50

M DpShMCreate: allocated wall_adm at 080CEC58

X

X Tue Nov 13 09:28:33 2007

X EmInit: MmSetImplementation( 2 ).

X <ES> client 0 initializing ....

X Using implementation std

M <EsNT> Memory Reset enabled as NT default

X ES initialized.

M

M Tue Nov 13 09:28:34 2007

M calling db_connect ...

C Got ORACLE_HOME=D:\oracle\PRD\920 from environment

C Client NLS settings: AMERICAN_AMERICA.WE8DEC

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

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

C Attaching to DB Server PRD (con_hdl=0,svchp=09BBAC64,svrhp=04DB5774)

C Starting user session (con_hdl=0,svchp=09BBAC64,srvhp=04DB5774,usrhp=04DBE388)

C

C Tue Nov 13 09:28:35 2007

C Now I'm connected to ORACLE

C Got SAPR3's password from OPS$-user

C Disconnecting from connection 0 ...

C Closing user session (con_hdl=0,svchp=09BBAC64,usrhp=04DBE388)

C Now I'm disconnected from ORACLE

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

C Starting user session (con_hdl=0,svchp=09BBAC64,srvhp=04DB5774,usrhp=04DBE388)

C Now I'm connected to ORACLE

C Database NLS settings: AMERICAN_AMERICA.WE8DEC

C Database instance prd is running on VE-CCS-SAPBF with ORACLE version 9.2.0.7.0 since 20071113

B Connection 0 opened

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

B 000 000 R/3 000000000 ACTIVE NO YES NO 255 255 20071113 092834 VE-CCS-SAPBF

M db_connect o.k.

M

M Tue Nov 13 09:28:40 2007

M CrtChkOsSem: create check mutex and RC=[already exists] (Key:4/Name:SAP_SYSTEM_MUTEX_0_4/Hdl:220)

I

I Tue Nov 13 09:28:42 2007

I MtxInit: 0 0 0

M SHM_PRES_BUF (addr: 09EC0040, size: 20000000)

M SHM_ROLL_AREA (addr: 21420040, size: 268435456)

M SHM_PAGING_AREA (addr: 10310040, size: 134217728)

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

M SHM_PAGING_ADM (addr: 0B470040, size: 525344)

M ThCreateNoBuffer allocated 320144 bytes for 1000 entries at 0B500040

M ThCreateNoBuffer index size: 3000 elems

M ThCreateVBAdm allocated 7424 bytes (50 server) at 0B550040

X EmInit: MmSetImplementation( 2 ).

X <ES> client 0 initializing ....

X Using implementation std

X ES initialized.

B

B Tue Nov 13 09:28:43 2007

B db_con_shm_ini: WP_ID = 0, WP_CNT = 26

B dbtbxbuf: Buffer TABL (addr: 183200C8, size: 50000000, end: 1B2CF148)

B dbtbxbuf: Buffer TABLP (addr: 1B2D00C8, size: 30720000, end: 1D01C0C8)

B dbexpbuf: Buffer EIBUF (addr: 1D0200D0, size: 20480000, end: 1E3A80D0)

B dbexpbuf: Buffer ESM (addr: 0F4E00D0, size: 4194304, end: 0F8E00D0)

B dbexpbuf: Buffer CUA (addr: 0F8F00D0, size: 5120000, end: 0FDD20D0)

B dbexpbuf: Buffer OTR (addr: 1E3B00D0, size: 4194304, end: 1E7B00D0)

M rdisp/reinitialize_code_page -> 0

M icm/accept_remote_trace_level -> 0

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 09CB9D00

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 intervals: query=50, rescan=1800, global=300 info=120

S processing queue enabled

S

S Tue Nov 13 09:28:44 2007

S creating spool memory service RSPO-RCLOCKS at 69B00098

S doing lock recovery

S setting server cache root

S using server cache size 100 (prof=100)

S creating spool memory service RSPO-SERVERCACHE at 69B00680

S using messages for server info

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

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_union_for_select_all = 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 =SBCS / <none>:-,

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

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

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

S size of open spool request entry: 1152 bytes

S immediate print option for implicitely closed spool requests is disabled

A

A -PXA--


A PXA INITIALIZATION

A PXA: Fragment Size too small: 97 MB, reducing # of fragments

A System page size: 4kb, admin_size: 13172kb.

A Attached to PXA (address 31450040, size 400000K)

A abap/pxa = shared protect gen_remote

A PXA INITIALIZATION FINISHED

A -PXA--


A

A

A Tue Nov 13 09:28:45 2007

A ABAP ShmAdm attached (addr=607E5000 leng=20955136 end=61BE1000)

A >> Shm MMADM area (addr=60B4D300 leng=134720 end=60B6E140)

A >> Shm MMDAT area (addr=60B6F000 leng=17244160 end=61BE1000)

A RFC Destination> destination ve-ccs-sapbf_PRD_00 host ve-ccs-sapbf system PRD systnr 0 (ve-ccs-sapbf_PRD_00)

A RFC Options> H=ve-ccs-sapbf,S=00

A RFC FRFC> fallback activ but this is not a central instance.

A

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

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 Method> initialize RemObjDriver for ABAP Objects

A Hotpackage version: 60

M ThrCreateShObjects allocated 27108 bytes at 0FF90040

N SsfSapSecin: putenv(SECUDIR=D:\usr\sap\PRD\DVEBMGS00\sec): ok

N

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

N === SSF INITIALIZATION:

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

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

N ===...SSF library is D:\usr\sap\PRD\SYS\exe\run\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.

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

W === ipl_Init() called

W ITSP Running against db release 620!

W ITSP Disable Kernel Web GUI functionality

W === ipl_Init() returns 2, ITSPE_DISABLED: Service is disabled (sapparam)

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

M

M Tue Nov 13 09:28:51 2007

M SecAudit(check_daily_file): audit file opened D:\usr\sap\PRD\DVEBMGS00\log\20071113.AUD

E

E Tue Nov 13 09:29:56 2007

E Replication is disabled

M

M Tue Nov 13 09:30:51 2007

M ***LOG R68=> ThIRollBack, roll back () [thxxhead.c 12251]

S

S Tue Nov 13 09:48:51 2007

S found spool memory service RSPO-ACTIONS at 69B04F08

C

C Tue Nov 13 09:58:51 2007

C User requested cancel of current SQL on connection 0

M program canceled

M reason = max run time exceeded

M user = SAPSYS

M client = 000

M terminal =

M report = SAPLSCSMROUTE

M

M Tue Nov 13 09:59:52 2007

M 2. ALARM: terminate process (pid=1288, user is T0/M0)

M ThRollOut: roll out T0/U1/M0 (level=7, short_roll_out=0)

M ThCallHooks: call hook >SAP-Trace RFC save< for event BEFORE_ROLL_OUT

M TrThHookFunc: called for Rollout

M ThCallHooks: call hook >rsts_before_rollout< for event BEFORE_ROLL_OUT

M SosSearchAnchor: search anchor for 10

M SosSearchAnchor: search anchor for 10

M ThCallHooks: call hook >RtmRollOut< for event BEFORE_ROLL_OUT

M ThRollOut: call rrol_out (1)

M ThRollOut: act roll state = DP_ROLLED_OUT

M ThRollOut: roll level <> 0, don't call ab_rollout

M ThRollOut: full roll out of U1 M0 I1 (Level 7) ok

M ThAlarmHandler: save snc contexts

M ThISncSaveAllContexts: save snc contexts

M ThAlarmHandler: C-Stack during alarm handler

M -


C-STACK -


SAP (R) - R/3(TM) Callstack, Version 1.0

Copyright (C) SAP AG. All rights reserved.

-


Callstack without Exception:

App : disp+work.EXE (pid=1288)

When : 11/13/2007 9:59:53.281

Threads : 2

-


Computer Name : VE-CCS-SAPBF

User Name : SAPServicePRD

-


Number of Processors: 4

Processor Type: x86 Family 15 Model 2 Stepping 9

Windows Version : 5.0 Current Build: 2195

-


State Dump for Thread Id 57c

eax=0000008a ebx=ffffffff ecx=00000010 edx=00000000 esi=7fffffff edi=00000102

eip=77f88f13 esp=02ff8c98 ebp=02ff8cd0 iopl=0 nv up ei ng nz ac po cy

cs=001b ss=0023 ds=0023 es=0023 fs=0038 gs=0000 efl=00000297

function : NtWaitForSingleObject

77f88f08 b8ea000000 mov eax,0xea

77f88f0d 8d542404 lea edx,[esp+0x4] ss:08b77c0b=00000000

77f88f11 cd2e int 2e

77f88f13 c20c00 ret 0xc

77f88f16 8bff mov edi,edi

--> Stack Back Trace <--

FramePtr ReturnAd Param#1 Param#2 Param#3 Param#4 Function Name

02ff8cd0 74fd160d 00000108 00000104 00000000 00000006 ntdll!NtWaitForSingleObject

02ff8d4c 750313fa 00000104 02ff8dac 00000001 02ff8db8 msafd!<nosymbols>

02ff8d88 09e65644 00000104 02ff8dac 00000001 02ff8db8 WS2_32!WSARecv

02ff8dbc 09e62f74 00000104 09d8a85e 00000810 09d61938 orantcp9!nttini

02ff8de4 041e47ba 09d8c5e8 09d8a85e 09d8ca6c 00000000 orantcp9!nttini

02ff8e4c 041b6640 09d60850 02ff96a8 09d8a808 09d8c4f8 oran9!ztvull

02ff96b4 041a1228 09d60850 09d8c4e8 09d627e0 09d61938 oran9!nsrdr

02ff9764 0417050a 09d62878 00000055 09d8d778 09d6294c oran9!nsdo

02ff97ac 03dbf99b 04db5890 00000000 04db7443 00000001 oran9!nioqrc

02ffa0d0 04173a0d 04db7434 00000000 04db7434 04db57ec oracommon9!ttcdrv

02ffa0e8 00294a7b 04db5890 00000000 002e3200 04db7434 oran9!nioqwa

02ffc198 0024f95d 00db57ec 0000005e 04db7368 04db7434 oraclient9!xaolog

02ffc1cc 0023d987 04db57ec 0000005e 04db7368 04db7434 oraclient9!upirtr

02ffc208 0021e89f 09bbac64 0000005e 04db7368 04db7434 oraclient9!kpurcs

02ffe2d0 0021fb2d 09bbac64 6f9cc528 04db8874 00000000 oraclient9!kpuexInitStmh

02ffe330 001a1bda 09bbac64 019cc528 09bbb2a8 00000001 oraclient9!kpuexec

02ffe35c 0017cdb7 09bbac64 6f9cc528 09bbb2a8 00000001 oraclient9!OCIStmtExecute

02ffe384 000f3dda 09bbac64 6f9cc528 09bbb2a8 00000001 OCI!OCICharSetToUnicode

02ffe3c8 000fc333 00000000 6f9cc528 00000001 00000000 dboraslib!oci_execute_stmt [dboci.c (1456)]

02ffe414 000ff050 01f45410 04d9a2f8 04da1318 01f45450 dboraslib!stmt_execute [dbsloci.c (10933)]

02ffe444 000ffbe7 01f45410 00000000 01f458ec 00000004 dboraslib!exec_modify [dbsloci.c (7863)]

02ffe468 00c4c018 00000000 01f4593c 00000002 01f45450 dboraslib!DbSlModify [dbsloci.c (3160)]

02ffe48c 00bd0db1 01f458ec 01f4593c 00000002 01f45450 disp+work!dbsl_modify [dbslxxx.c (531)]

02ffe4ac 00bd116a 01f45408 00000003 00000003 00000000 disp+work!dbsl_call [dbtrtab.c (3817)]

02ffe4c8 00bd150b 00000003 00000004 00000002 01f45408 disp+work!dbsl_exec [dbtrtab.c (3739)]

02ffe4dc 00bd1664 00000003 01f264b0 01f24600 00b6fa76 disp+work!modify_single [dbtrtab.c (1952)]

02ffe4ec 00b6fa76 00000003 01f264b0 00000003 01f264b0 disp+work!dbrt_trtab [dbtrtab.c (1222)]

02ffe50c 00b73485 00000003 00000003 00000000 02ffe540 disp+work!tran_rtab [dbcrtab.c (4027)]

02ffe528 00b74796 02ffe58c 49b80ca4 49b80ca4 01f264b0 disp+work!rtab_exec [dbcrtab.c (2778)]

02ffe540 00b69220 02ffe56c 00000003 02ffe59c 02ffe58c disp+work!db_xrtab0 [dbcrtab.c (1591)]

02ffe5b0 00b6d567 49b80d18 01f24610 02ffe610 49b80d18 disp+work!call_rtab [dbrsql.c (12536)]

02ffe5cc 00b6dab1 49b80c60 49b80ca4 02ffe618 00b6eb29 disp+work!modify_wrapper [dbrsql.c (6475)]

02ffe5dc 00b6eb29 49b80c60 49b80d18 49b80c60 00000000 disp+work!norm_access [dbrsql.c (4130)]

02ffe618 00742dc5 49b80c60 00b80ca4 49b80d18 ffffffff disp+work!db_rsql1994 [dbrsql.c (2687)]

02ffe63c 00748581 00000062 49b80d18 01c71358 00000001 disp+work!CallRsql94 [absapsql.c (7044)]

02ffe678 0075193e 00000111 00000000 000000cd 00000000 disp+work!SqlsExecuteCall [absapsql.c (7402)]

02ffe6b0 006e0e85 00000000 00000001 00000000 02ffe728 disp+work!ab_jsqls [absapsql.c (1389)]

02ffe754 007678e9 015e92e4 00000001 02ffe780 00000000 disp+work!ab_extri [abextri.c (425)]

02ffe768 00767dfb 00000000 00000000 01af44c4 00000000 disp+work!ab_xevent [abrunt1.c (261)]

02ffe780 008448f1 49b5a958 00000000 00000054 00000016 disp+work!ab_trigg [abrunt1.c (136)]

02ffe798 00571614 49b5a958 49b5a958 49b5b498 00000000 disp+work!ab_run [absubmit.c (602)]

02ffe7bc 005740bb 00000000 49b5a958 7c36a42b 00000000 disp+work!dynpmcal [dymainstp.c (2207)]

02ffe7d4 0057365d 49b5b498 49b5a958 00000003 02fffdcc disp+work!dynppai0 [dymainstp.c (1043)]

02ffe7f0 00549333 49b5a958 0000001a 0157088c 00000000 disp+work!dynprctl [dymainstp.c (350)]

02fffdcc 004747e2 00000013 02fffe74 00000001 00497614 disp+work!dynpen00 [dymain.c (1375)]

02fffddc 00497614 00000014 00fc3d00 035260d0 00000002 disp+work!Thdynpen00 [thxxhead.c (4353)]

02fffeec 004979ce 00000001 7ffdf000 00000000 00000000 disp+work!TskhLoop [thxxhead.c (4117)]

02ffff04 00421d69 00000000 00000000 02ffff60 00401059 disp+work!tskhstart [thxxhead.c (1038)]

02ffff14 00401059 00000003 035260d0 00000001 00000000 disp+work!DpMain [dpxxdisp.c (1009)]

02ffff60 0106172b 00000003 035260d0 03522c20 0152c000 disp+work!main [thxxanf.c (58)]

02ffffc0 7c5989d5 02fff170 7c57d784 7ffdf000 77fabe9c disp+work!mainCRTStartup [crtexe.c (398)]

02fffff0 00000000 010615e8 00000000 000000c8 00000100 KERNEL32!ProcessIdToSessionId

-


State Dump for Thread Id a68

eax=7c3494f6 ebx=00000103 ecx=02fffa58 edx=00000000 esi=00000000 edi=00000000

eip=77f884ff esp=0637feb0 ebp=0637fef0 iopl=0 nv up ei pl zr na po nc

cs=001b ss=0023 ds=0023 es=0023 fs=0038 gs=0000 efl=00000246

function : ZwFsControlFile

77f884f4 b848000000 mov eax,0x48

77f884f9 8d542404 lea edx,[esp+0x4] ss:0befee23=00000000

77f884fd cd2e int 2e

77f884ff c22800 ret 0x28

77f88502 8bff mov edi,edi

--> Stack Back Trace <--

FramePtr ReturnAd Param#1 Param#2 Param#3 Param#4 Function Name

0637fef0 00fc439c 00000208 00000000 03526920 04d56de8 ntdll!ZwFsControlFile

0637ff80 7c349565 00000000 03526920 00001c20 04d1ff58 disp+work!SigIMsgFunc [signt.c (559)]

0637ffb4 7c57b3bc 04d1ff58 03526920 00001c20 04d1ff58 MSVCR71!endthreadex

0637ffec 00000000 7c3494f6 04d1ff58 00000000 00000000 KERNEL32!lstrcmpiW

-


M -


M Tue Nov 13 09:59:53 2007

M ThCheckEmMagic: check em magic at 49B00A80 for T0/M0/I1

M ThCheckEmMagic: em magic at 49B00A80 for T0/M0/I1 o.k.

M ThEmContextDetach2: detach T0/M0 from em memory (em_hdl=2, force=0)

M ThEmContextDetach2: reset local em info

M ***LOG Q02=> wp_halt, WPStop (Workproc 0 1288) [dpnttool.c 357]

-


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

-


*

  • ACTIVE TRACE LEVEL 1

  • ACTIVE TRACE COMPONENTS all, M

*

B

B Tue Nov 13 10:00:06 2007

B create_con (con_name=R/3)

B Loading DB library 'D:\usr\sap\PRD\SYS\exe\run\dboraslib.dll' ...

B Library 'D:\usr\sap\PRD\SYS\exe\run\dboraslib.dll' loaded

B Version of 'D:\usr\sap\PRD\SYS\exe\run\dboraslib.dll' is "640.00", patchlevel (0.19)

B New connection 0 created

M systemid 560 (PC with Windows NT)

M relno 6400

M patchlevel 0

M patchno 21

M intno 20020600

M make: multithreaded, ASCII

M pid 476

M

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

I MtxInit: -2 0 0

M DpSysAdmExtCreate: ABAP is active

M DpSysAdmExtCreate: JAVA is not active

M DpShMCreate: sizeof(wp_adm) 21528 (828)

M DpShMCreate: sizeof(tm_adm) 22099048 (11044)

M DpShMCreate: sizeof(wp_ca_adm) 18000 (60)

M DpShMCreate: sizeof(appc_ca_adm) 6000 (60)

M DpShMCreate: sizeof(comm_adm) 192000 (384)

M DpShMCreate: sizeof(vmc_adm) 0 (320)

M DpShMCreate: sizeof(wall_adm) (224040/329544/56/100)

M DpShMCreate: SHM_DP_ADM_KEY (addr: 06C10040, size: 22896320)

M DpShMCreate: allocated sys_adm at 06C10040

M DpShMCreate: allocated wp_adm at 06C117A8

M DpShMCreate: allocated tm_adm_list at 06C16BC0

M DpShMCreate: allocated tm_adm at 06C16BE8

M DpShMCreate: allocated wp_ca_adm at 0812A050

M DpShMCreate: allocated appc_ca_adm at 0812E6A0

M DpShMCreate: allocated comm_adm_list at 0812FE10

M DpShMCreate: allocated comm_adm at 0812FE28

M DpShMCreate: allocated vmc_adm_list at 0815EC28

M DpShMCreate: system runs without vmc_adm

M DpShMCreate: allocated ca_info at 0815EC50

M DpShMCreate: allocated wall_adm at 0815EC58

X EmInit: MmSetImplementation( 2 ).

X <ES> client 0 initializing ....

X Using implementation std

M <EsNT> Memory Reset enabled as NT default

X ES initialized.

M

M Tue Nov 13 10:00:07 2007

M calling db_connect ...

C Got ORACLE_HOME=D:\oracle\PRD\920 from environment

C Client NLS settings: AMERICAN_AMERICA.WE8DEC

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

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

C Attaching to DB Server PRD (con_hdl=0,svchp=09C4AC64,svrhp=04DB56D4)

C Starting user session (con_hdl=0,svchp=09C4AC64,srvhp=04DB56D4,usrhp=04DBE2E8)

What we do:

We restart the server, them R3up repeat and it finish the phase!!

But now it is hanged on the phase <b>CHK_POSTUP</b>

This could be our problem:

Our Memory RAM is: 3800 MB

But before the upgrade we set the shared_pool_size parameter to 400.000.000

So this could be affecting our sistem!!

Best Regards,

Ivonne Villegas

markus_doehr2
Active Contributor
0 Kudos

The system bails out here:

User requested cancel of current SQL on connection 0

M program canceled

M reason = max run time exceeded

M user = SAPSYS

M client = 000

M terminal =

M report = SAPLSCSMROUTE

M

what is your current setting of rdisp/max_wprun_time

--

Markus

Former Member
0 Kudos

Hi Markus,

Our rdisp/max_wprun_time is: <b>600</b>

We are having a lot of problems with the perfomance of the SAP System, since we try to finish the UPGRADE but it simply stop to work and give you bad queue time.

Best Regards,

Ivonne Villegas

Former Member
0 Kudos

Hi!

The UPGRADE finished, but now we are having a lot of perfomance problems!

We changed our Kernel to 196 version (Remenber we are now in the 4.7 Set 200 SR1).

We distribute the WP in this form:

<b>rdisp/wp_no_dia = 17</b>

rdisp/wp_no_vb = 4

rdisp/wp_no_vb2 = 2

rdisp/wp_no_enq = 1

<b>rdisp/wp_no_btc = 1</b>

rdisp/wp_no_spo = 1

abap/heap_area_dia = 2147483647

abap/heap_area_nondia = 2147483647

PHYS_MEMSIZE = 2208

Originally it was this:

<b>rdisp/wp_no_dia = 13</b>

rdisp/wp_no_vb = 4

rdisp/wp_no_vb2 = 2

rdisp/wp_no_enq = 1

<b>rdisp/wp_no_btc = 5</b>

rdisp/wp_no_spo = 1

abap/heap_area_dia = 2147483647

abap/heap_area_nondia = 2147483647

PHYS_MEMSIZE = 2208

But with this configuration ( <b>rdisp/wp_no_btc = 5</b>) our SAP system goes from Green to Yellow giving a Bad Queue Time.

We try with <b>rdisp/wp_no_btc = 3</b> too but it goes to yellow...

Best Regards,

Ivonne Villegas

markus_doehr2
Active Contributor
0 Kudos

A bad "queue time"? What do you mean with that?

I´m still not sure, the system writes a complete workprocess dump, something (maybe on OS level) is wrong.

- Start transactino SGEN and generate the programs

- restart the instance after that (to clear the buffers)

- update statistics on the database

What do you see in SM50 when you say "bad performance"?

--

Markus

Former Member
0 Kudos

by the way:

>>-SAPBF with ORACLE version 9.2.0.7.0 since 20071113

9.2.0.7.0 is not the most recent version of Oracle 9.2.

I personaly would recommend 9.2.0.8 with the latest minipatch (9.2.0.8.12) on top of it

peter

Former Member
0 Kudos

Hi!

Peter: Oracle 9.2.0.7 Was is the BOX that SAP send us to do the UPGRADE, it was sent the last month so it has to be with SAP that doesn't care about this details.

We resolved the problem, please see note <b>521264</b> page <b>4</b> point <b>3.</b> the problem was oracle not SAP.

Exactly this:

3. While the system is running:

a) In addition to the archiver stuck problem (Note 391) and a "Checkpoint not complete" error (Note 79341), which is easily identified by the entries in the alert log, the database hang situation may also be caused by the <b>log_archive_start parameter</b>: if the parameter is set to <b>"false" </b>even though the archive log mode is activated, the archiver process does not run and the online redo logs to be archived are not saved. The log writer then refuses to perform a log switch if it encounters one of the (unsaved) online redo logs. Set log_archive_start to "true" and restart the database to correct this problem. If you do not restart the database, you can also temporarily use an "archive log start" with SQLPLUS.

thanks a lot to all the people that help us!!!

Best Regards,

Ivonne Villegas

null