cancel
Showing results for 
Search instead for 
Did you mean: 

Problem Found(Dispatcher Failed & Stopped)

Former Member
0 Kudos

Hi,

This is to inform all, I am getting problem while i am trying to start SAP MMC from Windows server 2003 64bit. Problem is that Dispatcher failed & stopped after a running few mint. Following action, I have checked & done at my site but no improvement.

1)Kernel Update.

2)All services are working.

3)Sap share folder is showing well.

4)Network & telnet port 3900 is able to connect.

5)Ping is getting.

Following Error Logs:

1)R3trans -d (It's showing 2EETW169 NO CONNECT POSSIBLE: ''DBMS = ORACLE   -- dbs ra_tnsname =ECC" . R3trans finished(0012)

2)Dev_W0_Log.

---------------------------------------------------

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

---------------------------------------------------

*

*  ACTIVE TRACE LEVEL           1

*  ACTIVE TRACE COMPONENTS      all, MJ

*

B Thu Jun 13 06:25:00 2013

B  create_con (con_name=R/3)

B  Loading DB library 'D:\usr\sap\ECC\DVEBMGS00\exe\dboraslib.dll' ...

B  Library 'D:\usr\sap\ECC\DVEBMGS00\exe\dboraslib.dll' loaded

B  Version of 'D:\usr\sap\ECC\DVEBMGS00\exe\dboraslib.dll' is "700.08", patchlevel (0.107)

B  New connection 0 created

M sysno      00

M sid        ECC

M systemid   560 (PC with Windows NT)

M relno      7000

M patchlevel 0

M patchno    111

M intno      20050900

M make:      multithreaded, Unicode, optimized

M pid        3264

M

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

M  length of sys_adm_ext is 576 bytes

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

I  MtxInit: 30000 0 0

M  DpSysAdmExtCreate: ABAP is active

M  DpSysAdmExtCreate: VMC (JAVA VM in WP) is not active

M Thu Jun 13 06:25:01 2013

M  DpShMCreate: sizeof(wp_adm)                    21544          (1436)

M  DpShMCreate: sizeof(tm_adm)                    4232256          (21056)

M  DpShMCreate: sizeof(wp_ca_adm)                    24000          (80)

M  DpShMCreate: sizeof(appc_ca_adm)          8000          (80)

M  DpCommTableSize: max/headSize/ftSize/tableSize=500/8/528056/528064

M  DpShMCreate: sizeof(comm_adm)                    528064          (1048)

M  DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0

M  DpShMCreate: sizeof(slock_adm)                    0          (96)

M  DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0

M  DpShMCreate: sizeof(file_adm)                    0          (72)

M  DpShMCreate: sizeof(vmc_adm)                    0          (1536)

M  DpShMCreate: sizeof(wall_adm)                    (38456/34360/64/184)

M  DpShMCreate: sizeof(gw_adm)          48

M  DpShMCreate: SHM_DP_ADM_KEY                    (addr: 06050040, size: 4895184)

M  DpShMCreate: allocated sys_adm at 06050040

M  DpShMCreate: allocated wp_adm at 06052090

M  DpShMCreate: allocated tm_adm_list at 060574B8

M  DpShMCreate: allocated tm_adm at 060574E8

M  DpShMCreate: allocated wp_ca_adm at 06460928

M  DpShMCreate: allocated appc_ca_adm at 064666E8

M  DpShMCreate: allocated comm_adm at 06468628

M  DpShMCreate: system runs without slock table

M  DpShMCreate: system runs without file table

M  DpShMCreate: allocated vmc_adm_list at 064E94E8

M  DpShMCreate: allocated gw_adm at 064E9528

M  DpShMCreate: system runs without vmc_adm

M  DpShMCreate: allocated ca_info at 064E9558

M  DpShMCreate: allocated wall_adm at 064E9560

X  EmInit: MmSetImplementation( 2 ).

X  MM global diagnostic options set: 0

X  <ES> client 0 initializing ....

X  Using implementation view

X  <EsNT> Using memory model view.

M  <EsNT> Memory Reset disabled as NT default

X  ES initialized.

M  ThInit: running on host gcecc62

M Thu Jun 13 06:25:02 2013

M  calling db_connect ...

C  Prepending D:\usr\sap\ECC\DVEBMGS00\exe to Path.

C  Oracle Client Version: '10.2.0.2.0'

C  Client NLS settings: AMERICAN_AMERICA.UTF8

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

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

C  Nls CharacterSet                 NationalCharSet              C      EnvHp      ErrHp ErrHpBatch

C    0 UTF8                                                      1   066514C8   06656B64   066563EC

C  Attaching to DB Server ECC (con_hdl=0,svchp=06656338,srvhp=066663E4)

C Thu Jun 13 06:25:03 2013

C  Starting user session (con_hdl=0,svchp=06656338,srvhp=066663E4,usrhp=066789E8)

C  *** ERROR => OCI-call 'OCISessionBegin' failed with rc=1033

[dboci.c      4532]

C  Detaching from DB Server (con_hdl=0,svchp=06656338,srvhp=066663E4)

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

[dbsloci.c    11042]

C  Try to connect with default password

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

C  Nls CharacterSet                 NationalCharSet              C      EnvHp      ErrHp ErrHpBatch

C    0 UTF8                                                      1   066514C8   06656B64   066563EC

C  Attaching to DB Server ECC (con_hdl=0,svchp=06656338,srvhp=066663E4)

C  Starting user session (con_hdl=0,svchp=06656338,srvhp=066663E4,usrhp=066789E8)

C  *** ERROR => OCI-call 'OCISessionBegin' failed with rc=1033

[dboci.c      4532]

C  Detaching from DB Server (con_hdl=0,svchp=06656338,srvhp=066663E4)

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

[dbsloci.c    11042]

B  ***LOG BV3=> severe db error 1033      ; work process is stopped [dbsh#2 @ 1199] [dbsh    1199 ]

B  ***LOG BY2=> sql error 1033   performing CON [dblink#3 @ 431] [dblink  0431 ]

B  ***LOG BY0=> ORA-01033: ORACLE initialization or shutdown in progress [dblink#3 @ 431] [dblink  0431 ]

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

M  in_ThErrHandle: 1

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

M  Info for wp 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 = -1

M    mode = 255

M    len = -1

M    rq_id = 65535

M Thu Jun 13 06:25:04 2013

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    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    req.vm = no VM

M    attachedVm = no VM

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

M  *

M  *  LOCATION    SAP-Server gcecc62_ECC_00 on host gcecc62 (wp 0)

M  *  ERROR       ThInit: db_connect

M  *

M  *  TIME        Thu Jun 13 06:25:04 2013

M  *  RELEASE     700

M  *  COMPONENT   Taskhandler

M  *  VERSION     1

M  *  RC          13

M  *  MODULE      thxxhead.c

M  *  LINE        10486

M  *  COUNTER     1

M  *

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

M  PfStatDisconnect: disconnect statistics

M  Entering TH_CALLHOOKS

M  ThCallHooks: call hook >ThrSaveSPAFields< for event BEFORE_DUMP

M  *** ERROR => ThrSaveSPAFields: no valid thr_wpadm [thxxrun1.c   720]

M  *** ERROR => ThCallHooks: event handler ThrSaveSPAFields for event BEFORE_DUMP failed [thxxtool3.c  260]

M  Entering ThSetStatError

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

M  Entering ThReadDetachMode

M  call ThrShutDown (1)...

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

3)Dev_Disp log.

---------------------------------------------------

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

---------------------------------------------------

sysno      00

sid        ECC

systemid   560 (PC with Windows NT)

relno      7000

patchlevel 0

patchno    111

intno      20050900

make:      multithreaded, Unicode, optimized

pid        2188

Thu Jun 13 06:24:55 2013

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

length of sys_adm_ext is 576 bytes

*** SWITCH TRC-HIDE on ***

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

          shared lib "dw_xml.dll" version 111 successfully loaded

          shared lib "dw_xtc.dll" version 111 successfully loaded

          shared lib "dw_stl.dll" version 111 successfully loaded

          shared lib "dw_gui.dll" version 111 successfully loaded

          shared lib "dw_mdm.dll" version 111 successfully loaded

rdisp/softcancel_sequence :  -> 0,5,-1

use internal message server connection to port 3900

Thu Jun 13 06:24:59 2013

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

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

MtxInit: 30000 0 0

DpSysAdmExtInit: ABAP is active

DpSysAdmExtInit: VMC (JAVA VM in WP) is not active

DpIPCInit2: start server >gcecc62_ECC_00                          <

DpShMCreate: sizeof(wp_adm)                    21544          (1436)

DpShMCreate: sizeof(tm_adm)                    4232256          (21056)

DpShMCreate: sizeof(wp_ca_adm)                    24000          (80)

DpShMCreate: sizeof(appc_ca_adm)          8000          (80)

DpCommTableSize: max/headSize/ftSize/tableSize=500/8/528056/528064

DpShMCreate: sizeof(comm_adm)                    528064          (1048)

DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0

DpShMCreate: sizeof(slock_adm)                    0          (96)

DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0

DpShMCreate: sizeof(file_adm)                    0          (72)

DpShMCreate: sizeof(vmc_adm)                    0          (1536)

DpShMCreate: sizeof(wall_adm)                    (38456/34360/64/184)

DpShMCreate: sizeof(gw_adm)          48

DpShMCreate: SHM_DP_ADM_KEY                    (addr: 06050040, size: 4895184)

DpShMCreate: allocated sys_adm at 06050040

DpShMCreate: allocated wp_adm at 06052090

DpShMCreate: allocated tm_adm_list at 060574B8

DpShMCreate: allocated tm_adm at 060574E8

DpShMCreate: allocated wp_ca_adm at 06460928

DpShMCreate: allocated appc_ca_adm at 064666E8

DpShMCreate: allocated comm_adm at 06468628

DpShMCreate: system runs without slock table

DpShMCreate: system runs without file table

DpShMCreate: allocated vmc_adm_list at 064E94E8

DpShMCreate: allocated gw_adm at 064E9528

DpShMCreate: system runs without vmc_adm

DpShMCreate: allocated ca_info at 064E9558

DpShMCreate: allocated wall_adm at 064E9560

MBUF state OFF

DpCommInitTable: init table for 500 entries

Thu Jun 13 06:25:00 2013

EmInit: MmSetImplementation( 2 ).

MM global diagnostic options set: 0

<ES> client 0 initializing ....

<ES> InitFreeList

<ES> block size is 1024 kByte.

Using implementation view

<EsNT> Using memory model view.

<EsNT> Memory Reset disabled as NT default

<ES> 511 blocks reserved for free list.

ES initialized.

rdisp/http_min_wait_dia_wp : 1 -> 1

***LOG CPS=> DpLoopInit, ICU ( 3.0 3.0 4.0.1) [dpxxdisp.c   1629]

Thu Jun 13 06:25:01 2013

***LOG Q0K=> DpMsAttach, mscon ( gcecc62) [dpxxdisp.c   11753]

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

DpStartStopMsg: start msg sent

CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

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

DpMsgAdmin: Set release to 7000, patchlevel 0

MBUF state PREPARED

MBUF component UP

DpMBufHwIdSet: set Hardware-ID

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

DpMsgAdmin: Set patchno for this platform to 111

Release check o.K.

Thu Jun 13 06:25:41 2013

*** ERROR => W0 (pid 3264) died [dpxxdisp.c   14441]

*** ERROR => W1 (pid 3272) died [dpxxdisp.c   14441]

*** ERROR => W2 (pid 3280) died [dpxxdisp.c   14441]

*** ERROR => W3 (pid 772) died [dpxxdisp.c   14441]

*** ERROR => W4 (pid 3308) died [dpxxdisp.c   14441]

*** ERROR => W5 (pid 3316) died [dpxxdisp.c   14441]

*** ERROR => W6 (pid 1456) died [dpxxdisp.c   14441]

*** ERROR => W7 (pid 3320) died [dpxxdisp.c   14441]

my types changed after wp death/restart 0xbf --> 0xbe

*** ERROR => W8 (pid 1948) died [dpxxdisp.c   14441]

my types changed after wp death/restart 0xbe --> 0xbc

*** ERROR => W9 (pid 3332) died [dpxxdisp.c   14441]

my types changed after wp death/restart 0xbc --> 0xb8

*** ERROR => W10 (pid 3340) died [dpxxdisp.c   14441]

*** ERROR => W11 (pid 3348) died [dpxxdisp.c   14441]

*** ERROR => W12 (pid 3368) died [dpxxdisp.c   14441]

my types changed after wp death/restart 0xb8 --> 0xb0

*** ERROR => W13 (pid 3204) died [dpxxdisp.c   14441]

my types changed after wp death/restart 0xb0 --> 0xa0

*** ERROR => W14 (pid 3460) died [dpxxdisp.c   14441]

my types changed after wp death/restart 0xa0 --> 0x80

*** DP_FATAL_ERROR => DpWPCheck: no more work processes

*** DISPATCHER EMERGENCY SHUTDOWN ***

increase tracelevel of WPs

NiWait: sleep (10000ms) ...

NiISelect: timeout 10000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:25:51 2013

NiISelect: TIMEOUT occured (10000ms)

dump system status

Workprocess Table (long)                              Thu Jun 13 00:55:51 2013

========================

No Ty. Pid      Status  Cause Start Err Sem CPU    Time  Program          Cl  User         Action                    Table

-------------------------------------------------------------------------------------------------------------------------------

0 DIA     3264 Ended         no      1   0        0                                                                         

1 DIA     3272 Ended         no      1   0        0                                                                         

2 DIA     3280 Ended         no      1   0        0                                                                         

3 DIA      772 Ended         no      1   0        0                                                                         

4 DIA     3308 Ended         no      1   0        0                                                                         

5 DIA     3316 Ended         no      1   0        0                                                                         

6 DIA     1456 Ended         no      1   0        0                                                                         

7 DIA     3320 Ended         no      1   0        0                                                                         

8 UPD     1948 Ended         no      1   0        0                                                                         

9 ENQ     3332 Ended         no      1   0        0                                                                         

10 BTC     3340 Ended         no      1   0        0                                                                         

11 BTC     3348 Ended         no      1   0        0                                                                         

12 BTC     3368 Ended         no      1   0        0                                                                         

13 SPO     3204 Ended         no      1   0        0                                                                         

14 UP2     3460 Ended         no      1   0        0                                                                         

Dispatcher Queue Statistics                              Thu Jun 13 00:55:51 2013

===========================

+------+--------+--------+--------+--------+--------+

|  Typ |    now |   high |    max | writes |  reads |

+------+--------+--------+--------+--------+--------+

| NOWP |      0 |      2 |   2000 |      6 |      6 |

+------+--------+--------+--------+--------+--------+

|  DIA |      5 |      5 |   2000 |      5 |      0 |

+------+--------+--------+--------+--------+--------+

|  UPD |      0 |      0 |   2000 |      0 |      0 |

+------+--------+--------+--------+--------+--------+

|  ENQ |      0 |      0 |   2000 |      0 |      0 |

+------+--------+--------+--------+--------+--------+

|  BTC |      0 |      0 |   2000 |      0 |      0 |

+------+--------+--------+--------+--------+--------+

|  SPO |      0 |      0 |   2000 |      0 |      0 |

+------+--------+--------+--------+--------+--------+

|  UP2 |      0 |      0 |   2000 |      0 |      0 |

+------+--------+--------+--------+--------+--------+

max_rq_id                    12

wake_evt_udp_now          0

wake events                total     8,  udp     7 ( 87%),  shm     1 ( 12%)

since last update          total     8,  udp     7 ( 87%),  shm     1 ( 12%)

Dump of tm_adm structure:                              Thu Jun 13 00:55:51 2013

=========================

Term    uid  man user    term   lastop  mod wp  ta   a/i (modes)

Workprocess Comm. Area Blocks                              Thu Jun 13 00:55:51 2013

=============================

Slots: 300, Used: 1, Max: 0

+------+--------------+----------+-------------+

|   id | owner        |   pid    | eyecatcher  |

+------+--------------+----------+-------------+

|    0 | DISPATCHER   |       -1 | *WPCAAD000* |

NiWait: sleep (5000ms) ...

NiISelect: timeout 5000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:25:56 2013

NiISelect: TIMEOUT occured (5000ms)

DpHalt: shutdown server >gcecc62_ECC_00                          < (normal)

DpJ2eeDisableRestart

DpModState: buffer in state MBUF_PREPARED

NiBufSend starting

NiIWrite: hdl 2 sent data (wrt=110,pac=1,MESG_IO)

MsINiWrite: sent 110 bytes

MsIModState: change state to SHUTDOWN

DpModState: change server state from STARTING to SHUTDOWN

Switch off Shared memory profiling

ShmProtect( 57, 3 )

ShmProtect(SHM_PROFILE, SHM_PROT_RW

ShmProtect( 57, 1 )

ShmProtect(SHM_PROFILE, SHM_PROT_RD

DpWakeUpWps: wake up all wp's

Stop work processes

Stop gateway

killing process (3736) (SOFT_KILL)

Stop icman

killing process (3256) (SOFT_KILL)

Terminate gui connections

wait for end of work processes

wait for end of gateway

[DpProcDied] Process lives  (PID:3736  HANDLE:1588)

waiting for termination of gateway ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:25:57 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process died  (PID:3736  HANDLE:1588)

wait for end of icman

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:25:58 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:25:59 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:00 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:01 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:02 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:03 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:04 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:05 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:06 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:07 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:3256  HANDLE:1592)

waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1617

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Thu Jun 13 06:26:08 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process died  (PID:3256  HANDLE:1592)

DpStartStopMsg: send stop message (myname is >gcecc62_ECC_00                          <)

NiIMyHostName: hostname = 'gcecc62'

AdGetSelfIdentRecord: >                                                                           <

AdCvtRecToExt: opcode 60 (AD_SELFIDENT), ser 0, ex 0, errno 0

AdCvtRecToExt: opcode 4 (AD_STARTSTOP), ser 0, ex 0, errno 0

DpConvertRequest: net size = 189 bytes

NiBufSend starting

NiIWrite: hdl 2 sent data (wrt=562,pac=1,MESG_IO)

MsINiWrite: sent 562 bytes

send msg (len 110+452) to name                    -, type 4, key -

DpStartStopMsg: stop msg sent

NiIRead: hdl 2 received data (rcd=274,pac=1,MESG_IO)

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 274 bytes

MSG received, len 110+164, flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

NiIRead: hdl 2 received data (rcd=274,pac=1,MESG_IO)

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 274 bytes

MSG received, len 110+164, flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

NiIRead: hdl 2 received data (rcd=274,pac=1,MESG_IO)

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 274 bytes

MSG received, len 110+164, flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

NiIRead: hdl 2 received data (rcd=274,pac=1,MESG_IO)

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 274 bytes

MSG received, len 110+164, flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

NiIRead: hdl 2 received data (rcd=274,pac=1,MESG_IO)

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 274 bytes

MSG received, len 110+164, flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

NiIRead: hdl 2 received data (rcd=274,pac=1,MESG_IO)

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 274 bytes

MSG received, len 110+164, flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

NiIRead: hdl 2 received data (rcd=274,pac=1,MESG_IO)

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 274 bytes

MSG received, len 110+164, flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

NiIRead: hdl 2 recv would block (errno=EAGAIN)

NiIRead: read for hdl 2 timed out (0ms)

DpHalt: no more messages from the message server

DpHalt: send keepalive to synchronize with the message server

NiBufSend starting

NiIWrite: hdl 2 sent data (wrt=114,pac=1,MESG_IO)

MsINiWrite: sent 114 bytes

send msg (len 110+4) to name           MSG_SERVER, type 0, key -

MsSndName: MS_NOOP ok

Send 4 bytes to MSG_SERVER

NiIRead: hdl 2 recv would block (errno=EAGAIN)

NiIPeek: peek successful for hdl 2 (r)

NiIRead: hdl 2 received data (rcd=114,pac=1,MESG_IO)

NiBufIIn: NIBUF len=114

NiBufIIn: packet complete for hdl 2

NiBufReceive starting

MsINiRead: received 114 bytes

MSG received, len 110+4, flag 3, from MSG_SERVER          , typ 0, key -

Received 4 bytes from MSG_SERVER                             

Received opcode MS_NOOP from msg_server, reply MSOP_OK

MsOpReceive: ok

MsSendKeepalive : keepalive sent to message server

NiIRead: hdl 2 recv would block (errno=EAGAIN)

Thu Jun 13 06:26:09 2013

NiIPeek: peek for hdl 2 timed out (r; 1000ms)

NiIRead: read for hdl 2 timed out (1000ms)

DpHalt: no more messages from the message server

DpHalt: sync with message server o.k.

detach from message server

***LOG Q0M=> DpMsDetach, ms_detach () [dpxxdisp.c   12099]

NiBufSend starting

NiIWrite: hdl 2 sent data (wrt=110,pac=1,MESG_IO)

MsINiWrite: sent 110 bytes

MsIDetach: send logout to msg_server

MsIDetach: call exit function

DpMsShutdownHook called

NiBufISelUpdate: new MODE -- (r-) for hdl 2 in set0

SiSelNSet: set events of sock 1520 to: ---

NiBufISelRemove: remove hdl 2 from set0

SiSelNRemove: removed sock 1520 (pos=2)

SiSelNRemove: removed sock 1520

NiSelIRemove: removed hdl 2

MBUF state OFF

AdGetSelfIdentRecord: >                                                                           <

AdCvtRecToExt: opcode 60 (AD_SELFIDENT), ser 0, ex 0, errno 0

AdCvtRecToExt: opcode 40 (AD_MSBUF), ser 0, ex 0, errno 0

AdCvtRecToExt: opcode 40 (AD_MSBUF), ser 0, ex 0, errno 0

blks_in_queue/wp_ca_blk_no/wp_max_no = 1/300/15

LOCK WP ca_blk 1

make DISP owner of wp_ca_blk 1

DpRqPutIntoQueue: put request into queue (reqtype 1, prio LOW, rq_id 15)

MBUF component DOWN

NiICloseHandle: shutdown and close hdl 2 / sock 1520

NiBufIClose: clear extension for hdl 2

MsIDetach: detach MS-system

cleanup EM

EsCleanup ....

EmCleanup() -> 0

Es2Cleanup: Cleanup ES2

***LOG Q05=> DpHalt, DPStop ( 2188) [dpxxdisp.c   10371]

Good Bye .....

Accepted Solutions (0)

Answers (4)

Answers (4)

Former Member
0 Kudos

Hi Abhijit,

1. Start db and SAP instance separately.

2. Check your database status, if it is open or not.

3. Check your listener service.

4. Check your OPS$ and SAPSR3 user status.

5. Check your SYSLOG for any other error log.

Regards

Shanthosh

Former Member
0 Kudos

Hi,

Thanks to all. Now problem is resolved.

Abhijit

0 Kudos

Hi Abhijit,

Please check oss note  655240

regards,

sridhar

Reagan
Advisor
Advisor
0 Kudos

Hello

Do you have the Oracle Instant Client present in the DIR_CT_RUN directory ?

Check whether it is present or not else follow the below note and update it.

Note 998004 - Update the Oracle Instant Client on Windows

Once done execute R3trans and provide the trans.log file

Good Luck

RB

Former Member
0 Kudos

Hi,

While i used R3Trans -d command & got error. (2eetw169 no connect possible "dbms = oracle   ---DBS_ORA_TNSNAME ='ECC' "

r3trans finished (0012 )

Abhijit.

Former Member
0 Kudos

Hi,

I have mentioned below Trans.log here. Plz find it & give me what shall i do next.

4 ETW000 R3Trans version 6.14 (release 700 - 15.06.07 - 15:50:00).

4 ETW000 unicode enabled version

4 ETW000 ===============================================

4 ETW000

4 ETW000 date&time   : 15.06.2013 - 11:30:42

4 ETW000 control file: <no ctrlfile>

4 ETW000 R3trans was called as follows: R3Trans -d

4 ETW000  trace at level 2 opened for a given file pointer

4 ETW000  [dev trc     ,00000]  Sat Jun 15 11:30:45 2013                               0  0.000000

4 ETW000  [dev trc     ,00000]  db_con_init called                                     0  0.000000

4 ETW000  [dev trc     ,00000]  create_con (con_name=R/3)                              0  0.000000

4 ETW000  [dev trc     ,00000]  Loading DB library 'dboraslib.dll' ...                 0  0.000000

4 ETW000  [dev trc     ,00000]  load shared library (dboraslib.dll), hdl 0         50786  0.050786

4 ETW000  [dev trc     ,00000]      using "D:\usr\sap\ECC\SYS\exe\uc\NTI386\dboraslib.dll"

4 ETW000                                                                              66  0.050852

4 ETW000  [dev trc     ,00000]  Library 'dboraslib.dll' loaded                        29  0.050881

4 ETW000  [dev trc     ,00000]  function DbSlExpFuns loaded from library dboraslib.dll

4 ETW000                                                                              53  0.050934

4 ETW000  [dev trc     ,00000]  Version of 'dboraslib.dll' is "700.08", patchlevel (0.107)

4 ETW000                                                                           16694  0.067628

4 ETW000  [dev trc     ,00000]  function dsql_db_init loaded from library dboraslib.dll

4 ETW000                                                                              50  0.067678

4 ETW000  [dev trc     ,00000]  function dbdd_exp_funs loaded from library dboraslib.dll

4 ETW000                                                                              59  0.067737

4 ETW000  [dev trc     ,00000]  New connection 0 created                              43  0.067780

4 ETW000  [dev trc     ,00000]  0: name = R/3, con_id = -000000001 state = DISCONNECTED, perm = YES, reco = NO , timeout = 000, con_max = 255, con_opt = 255, occ = NO

4 ETW000                                                                              53  0.067833

4 ETW000  [dev trc     ,00000]  db_con_connect (con_name=R/3)                         31  0.067864

4 ETW000  [dev trc     ,00000]  find_con_by_name found the following connection for reuse:

4 ETW000                                                                              43  0.067907

4 ETW000  [dev trc     ,00000]  0: name = R/3, con_id = 000000000 state = DISCONNECTED, perm = YES, reco = NO , timeout = 000, con_max = 255, con_opt = 255, occ = NO

4 ETW000                                                                              50  0.067957

4 ETW000  [dev trc     ,00000]  CLIENT_ORACLE_HOME is not set as environment variable or

4 ETW000                        DIR_CLIENT_ORAHOME is not set as profile parameter.

4 ETW000                          assuming using instant client with unspecified location.

4 ETW000                                                                            2019  0.069976

4 ETW000  [dev trc     ,00000]  Oracle Client Version: '10.2.0.2.0'                66035  0.136011

4 ETW000  [dev trc     ,00000]  -->oci_initialize (con_hdl=0)                         50  0.136061

4 ETW000  [dev trc     ,00000]  Client NLS settings: AMERICAN_AMERICA.UTF8          2621  0.138682

4 ETW000  [dev trc     ,00000]  Logon as OPS$-user to get SAPSR3's password           53  0.138735

4 ETW000  [dev trc     ,00000]  Connecting as /@ECC on connection 0 (nls_hdl 0) ... (dbsl 700 250407)

4 ETW000                                                                              53  0.138788

4 ETW000  [dev trc     ,00000]  Nls CharacterSet                 NationalCharSet              C      EnvHp      ErrHp ErrHpBatch

4 ETW000                                                                             323  0.139111

4 ETW000  [dev trc     ,00000]    0 UTF8                                                      1   0258E110   02593744   02592FCC

4 ETW000                                                                             107  0.139218

4 ETW000  [dev trc     ,00000]  Allocating service context handle for con_hdl=0      390  0.139608

4 ETW000  [dev trc     ,00000]  Allocating server context handle                     219  0.139827

4 ETW000  [dev trc     ,00000]  Attaching to DB Server ECC (con_hdl=0,svchp=02592F18,srvhp=025A478C)

4 ETW000                                                                              90  0.139917

4 ETW000  [dev trc     ,00000]  Assigning server context 025A478C to service context 02592F18

4 ETW000                                                                           21691  0.161608

4 ETW000  [dev trc     ,00000]  Allocating user session handle                        47  0.161655

4 ETW000  [dev trc     ,00000]  Starting user session (con_hdl=0,svchp=02592F18,srvhp=025A478C,usrhp=025DFC38)

4 ETW000                                                                              68  0.161723

4 ETW000  [dboci.c     ,00000]  *** ERROR => OCI-call 'OCISessionBegin' failed with rc=1033

4 ETW000                                                                           38875  0.200598

4 ETW000  [dev trc     ,00000]  server_detach(con_hdl=0,stale=1,svrhp=025A478C)      165  0.200763

4 ETW000  [dev trc     ,00000]  Detaching from DB Server (con_hdl=0,svchp=02592F18,srvhp=025A478C)

4 ETW000                                                                              52  0.200815

4 ETW000  [dev trc     ,00000]  Deallocating server context handle 025A478C          564  0.201379

4 ETW000  [dbsloci.    ,00000]  *** ERROR => CONNECT failed with sql error '1033'

4 ETW000                                                                              73  0.201452

4 ETW000  [dev trc     ,00000]     set_ocica() -> OCI or SQL return code 1033         34  0.201486

4 ETW000  [dev trc     ,00000]  Try to connect with default password                  83  0.201569

4 ETW000  [dev trc     ,00000]  Connecting as SAPSR3/<pwd>@ECC on connection 0 (nls_hdl 0) ... (dbsl 700 250407)

4 ETW000                                                                              53  0.201622

4 ETW000  [dev trc     ,00000]  Nls CharacterSet                 NationalCharSet              C      EnvHp      ErrHp ErrHpBatch

4 ETW000                                                                              55  0.201677

4 ETW000  [dev trc     ,00000]    0 UTF8                                                      1   0258E110   02593744   02592FCC

4 ETW000                                                                              54  0.201731

4 ETW000  [dev trc     ,00000]  Allocating server context handle                      29  0.201760

4 ETW000  [dev trc     ,00000]  Attaching to DB Server ECC (con_hdl=0,svchp=02592F18,srvhp=025A478C)

4 ETW000                                                                              62  0.201822

4 ETW000  [dev trc     ,00000]  Assigning server context 025A478C to service context 02592F18

4 ETW000                                                                           13968  0.215790

4 ETW000  [dev trc     ,00000]  Assigning username to user session 025DFC38           37  0.215827

4 ETW000  [dev trc     ,00000]  Assigning password to user session 025DFC38           39  0.215866

4 ETW000  [dev trc     ,00000]  Starting user session (con_hdl=0,svchp=02592F18,srvhp=025A478C,usrhp=025DFC38)

4 ETW000                                                                              56  0.215922

4 ETW000  [dboci.c     ,00000]  *** ERROR => OCI-call 'OCISessionBegin' failed with rc=1033

4 ETW000                                                                           40566  0.256488

4 ETW000  [dev trc     ,00000]  server_detach(con_hdl=0,stale=1,svrhp=025A478C)       32  0.256520

4 ETW000  [dev trc     ,00000]  Detaching from DB Server (con_hdl=0,svchp=02592F18,srvhp=025A478C)

4 ETW000                                                                              49  0.256569

4 ETW000  [dev trc     ,00000]  Deallocating server context handle 025A478C          564  0.257133

4 ETW000  [dbsloci.    ,00000]  *** ERROR => CONNECT failed with sql error '1033'

4 ETW000                                                                              69  0.257202

4 ETW000  [dev trc     ,00000]     set_ocica() -> OCI or SQL return code 1033         30  0.257232

4 ETW000  [dblink      ,00431]  ***LOG BY2=>sql error 1033   performing CON [dblink#3 @ 431]

4 ETW000                                                                             917  0.258149

4 ETW000  [dblink      ,00431]  ***LOG BY0=>ORA-01033: ORACLE initialization or shutdown in progress [dblink#3 @ 431]

4 ETW000                                                                              85  0.258234

2EETW169 no connect possible: "DBMS = ORACLE                           --- dbs_ora_tnsname = 'ECC'"

Former Member
0 Kudos

Hi Abhi,

              Check the OPS$ and SAPSR3 user status in oracle. If the user locked, unlock and try to start SAP instance.

OSS note 400241 - Problems with ops$ or sapr3 connect to Oracle

Regards

Jose H.

Reagan
Advisor
Advisor
0 Kudos

Hello

Please provide the output of this SQL statment.

select name, open_mode from v$database;

Also, go to the DIR_CT_RUN Directory (D:\usr\sap\ECC\SYS\exe\uc\NTI386) and check for the files present in the dbclient.lst file.

Make sure these files are copied to the DIR_EXECUTABLE directory (D:\usr\sap\ECC\DVEBMGS00\exe).

If the Oracle instant client files are not present update them.

Check the SAP Note I have mentioned above for more details.

Regards

RB

AtulKumarJain
Active Contributor
0 Kudos

Hi Abhijit,

As above log showing

" ORACLE initialization or shutdown in progress"

Please execute

sqlplus / as sysdba

shutdown immediate

startup

First ensure you db is up and running fine

.

ensure ora listener is running.

Best Regards,

Atul

Former Member
0 Kudos

Hi Atul..

Everything is working fine. I have checked it. Next what shall i do ?

Abhijit.