cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 

Problem starting SAP: disp+work.EXE Running but bad dialog queue time

Go to solution
Former Member

on ‎08-11-2014 9:35 AM

0 Kudos

Hello,

I have been trying to start my SAP test instance on a local computer, but it does not work. I can see "Running but bad dialog queue time: 12.00 sec". This was working a few weeks ago, but  now it doesn't anymore. My initial assumption was the database was filled, but I checked that and it seems ok, there  is still space for data and logs...

I have checked the dev_disp log and I can see these:

*** ERROR => DpHdlDeadWp: W0 (pid 7540) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W1 (pid 6008) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W2 (pid 2412) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W3 (pid 7284) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W4 (pid 1340) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W5 (pid 4088) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W6 (pid 304) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W8 (pid 2120) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W9 (pid 5392) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W10 (pid 6140) died (severity=0, status=0) [dpxxwp.c    1531]

*** ERROR => DpHdlDeadWp: W11 (pid 2996) died (severity=0, status=0) [dpxxwp.c    1531]

Then I checked the log for W0 and I think this is the problem:

M  ***LOG Q0I=> NiPConnect2: 127.0.0.1:65000: connect (10061: WSAECONNREFUSED: Connection refused) [nixxi.cpp 3286]

M  *** ERROR => NiPConnect2: SiPeekPendConn failed for hdl 1/sock 1276

    (SI_ECONN_REFUSE/10061; I4; ST; 127.0.0.1:65000) [nixxi.cpp    3286]

M  *** ERROR => IcmConnect: NiConnect to localhost:65000,-1 (rc=-10) [icxxext.c    182]

M  *** ERROR => ThPlgConnectToIcm: IcmConnect failed (-10) [thxxplg.c    5137]

M  in_ThErrHandle: 1

M  *** ERROR => ThStart: connect to icman (step 1, th_errno 2, action 3, level 1) [thxxhead.c  11313]

The connections are refused for some reason, I don't know why. Can anyone help? I am attaching the log files...

dev_disp.txt.zip
dev_icm.txt.zip
dev_w0.txt.zip
Show replies
Show replies
Answer

Accepted Solutions (1)

Accepted Solutions (1)

madasamy_arunachalam
Active Participant
‎08-11-2014 11:24 AM
0 Kudos

Hi

As per your work process trace (dev_w0), it is not able to reach icm port 65000



M Mon Aug 11 10:27:39 2014


M  ***LOG Q0I=> NiPConnect2: 127.0.0.1:65000: connect (10061: WSAECONNREFUSED: Connection refused) [nixxi.cpp 3286]


M  *** ERROR => NiPConnect2: SiPeekPendConn failed for hdl 1/sock 1260


    (SI_ECONN_REFUSE/10061; I4; ST; 127.0.0.1:65000) [nixxi.cpp    3286]


M  *** ERROR => IcmConnect: NiConnect to localhost:65000,-1 (rc=-10) [icxxext.c    182]


M  *** ERROR => ThPlgConnectToIcm: IcmConnect failed (-10) [thxxplg.c    5137]


M  in_ThErrHandle: 1


M  *** ERROR => ThStart: connect to icman (step 1, th_errno 2, action 3, level 1) [thxxhead.c   11313]

to fix this error, follow below 2  SAP KBAs ;


1934023 - Application server startup fail with an ICM connection error

1900647 - Parameter icm/admin_port

regards

Swami

Show replies
Show replies
Former Member
‎08-11-2014 1:52 PM
0 Kudos

Thank you very much, this worked. I set a parameter for icm admin port in instance profile and it started again. Obviously there was a conflict with another server in the system, although I cannot see which, as I was not running anything else from SAP at the time.

Thanks to everyone for help

madasamy_arunachalam
Active Participant
‎08-11-2014 5:18 PM
0 Kudos

gr8, good to hear. Please note whatever Port  (within SAP specified range) we set in Parameter, need to ensure those port is not used by any other application.

Former Member
‎08-17-2014 11:07 AM
0 Kudos

Hi Madasamy,


when i try to open the url in your post i need to enter User and Password, but im allready logged to community networt, how to read the article??


This two url:

1934023 - Application server startup fail with an ICM connection error

1900647 - Parameter icm/admin_port


Thanks

Mirso

Former Member
‎10-06-2015 7:43 AM
0 Kudos

I am also trying to run SAP: Disp+Work.exe runs but after some few seconds it stops.

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

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

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

sysno      45

sid        D6M

systemid   562 (PC with Windows NT)

relno      7210

patchlevel 0

patchno    45

intno      20020600

make       multithreaded, Unicode, 64 bit, optimized

profile    \\wdflbmt4604\sapmnt\D6M\SYS\profile\D6M_DVEBMGS45_wdflbmt4604

pid        3500

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

length of sys_adm_ext is 588 bytes

*** SWITCH TRC-HIDE on ***

***LOG Q00=> DpSapEnvInit, DPStart (45 3500) [dpxxdisp.c   1314]

  shared lib "dw_xml.dll" version 45 successfully loaded

  shared lib "dw_xtc.dll" version 45 successfully loaded

  shared lib "dw_stl.dll" version 45 successfully loaded

  shared lib "dw_gui.dll" version 45 successfully loaded

  shared lib "dw_mdm.dll" version 45 successfully loaded

  shared lib "dw_rndrt.dll" version 45 successfully loaded

  shared lib "dw_abp.dll" version 45 successfully loaded

  shared lib "dw_sym.dll" version 45 successfully loaded

  shared lib "dw_aci.dll" version 45 successfully loaded

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

use internal message server connection to port 3945

rdisp/dynamic_wp_check : 1

rdisp/calculateLoadAverage : 1

Tue Oct 06 08:28:20 2015

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

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

MtxInit: 30000 0 0

DpSysAdmExtInit: ABAP is active

DpSysAdmExtInit: VMC (JAVA VM in WP) is active

DpIPCInit2: write dp-profile-values into sys_adm_ext

DpIPCInit2: start server >wdflbmt4604_D6M_45                      <

DpShMCreate: sizeof(wp_adm) 76704 (2256)

DpShMCreate: sizeof(tm_adm) 54923456 (27448)

DpShMCreate: sizeof(wp_ca_adm) 132096 (64)

DpShMCreate: sizeof(appc_ca_adm) 132096 (64)

DpCommTableSize: max/headSize/ftSize/tableSize=2000/16/5536064/5536080

DpShMCreate: sizeof(comm_adm) 5536080 (2744)

DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=512/48/163904/96560/260512

DpShMCreate: sizeof(slock_adm) 260512 (296)

DpFileTableSize: max/headSize/ftSize/tableSize=6800/16/652864/652880

DpShMCreate: sizeof(file_adm) 652880 (80)

DpShMCreate: sizeof(vmc_adm) 73440 (2160)

DpShMCreate: sizeof(wall_adm) (416064/352496/64/192)

DpShMCreate: sizeof(gw_adm) 48

DpShMCreate: sizeof(j2ee_adm) 3952

DpShMCreate: SHM_DP_ADM_KEY (addr: 000000000CDA0050, size: 62574096)

DpShMCreate: allocated sys_adm at 000000000CDA0060

DpShMCreate: allocated wp_adm_list at 000000000CDA30B0

DpShMCreate: allocated wp_adm at 000000000CDA32A0

DpShMCreate: allocated tm_adm_list at 000000000CDB5E50

DpShMCreate: allocated tm_adm at 000000000CDB5EA0

DpShMCreate: allocated wp_ca_adm at 0000000010216F70

DpShMCreate: allocated appc_ca_adm at 0000000010237380

DpShMCreate: allocated comm_adm at 0000000010257790

DpShMCreate: allocated slock_adm at 000000001079F0F0

DpShMCreate: allocated file_adm at 00000000107DEAA0

DpShMCreate: allocated vmc_adm_list at 000000001087E100

DpShMCreate: allocated vmc_adm at 000000001087E1B0

DpShMCreate: allocated gw_adm at 00000000108900A0

DpShMCreate: allocated j2ee_adm at 00000000108900E0

DpShMCreate: allocated ca_info at 0000000010891060

DpShMCreate: allocated wall_adm at 00000000108910F0

Tue Oct 06 08:28:21 2015

DpCommAttachTable: attached comm table (header=0000000010257790/ft=00000000102577A0)

DpSysAdmIntInit: initialize sys_adm

rdisp/test_roll : roll strategy is DP_NORMAL_ROLL

dia token check not active (15 token)

MBUF state OFF

DpCommInitTable: init table for 2000 entries

DpFileInitTable: init table for 6800 entries

DpSesCreateTable: created session table at 0000000004600050 (len=1485072)

DpRqQInit: keep protect_queue / slots_per_queue 0 / 4001 in sys_adm

rdisp/queue_size_check_value :  -> on,50,30,40,500,50,500,80

EmInit: MmSetImplementation( 2 ).

MM global diagnostic options set: 0

<ES> client 0 initializing ....

<ES> EsILock: use spinlock for locking

<ES> InitFreeList

<ES> block size is 4096 kByte.

Using implementation view

<EsNT> Using memory model view.

<EsNT> Memory Reset disabled as NT default

<ES> 1023 blocks reserved for free list.

ES initialized.

mm.dump: set maximum dump mem to 96 MB

Es2ResCreate: Changed resource parameters to

  filenamePrefix =

  blockSizeKB    = 1024 KB

  initialSizeMB  = 240 MB

  extendSizeMB   = 120 MB

  maxSizeMB      = 10080 MB

DpVmcSetActive: set vmc state DP_VMC_ENABLED

DpVmcSetActive: set vmc state DP_VMC_ACTIVE

DpVmcInit2: o.k.

MPI: dynamic quotas disabled.

MPI init: pipes=4000 buffers=1279 reserved=383 quota=10%

Tue Oct 06 08:28:25 2015

rdisp/http_min_wait_dia_wp : 1 -> 1

***LOG CPS=> DpLoopInit, ICU ( 3.4 3.4 4.1) [dpxxdisp.c   1700]

***LOG Q0K=> DpMsAttach, mscon ( wdflbmt4604) [dpxxdisp.c   12667]

MBUF state LOADING

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

DpStartStopMsg: start msg sent to message server o.k.

CCMS: SemInMgt: Semaphore Management initialized by AlAttachShm_Doublestack.

CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.

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

DpMBufHwIdSet: set Hardware-ID

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

MBUF state ACTIVE

DpWpBlksLow: max wp blocks in queue is 1651 (80 %)

MBUF component UP

DpMsgProcess: 1 server in MBUF

DpAppcBlksLow: max appc blocks in queue is 1032 (50 %)

Tue Oct 06 08:29:05 2015

*** ERROR => DpHdlDeadWp: W0 (pid 3648) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W1 (pid 3688) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W2 (pid 1724) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W3 (pid 3488) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W4 (pid 1948) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W5 (pid 1900) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W6 (pid 2332) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W7 (pid 3924) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W8 (pid 3432) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W9 (pid 3820) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W10 (pid 3344) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W11 (pid 2736) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W12 (pid 2724) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W13 (pid 3108) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W14 (pid 1960) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W15 (pid 776) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W16 (pid 3100) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W17 (pid 4072) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W18 (pid 3428) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W19 (pid 3952) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W20 (pid 1520) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W21 (pid 3452) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W22 (pid 1352) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W23 (pid 2912) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W24 (pid 1972) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W25 (pid 3088) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W26 (pid 1088) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W27 (pid 3384) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W28 (pid 2248) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W29 (pid 3352) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W30 (pid 3444) died (severity=0, status=0) [dpxxwp.c     1729]

*** ERROR => DpHdlDeadWp: W31 (pid 852) died (severity=0, status=0) [dpxxwp.c     1729]

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

*** DISPATCHER EMERGENCY SHUTDOWN ***

increase tracelevel of WPs

NiWait: sleep (10000ms) ...

NiISelect: timeout 10000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:15 2015

NiISelect: TIMEOUT occured (10000ms)

dump system status

Workprocess Table (long) Tue Oct 06 06:29:15 2015

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

No Type  Pid    Status  Cause Start Rstr  Err Sem Time Program          Cl  User         Action                    Table

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

0 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

1 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

2 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

3 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

4 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

5 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

6 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

7 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

8 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

9 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

10 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

11 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

12 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

13 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

14 DIA       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

15 UPD       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

16 UPD       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

17 UPD       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

18 ENQ       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

19 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

20 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

21 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

22 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

23 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

24 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

25 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

26 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

27 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

28 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

29 SPO       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

30 UP2       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

31 UP2       -1 Ended         no    no     1   0    0                                    NO_ACTION                         

Dispatcher Queue Statistics Tue Oct 06 06:29:15 2015

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

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

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

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

| NOWP |      0 |      2 |   4000 |          6 |          6 |

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

|  DIA |      7 |      7 |   4000 |          7 |          0 |

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

|  UPD |      0 |      0 |   4000 |          0 |          0 |

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

|  ENQ |      0 |      0 |   4000 |          0 |          0 |

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

|  BTC |      0 |      0 |   4000 |          0 |          0 |

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

|  SPO |      0 |      0 |   4000 |          0 |          0 |

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

|  UP2 |      0 |      0 |   4000 |          0 |          0 |

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

max_rq_id 12

wake_evt_udp_now 1

wake events       total    10,  udp     9 ( 90%),  shm     1 ( 10%)

since last update total    10,  udp     9 ( 90%),  shm     1 ( 10%)

DpGetLoadAverage: la1[1] = 0.000000 / -1.000000 %

DpGetLoadAverage: la5[1] = 0.000000 / -1.000000 %

DpGetLoadAverage: la15[1] = 0.055556 / -1.000000 %

Dispatcher Load Statistics

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

|  Typ |     LA1 |     LA5 |    LA15 |

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

|  DIA |   0.000 |   0.000 |   0.056 |

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

DpGetLoadAverage: la1[2] = 0.000000 / -1.000000 %

DpGetLoadAverage: la5[2] = 0.000000 / -1.000000 %

DpGetLoadAverage: la15[2] = 0.000000 / -1.000000 %

|  UPD |   0.000 |   0.000 |   0.000 |

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

DpGetLoadAverage: la1[3] = 0.000000 / -1.000000 %

DpGetLoadAverage: la5[3] = 0.000000 / -1.000000 %

DpGetLoadAverage: la15[3] = 0.000000 / -1.000000 %

|  ENQ |   0.000 |   0.000 |   0.000 |

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

DpGetLoadAverage: la1[4] = 0.000000 / -1.000000 %

DpGetLoadAverage: la5[4] = 0.000000 / -1.000000 %

DpGetLoadAverage: la15[4] = 0.000000 / -1.000000 %

|  BTC |   0.000 |   0.000 |   0.000 |

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

DpGetLoadAverage: la1[5] = 0.000000 / -1.000000 %

DpGetLoadAverage: la5[5] = 0.000000 / -1.000000 %

DpGetLoadAverage: la15[5] = 0.000000 / -1.000000 %

|  SPO |   0.000 |   0.000 |   0.000 |

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

DpGetLoadAverage: la1[6] = 0.000000 / -1.000000 %

DpGetLoadAverage: la5[6] = 0.000000 / -1.000000 %

DpGetLoadAverage: la15[6] = 0.000000 / -1.000000 %

|  UP2 |   0.000 |   0.000 |   0.000 |

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

Dump of tm_adm structure: Tue Oct 06 06:29:15 2015

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

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

Workprocess Comm. Area Blocks Tue Oct 06 06:29:15 2015

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

Slots: 2064, Used: 1, Max: 1

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

|   id | owner        |   pid    | eyecatcher  |

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

|    0 | DISPATCHER   |       -1 | 0xdeadbeef |

NiWait: sleep (5000ms) ...

NiISelect: timeout 5000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:20 2015

NiISelect: TIMEOUT occured (5000ms)

DpHalt: shutdown server >wdflbmt4604_D6M_45                      < (normal)

DpJ2eeDisableRestart

DpMBufRead: read server list from MBUF (1 entries)

DpMBufMod: name [wdflbmt4604_D6M_45                      ], types 191 state 3 (0x2 0x0 0x0 0x0)

DpMBufMod: call hooks for event MBUF_DPEVT_DSTATE (5->3)

AdGetSelfIdentRecord: >                                                                           <

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

AdCallRegisteredCvtToExt: opcode 60

AdCallRegisteredCvtToExt: opcode 60 call 00000001417DD100

AdCallRegisteredCvtToExt: opcode 60 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

DpWpBlksLow: wp_blks_in_queue/limit/max = 1/1651/2064

DpLockWpBlkEx: lock wp ca_blk 1

make DISP owner of wp_ca_blk 1

DpRqIPutIntoQ: put elem in queue DIA, elems=8

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

MBUF server state SHUTDOWN

DpModState: change state STARTING -> SHUTDOWN

NiBufSend starting

NiIWrite: hdl 17 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

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

DpHalt: stop work processes

DpHalt: stop gateway

killing process (2176) (SOFT_KILL)

DpHalt: stop icman

killing process (464) (SOFT_KILL)

DpHalt: terminate gui connections

DpHalt: wait for end of work processes

DpHalt: wait for end of gateway

[DpProcDied] Process lives  (PID:2176  HANDLE:908)

DpHalt: waiting for termination of gateway ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:21 2015

NiISelect: TIMEOUT occured (1000ms)

*** ERROR => [DpProcDied] Process died  (PID:2176  HANDLE:908  rc:0x0) [dpnttool2.c  147]

DpHalt: wait for end of icman

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:22 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:23 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:24 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:25 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:26 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:27 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:28 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:29 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:30 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:31 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:32 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:33 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:34 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:35 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:36 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:37 2015

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:464  HANDLE:916)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=1093

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Tue Oct 06 08:29:38 2015

NiISelect: TIMEOUT occured (1000ms)

*** ERROR => [DpProcDied] Process died  (PID:464  HANDLE:916  rc:0x0) [dpnttool2.c  147]

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

DpStartStopMsg: Write AD_STARTSTOP message with type=  0, name=wdflbmt4604_D6M_45  , sapsysnr=45, hostname=wdflbmt4604                                                   

AdGetSelfIdentRecord: >                                                                           <

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

AdCallRegisteredCvtToExt: opcode 60

AdCallRegisteredCvtToExt: opcode 60 call 00000001417DD100

AdCallRegisteredCvtToExt: opcode 60 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 4

AdCallRegisteredCvtToExt: opcode 4 call 00000001417D8E60

AdCallRegisteredCvtToExt: opcode 4 exit rc=SAP_O_K

DpConvertRequest: net size = 189 bytes

NiBufIAlloc: malloc MSLIB-BUF, to 32110 bytes

NiBufSend starting

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

MsINiWrite: sent 562 bytes

MsISndTypeOnce: send msg (ms hdr/msg 110/452 bytes) to MSG_SERVER, type 1

DpStartStopMsg: stop msg sent to message server o.k.

NiBufIAlloc: malloc MSLIB-BUF, to 32110 bytes

NiBufIAlloc: malloc NIBUF-IN, to 32110 bytes

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

NiBufIIn: NIBUF len=274

NiBufIIn: packet complete for hdl 17

NiBufReceive starting

MsINiRead: received 274 bytes

MsIReceive: received msg (ms hdr/msg 110/164 bytes), flag 1, from MSG_SERVER          , typ 0, key -

DpHalt: received 164 bytes from message server

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

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

DpHalt: more messages from the message server

DpHalt: send keepalive to synchronize with the message server

NiBufSend starting

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

MsINiWrite: sent 114 bytes

MsISnd2: send msg (ms hdr/msg 110/4 bytes) to name           MSG_SERVER, type 0, key -

MsSndName: MS_NOOP ok

Send 4 bytes to MSG_SERVER

NiBufIAlloc: malloc MSLIB-BUF, to 32110 bytes

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

NiIPeek: peek successful for hdl 17 (r)

NiBufIAlloc: malloc NIBUF-IN, to 32110 bytes

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

NiBufIIn: NIBUF len=114

NiBufIIn: packet complete for hdl 17

NiBufReceive starting

MsINiRead: received 114 bytes

MsIReceive: received msg (ms hdr/msg 110/4 bytes), 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

MsISendKeepalive : keepalive sent to message server

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

Tue Oct 06 08:29:39 2015

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

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

DpHalt: no more messages from the message server

DpHalt: sync with message server o.k.

DpHalt: detach from message server

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

NiBufSend starting

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

MsINiWrite: sent 110 bytes

MsIDetach: send logout to msg_server (wdflbmt4604 / 3945)

MsIDetach: call exit function

DpMsShutdownHook called

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

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

NiBufISelRemove: remove hdl 17 from set0

SiSelNRemove: removed sock 904 (pos=2)

SiSelNRemove: removed sock 904

NiSelIRemove: removed hdl 17

DpDelSocketInfo: del info for nihdl 17 (pos/type=2/4)

MBUF state OFF

AdGetSelfIdentRecord: >                                                                           <

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

AdCallRegisteredCvtToExt: opcode 60

AdCallRegisteredCvtToExt: opcode 60 call 00000001417DD100

AdCallRegisteredCvtToExt: opcode 60 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

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

AdCallRegisteredCvtToExt: opcode 40

AdCallRegisteredCvtToExt: opcode 40 call 00000001417DE090

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

DpWpBlksLow: wp_blks_in_queue/limit/max = 2/1651/2064

DpLockWpBlkEx: lock wp ca_blk 2

make DISP owner of wp_ca_blk 2

DpRqIPutIntoQ: put elem in queue DIA, elems=9

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

MBUF component DOWN

NiICloseHandle: shutdown and close hdl 17/sock 904

NiBufIClose: clear extension for hdl 17

MsIDetach: detach MS-system (wdflbmt4604 / 3945)

DpHalt: cleanup EM

EsCleanup( )

EsCleanup ....

EmCleanup() -> 0

Em2Cleanup: Cleanup EM2

Eg2Cleanup: Cleanup EG2

Es2Cleanup: Cleanup ES2

Es2GetNumberOfFileSlots: -> 8 files

Es2ResLocSlotClear: clear slot 0

Es2ResLocSlotClear: clear slot 0

Es2ResLocSlotClear: clear slot 0

Es2ResLocSlotClear: clear slot 0

Es2ResLocSlotClear: clear slot 0

Es2ResLocSlotClear: clear slot 0

Es2ResLocSlotClear: clear slot 0

Es2ResLocSlotClear: clear slot 0

***LOG Q05=> DpHalt, DPStop ( 3500) [dpxxdisp.c   11574]

DpHalt: Good Bye .....

Former Member
‎10-06-2015 10:17 AM
0 Kudos

Hi,

as Madasamy wrote:



As per your work process trace (dev_w0), it is not able to reach icm port 65000






M Mon Aug 11 10:27:39 2014


M  ***LOG Q0I=> NiPConnect2: 127.0.0.1:65000: connect (10061: WSAECONNREFUSED: Connection refused) [nixxi.cpp 3286]


M  *** ERROR => NiPConnect2: SiPeekPendConn failed for hdl 1/sock 1260


    (SI_ECONN_REFUSE/10061; I4; ST; 127.0.0.1:65000) [nixxi.cpp    3286]


M  *** ERROR => IcmConnect: NiConnect to localhost:65000,-1 (rc=-10) [icxxext.c    182]


M  *** ERROR => ThPlgConnectToIcm: IcmConnect failed (-10) [thxxplg.c    5137]


M  in_ThErrHandle: 1


M  *** ERROR => ThStart: connect to icman (step 1, th_errno 2, action 3, level 1) [thxxhead.c   11313]






to fix this error, follow below 2  SAP KBAs ;





 1934023 - Application server startup fail with an ICM connection error 


1900647 - Parameter icm/admin_port

In my case i solve it by checking what process uses port 65000:

(I'm not so familiar with "set a parameter for icm admin port in instance profile"

so i do it in this way)

1) call cmd.exe

2) write command: netstat -ano

now you have a list of all processes that uses ports and their process id

Search that process who use port 65000 -> note that id

3) open task manager and show the PID column in the list (if not visible)

4) search the process with the noted id

     a) if you don't need this process kill them and  try again.

     b) if you need this process(application) then set a own port for icm and try again

This is the exact way how i solve my problem, in my case it was a background process of my

NVidia Tool (graphic adapter) i disable this services because i don't need them.

When im more familiar with custom settings icm/server_port i enable again this disabled services,

but for now i dont need them.

Regards

Mirso

Answers (3)

Answers (3)

JPReyes
Active Contributor
‎08-11-2014 9:49 AM
0 Kudos 


M  ***LOG Q0I=> NiPConnect2: 127.0.0.1:65000: connect (10061: WSAECONNREFUSED: Connection refused) [nixxi.cpp 3286]


M  *** ERROR => NiPConnect2: SiPeekPendConn failed for hdl 1/sock 1276


    (SI_ECONN_REFUSE/10061; I4; ST; 127.0.0.1:65000) [nixxi.cpp    3286]

Disable your local firewall and your antivirus and try again.

Regards, Juan

Show replies
Show replies
Former Member
‎08-11-2014 11:05 AM
0 Kudos

I tried to disable firewall and antivirus, but it did not help...

former_member188883
Active Contributor
‎08-11-2014 9:49 AM
0 Kudos

Hi Miha,

Please check whether services file and hosts file have correct ip and hostname entries.

sapms<SID> entry exists in services file.

Look out for these files under etc/drives

Regards,

Deepak Kori

Show replies
Show replies
Former Member
‎08-11-2014 11:03 AM
0 Kudos

The service file contains the correct entry:

sapmsNSP 3600/tcp

And the hosts file has these related to my system (10.2.1.140 is the local address of the machine):

127.0.0.1 localhost

10.2.1.140 Miha-PC Miha-PC.domain.ext

former_member188883
Active Contributor
‎08-11-2014 11:05 AM
0 Kudos

Hi Miha,

Please comment this entry 127.0.0.1 localhost

take SAP restart and check the results.

Regards,

Deepak Kori

Former Member
‎08-11-2014 11:25 AM
0 Kudos

It is the same. I have tried the niping command ass suggested by @Divyanshu Srivastava

This i what it returns, the IP resolution looks ok to me:


former_member188883
Active Contributor
‎08-11-2014 11:54 AM
0 Kudos

Hi Miha,

please have a look into SAP Note 501902 - Error in the SAP dispatcher.

It talks about bug fixes in SAP kernel.

Alternative you may perform full reboot of your machine and then check the results.

Regards,

Deepak Kori

divyanshu_srivastava3
Active Contributor
‎08-11-2014 9:48 AM
0 Kudos

Hi Miha,

Seems some problem with ICM connection.

Refer; 1891193 - SAP system cannot start due to local ICM is not connected


Divyanshu

Show replies
Show replies
Ask a Question