Долго запускается инстанция после обновления KERNEL 701

BRILDI

Привет всем!

Обновил файлы ядра до последнего уровня Sup. Pkg. level. KERNEL 701.
Перезупускаю инстанцию и уже 4 часа загрузка процессора 50%, инстанция желтая (disp+work желтый).
Посмотрел в Task manager , oracle.exe загрузил процессор на все 50%.

Кто сталкивался с такой ситуацией и когда она закончится ?

Спасибо!

шрам

если грузит oracle.exe, то дело скорее всего не в ядре сапа.

в oracle alert_log чего?

BRILDI

Вот последние строки из лога

Цитата:

Tue Dec 04 09:04:38 2012
Thread 1 advanced to log sequence 2052 (LGWR switch)
Current log# 4 seq# 2052 mem# 0: C:\ORACLE\ET1\ORIGLOGB\LOG_G14M1.DBF
Current log# 4 seq# 2052 mem# 1: C:\ORACLE\ET1\MIRRLOGB\LOG_G14M2.DBF
Tue Dec 04 09:05:13 2012
Completed checkpoint up to RBA [0x803.2.10], SCN: 19544857
Tue Dec 04 09:06:31 2012
Incremental checkpoint up to RBA [0x803.33b2.0], current log tail at RBA [0x804.72ac.0]
Tue Dec 04 09:09:45 2012
Completed checkpoint up to RBA [0x804.2.10], SCN: 19548945
Tue Dec 04 09:10:39 2012
Beginning log switch checkpoint up to RBA [0x805.2.10], SCN: 19552862
Tue Dec 04 09:10:39 2012
Thread 1 advanced to log sequence 2053 (LGWR switch)
Current log# 1 seq# 2053 mem# 0: C:\ORACLE\ET1\ORIGLOGA\LOG_G11M1.DBF
Current log# 1 seq# 2053 mem# 1: C:\ORACLE\ET1\MIRRLOGA\LOG_G11M2.DBF
Tue Dec 04 09:15:45 2012
Completed checkpoint up to RBA [0x805.2.10], SCN: 19552862
Tue Dec 04 09:17:11 2012
Beginning log switch checkpoint up to RBA [0x806.2.10], SCN: 19556804
Tue Dec 04 09:17:11 2012
Thread 1 advanced to log sequence 2054 (LGWR switch)
Current log# 2 seq# 2054 mem# 0: C:\ORACLE\ET1\ORIGLOGB\LOG_G12M1.DBF
Current log# 2 seq# 2054 mem# 1: C:\ORACLE\ET1\MIRRLOGB\LOG_G12M2.DBF
Tue Dec 04 09:22:16 2012
Completed checkpoint up to RBA [0x806.2.10], SCN: 19556804
Tue Dec 04 09:26:38 2012
Beginning log switch checkpoint up to RBA [0x807.2.10], SCN: 19561496
Tue Dec 04 09:26:38 2012
Thread 1 advanced to log sequence 2055 (LGWR switch)
Current log# 3 seq# 2055 mem# 0: C:\ORACLE\ET1\ORIGLOGA\LOG_G13M1.DBF
Current log# 3 seq# 2055 mem# 1: C:\ORACLE\ET1\MIRRLOGA\LOG_G13M2.DBF
Tue Dec 04 09:31:44 2012
Completed checkpoint up to RBA [0x807.2.10], SCN: 19561496
Tue Dec 04 09:35:01 2012
Beginning log switch checkpoint up to RBA [0x808.2.10], SCN: 19567402
Tue Dec 04 09:35:01 2012
Thread 1 advanced to log sequence 2056 (LGWR switch)
Current log# 4 seq# 2056 mem# 0: C:\ORACLE\ET1\ORIGLOGB\LOG_G14M1.DBF
Current log# 4 seq# 2056 mem# 1: C:\ORACLE\ET1\MIRRLOGB\LOG_G14M2.DBF
Tue Dec 04 09:36:47 2012
Incremental checkpoint up to RBA [0x807.f325.0], current log tail at RBA [0x808.6615.0]
Tue Dec 04 09:39:25 2012
Beginning log switch checkpoint up to RBA [0x809.2.10], SCN: 19572387
Tue Dec 04 09:39:25 2012
Thread 1 advanced to log sequence 2057 (LGWR switch)
Current log# 1 seq# 2057 mem# 0: C:\ORACLE\ET1\ORIGLOGA\LOG_G11M1.DBF
Current log# 1 seq# 2057 mem# 1: C:\ORACLE\ET1\MIRRLOGA\LOG_G11M2.DBF
Tue Dec 04 09:40:05 2012
Completed checkpoint up to RBA [0x808.2.10], SCN: 19567402
Tue Dec 04 09:42:12 2012
Beginning log switch checkpoint up to RBA [0x80a.2.10], SCN: 19576506
Tue Dec 04 09:42:12 2012
Thread 1 advanced to log sequence 2058 (LGWR switch)
Current log# 2 seq# 2058 mem# 0: C:\ORACLE\ET1\ORIGLOGB\LOG_G12M1.DBF
Current log# 2 seq# 2058 mem# 1: C:\ORACLE\ET1\MIRRLOGB\LOG_G12M2.DBF

шрам

в этом куске лога криминала нет.

тогда давайте разбираться...

какой тип системы? ABAP, JAVA, ABAP+JAVA?

посмотрите логи в c:\usr\sap\<SID>\<InstanceNN>\work
отсортируйте в этой папке файлы по дате изменения и посмотрите последние из них...
особенно будут интересны файлы с префиксом dev_
dev_w0 - лог первого процесса
dev_ms - message сервер и т.п.

BRILDI

Система ABAP.

dev_w0

Цитата:

A Tue Dec 04 10:05:09 2012
A *GENER* starting remote generation: CX_WDY_RG_MD==================CP (requested by W12).
A *GENER* starting remote generation: CX_WDY_RG=====================CP (requested by W12).
A *GENER* starting remote generation: IF_WDY_MD_LOCKABLE_OBJECT=====IP (requested by W12).
A
A Tue Dec 04 10:05:13 2012
A *GENER* starting remote generation: IF_WDY_MD_COMPONENT===========IP (requested by W12).
A
A Tue Dec 04 10:05:15 2012
A *GENER* starting remote generation: CL_WDY_MD_COMPONENT_INTF_IMPL=CP (requested by W12).
A
A Tue Dec 04 10:05:17 2012
A *GENER* starting remote generation: CL_WDY_MD_COMPONENT_INTERFACE=CP (requested by W12).
A
A Tue Dec 04 10:05:19 2012
A *GENER* starting remote generation: CL_WDY_MD_OBJECT==============CP (requested by W12).
A
A Tue Dec 04 10:05:20 2012
A *GENER* starting remote generation: IF_WDY_RG_CONSTANTS===========IP (requested by W12).
A *GENER* starting remote generation: CL_WDY_MD_OBJECT_MAP==========CP (requested by W12).
A *GENER* starting remote generation: CL_OBJECT_MAP=================CP (requested by W12).
A
A Tue Dec 04 10:05:21 2012
A *GENER* starting remote generation: CL_WDY_MD_CTLR_READER_ENH=====CP (requested by W12).
A
A Tue Dec 04 10:05:22 2012
A *GENER* starting remote generation: CL_WDY_MD_CAST_COLLECTION=====CP (requested by W12).
A
A Tue Dec 04 10:05:24 2012
A *GENER* starting remote generation: SAPLWDY_RG_CODE_TEMPLATES (requested by W12).
A
A Tue Dec 04 10:05:28 2012
A *GENER* starting remote generation: CL_WDY_RG_SOURCE_CODE=========CP (requested by W12).
A
A Tue Dec 04 10:05:31 2012
A *GENER* starting remote generation: CL_OBJECT_MAP_PROXY===========CP (requested by W12).
A *GENER* starting remote generation: CL_WDY_RG_CAST_TOOL===========CP (requested by W12).

BRILDI

dev_w12

Цитата:

A Tue Dec 04 10:07:42 2012
A *GENER* request remote generation: CL_WDY_MD_PLACEHOLDER=========CP.
A
A Tue Dec 04 10:07:45 2012
A *GENER* request remote generation: IF_WDY_MD_WINDOW==============IP.
A
A Tue Dec 04 10:07:47 2012
A *GENER* request remote generation: IF_WDY_MD_VSET_HIERARCHY_NODE=IP.
A
A Tue Dec 04 10:07:49 2012
A *GENER* request remote generation: CL_WDY_MD_VIEW_CNT_ASSIGNMENT=CP.
A
A Tue Dec 04 10:07:53 2012
A *GENER* request remote generation: IF_WDY_MD_PLACEHOLDER=========IP.
A
A Tue Dec 04 10:07:55 2012
A *GENER* request remote generation: IF_WDY_MD_VIEWSET=============IP.
A
A Tue Dec 04 10:07:59 2012
A *GENER* request remote generation: CL_WDY_MD_EMPTY_VIEW==========CP.
A
A Tue Dec 04 10:08:01 2012
A *GENER* request remote generation: IF_WDY_MD_CONTROLLER==========IP.
A
A Tue Dec 04 10:08:06 2012
A *GENER* request remote generation: CL_ABAP_SWITCH================CP.
A
A Tue Dec 04 10:08:07 2012
A *GENER* request remote generation: IF_WDY_MD_VIEW_AREA===========IP.
A
A Tue Dec 04 10:08:20 2012
A *GENER* request remote generation: IF_WDY_MD_VIEW_CNT_ASSIGNMENT=IP.
A
A Tue Dec 04 10:08:25 2012
A *GENER* request remote generation: CL_WDY_MD_NAVIGATION_LINKS====CP.
A
A Tue Dec 04 10:08:28 2012
A *GENER* request remote generation: CL_WDY_MD_COMPONENT_USAGE=====CP.

шрам

опять таки - ничего страшного в логах нет.
идет генерация основных программ, поэтому и занят оракл и остальные части.
это процесс одноразовый в пределах одной смены ядра.

А между тем, Вы не ответили на вопрос про тип системы.
это поможет более точно дать рекомендацию - куда посмотреть.

если у вас таки DUAL STACK, то DVEBMGS будет желтым довольно долго (в некоторых случаях)

чего есть в файлах:
dev_cp
dev_ms
dev_disp
dev_rd
?

посмотреть что творится в рабочих процессах можно либо через mmc (http://<hostname>:5nn13) где nn - номер системы.
либо через программу dpmon, которая должна лежать в ядре сапа.

сдается мне, у Вас SGEN там шурует во всю...

BRILDI

Сории, я не проигнорировал, я ответил - тип системы ABAP . (без JAVA) :oops:

По поводу DUAL STACK, я в этом не разбираюсь :oops:

Здесь http://<hostname>:5nn13 открылась такая же консоль как и в самом сервере Windows.
И показывает почему-то зеленую инстанцию.
А на самом сервере в Windows консоль не отвечает...забита процессом Оракла.

Вероятно, все-таки, этот надо дождаться конца генерации . Наверно это нормальный процесс.
Почему только нигде об этом не пишут, что при смене файлов ядра происходит перегенерация программ.

dev_cp - нет в этой папке.

dev_ms

Цитата:

---------------------------------------------------
trc file: "dev_ms", trc level: 1, release: "701"
---------------------------------------------------

[Thr 580] Mon Dec 03 21:21:07 2012
[Thr 580] MsSSetTrcLog: trc logging active, max size = 20971520 bytes
systemid 562 (PC with Windows NT)
relno 7010
patchlevel 0
patchno 183
intno 20020600
make: multithreaded, Unicode, 64 bit, optimized
pid 588

[Thr 580] ***LOG Q01=> MsSInit, MSStart (Msg Server 1 588) [msxxserv.c 1853]

[Thr 580] Mon Dec 03 21:21:09 2012
[Thr 580] load acl file = C:\usr\sap\ET1\SYS\global\ms_acl_info.DAT
[Thr 580] MsGetOwnIpAddr: my host addresses are :
[Thr 580] 1 : [192.168.0.124] et1 (HOSTNAME)
[Thr 580] 2 : [127.0.0.1] et1 (LOCALHOST)
[Thr 580] MsHttpInit: full qualified hostname = et1
[Thr 580] HTTP logging is switch off
[Thr 580] set HTTP state to LISTEN
[Thr 580] ms/icf_info_server : deleted
[Thr 580] *** I listen to port sapmsET1 (3604) ***
[Thr 580] *** I listen to internal port 3904 (3904) ***
[Thr 580] *** HTTP port 8100 state LISTEN ***
[Thr 580] CUSTOMER KEY: >L1492063298<
[Thr 580] build version=701.2009.12.02

[Thr 580] Mon Dec 03 21:22:19 2012
[Thr 580] MsHttpReadUrl2: H-1 url = /sap/public/icf_info/urlprefix (403 Forbidden)
[Thr 580] MsHttpReadUrl2: H-1 url = /sap/public/icf_info/logon_groups (403 Forbidden)

dev_disp

Цитата:

Mon Dec 03 21:21:19 2012
rdisp/http_min_wait_dia_wp : 1 -> 1
***LOG CPS=> DpLoopInit, ICU ( 3.0 3.0 4.0.1) [dpxxdisp.c 1759]
***LOG Q0K=> DpMsAttach, mscon ( et1) [dpxxdisp.c 12953]
DpStartStopMsg: send start message (myname is >et1_ET1_00 <)
DpStartStopMsg: start msg sent

Mon Dec 03 21:21:21 2012
CCMS: Initalized shared memory of size 60000000 for monitoring segment.
CCMS: Checking Downtime Configuration of Monitoring Segment.

Mon Dec 03 21:21:22 2012
CCMS: start to initalize 3.X shared alert area (first segment).
DpMsgAdmin: Set release to 7010, patchlevel 0
MBUF state PREPARED
MBUF component UP
DpMBufHwIdSet: set Hardware-ID
***LOG Q1C=> DpMBufHwIdSet [dpxxmbuf.c 1048]
DpMsgAdmin: Set patchno for this platform to 196
Release check o.K.

Mon Dec 03 21:21:50 2012
MBUF state ACTIVE
DpModState: change server state from STARTING to ACTIVE

Mon Dec 03 21:21:53 2012
*** WARNING => DpRqServiceQueue: timeout of HIGH PRIO msg, return DP_CANT_HANDLE_REQ

Mon Dec 03 21:21:54 2012
*** WARNING => DpRqServiceQueue: timeout of HIGH PRIO msg, return DP_CANT_HANDLE_REQ
*** WARNING => DpRqServiceQueue: timeout of HIGH PRIO msg, return DP_CANT_HANDLE_REQ

Mon Dec 03 21:21:55 2012
*** WARNING => DpRqServiceQueue: timeout of HIGH PRIO msg, return DP_CANT_HANDLE_REQ

Mon Dec 03 21:21:56 2012
*** WARNING => DpRqServiceQueue: timeout of HIGH PRIO msg, return DP_CANT_HANDLE_REQ

dev_rd

Цитата:

---------------------------------------------------
trc file: "dev_rd", trc level: 1, release: "701"
---------------------------------------------------

Mon Dec 03 21:21:21 2012
***LOG S00=> GwInitReader, gateway started ( 2260) [gwxxrd.c 1759]
systemid 562 (PC with Windows NT)
relno 7010
patchlevel 0
patchno 194
intno 20020600
make: multithreaded, Unicode, 64 bit, optimized
pid 2260

gateway runs with dp version 246000(ext=110000) (@(#) DPLIB-INT-VERSION-246000-UC)
gateway (version=701.2012.07.24)
gw/reg_no_conn_info = 0
gw/local_addr : 0.0.0.0
* SWITCH TRC-RESOLUTION from 1 TO 1

Mon Dec 03 21:21:22 2012
Bind service sapgw00 (socket) to port 3300
GwIRegInitRegInfo: reg_info file C:\usr\sap\ET1\DVEBMGS00\data\reginfo.DAT not found
GwPrintMyHostAddr: my host addresses are :
1 : [192.168.0.124] et1 (HOSTNAME)
2 : [127.0.0.1] et1 (LOCALHOST)
Full qualified hostname = et1
DpSysAdmExtCreate: ABAP is active
DpSysAdmExtCreate: VMC (JAVA VM in WP) is not active
DpShMCreate: sizeof(wp_adm) 29792 (1752)
DpShMCreate: sizeof(tm_adm) 5912704 (29416)
DpShMCreate: sizeof(wp_ca_adm) 24064 (80)
DpShMCreate: sizeof(appc_ca_adm) 8000 (80)
DpCommTableSize: max/headSize/ftSize/tableSize=500/16/552064/552080
DpShMCreate: sizeof(comm_adm) 552080 (1088)
DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0
DpShMCreate: sizeof(slock_adm) 0 (104)
DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0
DpShMCreate: sizeof(file_adm) 0 (72)
DpShMCreate: sizeof(vmc_adm) 0 (1864)
DpShMCreate: sizeof(wall_adm) (41664/36752/64/192)
DpShMCreate: sizeof(gw_adm) 48
DpShMCreate: SHM_DP_ADM_KEY (addr: 000000000ED80050, size: 6614288)
DpShMCreate: allocated sys_adm at 000000000ED80050
DpShMCreate: allocated wp_adm at 000000000ED822A0
DpShMCreate: allocated tm_adm_list at 000000000ED89700
DpShMCreate: allocated tm_adm at 000000000ED89760
DpShMCreate: allocated appc_ca_adm at 000000000F332DE0
DpShMCreate: allocated comm_adm at 000000000F334D20
DpShMCreate: system runs without slock table
DpShMCreate: system runs without file table
DpShMCreate: allocated vmc_adm_list at 000000000F3BB9B0
DpShMCreate: allocated gw_adm at 000000000F3BBA30
DpShMCreate: system runs without vmc_adm
DpShMCreate: allocated ca_info at 000000000F3BBA60

Mon Dec 03 21:21:23 2012
MtxInit: -2 0 0
DpRqQInit: Parameter rdisp/queue_lock_level = 2

Mon Dec 03 21:21:44 2012
GwDpInit: attached to gw_adm at 000000000F3BBA30

шрам

пардоньте, видимо проскочил про ABAP во время чтения :oops:

ну собственно, вывод примерно таков - система жива и работает, однако сервак прогнут по производительности.... ждите, когда все устаканится.

BRILDI

Большое спасибо!!! :pivo:

adem

А в саму систему зайти-хоть можно? Окно с запросом логина-пароля появляется?

Правила форума

Долго запускается инстанция после обновления KERNEL 701

Кто сейчас на конференции