cancel
Showing results for 
Search instead for 
Did you mean: 

dev_enqio shows connection reset from time to time

former_member211576
Contributor
0 Kudos

Hi experts,

We run SAP with Windows 2008 cluster and standalone enqueue replication server. I found dev_enqio shows many connection reset(10054) from time to time. I have run NiPing for LAN stability test for 10 hours on 3 APP servers but no errors(connection reset is detected). I am pretty sure that it could not be network problem. There maybe some misconfiguration of network/DNS/services file or SAP profiles? Please tell me how do I troubleshoot this issue, thx.

-


[Thr 8420] Wed Sep 02 13:20:01 2009

[Thr 8420] ***LOG Q0I=> NiIRead: recv (10054: WSAECONNRESET: Connection reset by peer) [nixxi.cpp 4424]

[Thr 8420] *** ERROR => NiIRead: SiRecv failed for hdl 232 / sock 2060

(SI_ECONN_BROKEN/10054; I4; ST; 192.168.28.69:50003) [nixxi.cpp 4424]

[Thr 8420] Wed Sep 02 13:24:06 2009

[Thr 8420] ***LOG Q0I=> NiIRead: recv (10054: WSAECONNRESET: Connection reset by peer) [nixxi.cpp 4424]

[Thr 8420] *** ERROR => NiIRead: SiRecv failed for hdl 232 / sock 2060

(SI_ECONN_BROKEN/10054; I4; ST; 192.168.28.69:50082) [nixxi.cpp 4424]

---

Accepted Solutions (0)

Answers (6)

Answers (6)

former_member211576
Contributor
0 Kudos

client cancel the transaction, I think.

former_member211576
Contributor
0 Kudos

Hi Manoj/Anreas,

I am sorry. The format become a mass.

---

-- enqio --

Thr 8420] Mon Sep 07 08:25:35 2009

[Thr 8420] ***LOG Q0I=> NiIRead: recv (10054: WSAECONNRESET: Connection reset by peer) [nixxi.cpp 4424]

[Thr 8420] *** ERROR => NiIRead: SiRecv failed for hdl 102 / sock 1496

(SI_ECONN_BROKEN/10054; I4; ST; 192.168.28.94:3910) [nixxi.cpp 4424]

--

-- event log --

9/7/2009 8:54:23 AM SAPTCP_14 Error None 17898 N/A TCCAP14 SAP-Basis System: Transaction Canceled 14 026 ( )

9/7/2009 8:48:21 AM SAPTCP_14 Error None 14197 N/A TCCAP14 "SAP-Basis System: Run-time error ""CONTROL_NOT_FOUND"" occurred "

9/7/2009 8:42:07 AM SAPTCP_14 Error None 17898 N/A TCCAP14 SAP-Basis System: Transaction Canceled 14 026 ( )

9/7/2009 8:25:35 AM SAPTCP_14 Error None 35712 N/A TCCAP14 SAP-Basis System: Operating system call recv failed (error no. 10054)

---

--SM21 --

08:25:35 DIA 002 218 01030109 QA32 Q0 2 Stop Workproc 2, PID 1616

08:25:35 RD Q0 I Operating system call recv failed (error no. 10054)

08:25:36 WRK 000 Q0 Q Start Workproc 2, Pid 632

---

-- dev_w2 --

C Mon Sep 07 08:25:30 2009

C SlBreak

B db_sqlbreak() = 15

M program canceled

M reason = soft cancel

M user = 01030109

M client = 218

M terminal = qsmctclient30

M report = SAPLZQMIVAN

M

M Mon Sep 07 08:25:35 2009

M ThWpSoftCancelHandler: soft kill timeout, terminate process (pid=1616, user is T35/M0)

M ThWpSoftCancelHandler: previous sql break failed, terminate without db cleanup and hooks

M ThWpSoftCancelHandler: prv_action of W2: 0x22

M ThRollOut: roll out T35/U18253/M0/I0 (level=7, short_roll_out=0)

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 U18253 M0 I0 (Level 7) ok

M ThWpSoftCancelHandler: prv_action of W2: 0x2a

M ThWpSoftCancelHandler: save snc contexts

M ThISncSaveAllContexts: save 0 snc contexts

M ThCheckEmMagic: check em magic at 000007FE571414A0 for T35/M0/I0

M ThCheckEmMagic: em magic at 000007FE571414A0 for T35/M0/I0 o.k.

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

M ThEmContextDetach2: reset local em info

M ***LOG Q02=> wp_halt, WPStop (Workproc 2 1616) [dpnttool.c 333]

former_member211576
Contributor
0 Kudos

Hi Manoj/Andreas,

I dump all related log files, FYI.

-- enqio --

Thr 8420] Mon Sep 07 08:25:35 2009

[Thr 8420] ***LOG Q0I=> NiIRead: recv (10054: WSAECONNRESET: Connection reset by peer) [nixxi.cpp 4424]

[Thr 8420] *** ERROR => NiIRead: SiRecv failed for hdl 102 / sock 1496

(SI_ECONN_BROKEN/10054; I4; ST; 192.168.28.94:3910) [nixxi.cpp 4424]

-- event log --

9/7/2009 8:54:23 AM SAPTCP_14 Error None 17898 N/A TCCAP14 SAP-Basis System: Transaction Canceled 14 026 ( )

9/7/2009 8:48:21 AM SAPTCP_14 Error None 14197 N/A TCCAP14 "SAP-Basis System: Run-time error ""CONTROL_NOT_FOUND"" occurred "

9/7/2009 8:42:07 AM SAPTCP_14 Error None 17898 N/A TCCAP14 SAP-Basis System: Transaction Canceled 14 026 ( )

9/7/2009 8:25:35 AM SAPTCP_14 Error None 35712 N/A TCCAP14 SAP-Basis System: Operating system call recv failed (error no. 10054)

-- SM21 --

08:25:35 DIA 002 218 01030109 QA32 Q0 2 Stop Workproc 2, PID 1616

08:25:35 RD Q0 I Operating system call recv failed (error no. 10054)

08:25:36 WRK 000 Q0 Q Start Workproc 2, Pid 632

-- dev_w2 --

C Mon Sep 07 08:25:30 2009

C SlBreak

B db_sqlbreak() = 15

M program canceled

M reason = soft cancel

M user = 01030109

M client = 218

M terminal = qsmctclient30

M report = SAPLZQMIVAN

M

M Mon Sep 07 08:25:35 2009

M ThWpSoftCancelHandler: soft kill timeout, terminate process (pid=1616, user is T35/M0)

M ThWpSoftCancelHandler: previous sql break failed, terminate without db cleanup and hooks

M ThWpSoftCancelHandler: prv_action of W2: 0x22

M ThRollOut: roll out T35/U18253/M0/I0 (level=7, short_roll_out=0)

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 U18253 M0 I0 (Level 7) ok

M ThWpSoftCancelHandler: prv_action of W2: 0x2a

M ThWpSoftCancelHandler: save snc contexts

M ThISncSaveAllContexts: save 0 snc contexts

M ThCheckEmMagic: check em magic at 000007FE571414A0 for T35/M0/I0

M ThCheckEmMagic: em magic at 000007FE571414A0 for T35/M0/I0 o.k.

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

M ThEmContextDetach2: reset local em info

M ***LOG Q02=> wp_halt, WPStop (Workproc 2 1616) [dpnttool.c 333]

-


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

-


*

  • ACTIVE TRACE LEVEL 1

  • ACTIVE TRACE COMPONENTS all, MJ

*

B

B Mon Sep 07 08:25:36 2009

B create_con (con_name=R/3)

B Loading DB library 'S:\usr\sap\TCP\D14\exe\dbmssslib.dll' ...

B Library 'S:\usr\sap\TCP\D14\exe\dbmssslib.dll' loaded

B Version of 'S:\usr\sap\TCP\D14\exe\dbmssslib.dll' is "700.08", patchlevel (0.213)

B New connection 0 created

M sysno 14

M sid TCP

M systemid 562 (PC with Windows NT)

M relno 7000

M patchlevel 0

M patchno 215

M intno 20050900

M make: multithreaded, Unicode, 64 bit, optimized

M pid 632

M

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

M length of sys_adm_ext is 576 bytes

M ***LOG Q0Q=> tskh_init, WPStart (Workproc 2 632) [dpxxdisp.c 1349]

I MtxInit: 30000 0 0

M DpSysAdmExtCreate: ABAP is active

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

M DpShMCreate: sizeof(wp_adm) 54320 (1752)

M DpShMCreate: sizeof(tm_adm) 58861440 (29416)

M DpShMCreate: sizeof(wp_ca_adm) 80000 (80)

M DpShMCreate: sizeof(appc_ca_adm) 160000 (80)

M DpCommTableSize: max/headSize/ftSize/tableSize=2000/16/2208064/2208080

M DpShMCreate: sizeof(comm_adm) 2208080 (1088)

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

M DpShMCreate: sizeof(slock_adm) 0 (104)

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

M DpShMCreate: sizeof(file_adm) 0 (72)

M DpShMCreate: sizeof(vmc_adm) 0 (1864)

M DpShMCreate: sizeof(wall_adm) (416064/346352/64/192)

M DpShMCreate: sizeof(gw_adm) 48

M DpShMCreate: SHM_DP_ADM_KEY (addr: 00000000116C0050, size: 62135344)

M DpShMCreate: allocated sys_adm at 00000000116C0050

M DpShMCreate: allocated wp_adm at 00000000116C2270

M DpShMCreate: allocated tm_adm_list at 00000000116CF6A0

M DpShMCreate: allocated tm_adm at 00000000116CF700

M DpShMCreate: allocated wp_ca_adm at 0000000014EF1E80

M DpShMCreate: allocated appc_ca_adm at 0000000014F05700

M DpShMCreate: allocated comm_adm at 0000000014F2C800

M DpShMCreate: system runs without slock table

M DpShMCreate: system runs without file table

M DpShMCreate: allocated vmc_adm_list at 0000000015147950

M DpShMCreate: allocated gw_adm at 00000000151479D0

M DpShMCreate: system runs without vmc_adm

M DpShMCreate: allocated ca_info at 0000000015147A00

M DpShMCreate: allocated wall_adm at 0000000015147A10

M rdisp/queue_size_check_value : -> off

M ThTaskStatus: rdisp/reset_online_during_debug 0

X EmInit: MmSetImplementation( 2 ).

X MM global diagnostic options set: 0

X <ES> client 2 initializing ....

X Using implementation view

X <EsNT> Using memory model view.

M <EsNT> Memory Reset disabled as NT default

X ES initialized.

X mm.dump: set maximum dump mem to 96 MB

M ThInit: running on host tccap14

M calling db_connect ...

C Callback functions for dynamic profile parameter registered

C Thread ID:2564

C Thank You for using the SLODBC-interface

C Using dynamic link library 'S:\usr\sap\TCP\D14\exe\dbmssslib.dll'

C dbmssslib.dll patch info

C patchlevel 0

C patchno 213

C patchcomment Access violation in ReportDeadlock for UPSERT (1354349)

C Network connection used from TCCAP14 to TCCDB1 using tcp:TCCDB1

C Network connection used from TCCAP14 to TCCDB1 using tcp:TCCDB1

C

C Mon Sep 07 08:25:37 2009

C Driver: sqlncli10.dll Driver release: 10.00.1812

C GetDbRelease: 10.00.2531

C GetDbRelease: Got DB release numbers (10,0,2531)

B Connection 0 opened (DBSL handle 0)

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

B 000 000 R/3 000000000 ACTIVE NO YES NO 000 255 255 20090907 082536 TCCDB1

M db_connect o.k.

M ICT: exclude compression: .zip,.cs,.rar,.arj,.z,.gz,.tar,.lzh,.cab,.hqx,.ace,.jar,.ear,.war,.css,.pdf,.js,.gzip

I MtxInit: 2 0 0

M SHM_PRES_BUF (addr: 0000000021A70050, size: 26001000)

M SHM_ROLL_AREA (addr: 000007FFA6B90050, size: 268435456)

M SHM_PAGING_AREA (addr: 0000000023340050, size: 268435456)

M SHM_ROLL_ADM (addr: 0000000033350050, size: 2760892)

M SHM_PAGING_ADM (addr: 0000000033AB0050, size: 4195360)

M ThCreateNoBuffer allocated 544152 bytes for 1000 entries at 0000000033600050

M ThCreateNoBuffer index size: 3000 elems

M ThCreateVBAdm allocated 12176 bytes (50 server) at 0000000033690050

X EmInit: MmSetImplementation( 2 ).

X MM global diagnostic options set: 0

X <ES> client 2 initializing ....

X Using implementation view

X ES initialized.

X mm.dump: set maximum dump mem to 96 MB

M Deactivate statistics hyper index locking

B dbntab: NTAB buffers attached

B dbntab: Buffer FTAB(hash header) (addr: 00000000350400E0, size: 584)

B dbntab: Buffer FTAB(anchor array) (addr: 0000000035040330, size: 1920056)

B dbntab: Buffer FTAB(item array) (addr: 0000000035214F70, size: 7680000)

B dbntab: Buffer FTAB(data area) (addr: 0000000035967F70, size: 245760000)

B dbntab: Buffer IREC(hash header) (addr: 0000000033EC00E0, size: 584)

B dbntab: Buffer IREC(anchor array) (addr: 0000000033EC0330, size: 1920056)

B dbntab: Buffer IREC(item array) (addr: 0000000034094F70, size: 1920000)

B dbntab: Buffer IREC(data area) (addr: 0000000034269B70, size: 8192000)

B dbntab: Buffer STAB(hash header) (addr: 00000000443D00E0, size: 584)

B dbntab: Buffer STAB(anchor array) (addr: 00000000443D0330, size: 1920056)

B dbntab: Buffer STAB(item array) (addr: 00000000445A4F70, size: 1920000)

B dbntab: Buffer STAB(data area) (addr: 0000000044779B70, size: 3072000)

B dbntab: Buffer TTAB(hash header) (addr: 0000000044A700E0, size: 3816)

B dbntab: Buffer TTAB(anchor array) (addr: 0000000044A70FD0, size: 1920056)

B dbntab: Buffer TTAB(item array) (addr: 0000000044C45C10, size: 4800000)

B dbntab: Buffer TTAB(data area) (addr: 00000000450D9A10, size: 35040000)

B db_con_shm_ini: WP_ID = 2, WP_CNT = 31, CON_ID = 2000007

B dbstat: TABSTAT buffer attached (addr: 0000000047267C30)

B dbtbxbuf: Buffer TABL (addr: 00000000498F0160, size: 332800000, end: 000000005D652160)

B dbtbxbuf: Buffer TABLP (addr: 0000000068140160, size: 204800000, end: 0000000074490160)

B dbexpbuf: Buffer EIBUF (addr: 000000005D660170, size: 131072000, end: 0000000065360170)

B dbexpbuf: Buffer ESM (addr: 0000000034A40170, size: 4194304, end: 0000000034E40170)

B dbexpbuf: Buffer CUA (addr: 0000000065370170, size: 24576000, end: 0000000066AE0170)

B dbexpbuf: Buffer OTR (addr: 0000000066AF0170, size: 4194304, end: 0000000066EF0170)

B dbcalbuf: Buffer CALE (addr: 0000000034E50050, size: 500000, end: 0000000034ECA170)

M CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.

S *** init spool environment

S TSPEVJOB updates inside critical section: event_update_nocsec = 0

S initialize debug system

T Stack direction is downwards.

T debug control: prepare exclude for printer trace

T new memory block 000000000B02EC10

S spool kernel/ddic check: Ok

S using table TSP02FX for frontend printing

former_member211576
Contributor
0 Kudos

Hi Andreas,

Actually, I have attached event log in last message. It showed transaction cancel(but it does not display the transaction name). That's why I suspect it would be canceled from client by user actively. If so, it might be a normal information.

former_member211576
Contributor
0 Kudos

Hi Manoj,

Thank for your information. I check dev_enqio log file and find there is a connection error at 8:51. I don't really understand the error message. Does this mean client cancel the transaction and disconnect the session actively? So this is just a normal record?

---

9/4/2009 8:51:18 AM SAPTCP_16 Error None 17898 N/A TCCAP16 SAP-Basis System: Transaction Canceled 00 359 ( /N )

9/4/2009 8:51:16 AM SAPTCP_16 Error None 35712 N/A TCCAP16 SAP-Basis System: Operating system call recv failed (error no. 10054)

9/4/2009 8:51:11 AM SAPTCP_16 Error None 17898 N/A TCCAP16 SAP-Basis System: Transaction Canceled 00 359 ( /N )

9/4/2009 8:40:58 AM SAPTCP_16 Error None 35698 N/A TCCAP16 SAP-Basis System: Connection to user 16503 (BY-NB1-2 ), terminal 51 (C01050217-2 ) lost

9/4/2009 8:40:58 AM SAPTCP_16 Error None 35712 N/A TCCAP16 SAP-Basis System: Operating system call recv failed (error no. 10054)

9/4/2009 8:21:12 AM SAPTCP_16 Error None 35698 N/A TCCAP16 SAP-Basis System: Connection to user 16306 (03030259 ), terminal 40 (c07071427-2 ) lost

9/4/2009 8:21:12 AM SAPTCP_16 Error None 35712 N/A TCCAP16 SAP-Basis System: Operating system call recv failed (error no. 10054)

Former Member
0 Kudos

Hi,

this is not normal - it's an error. For getting more information on that issue please have a look at the event log of your Windows like Manoj is asking for. If you find there also an error at the same time like the error shown in your SAP dev_enqio log file, this might be a useful information.

If so, could you please provide the entry of event log?

Regards,

Andreas

Former Member
0 Kudos

Usually this error related with the network connectivity. Check event log for that time slot and see if you get the more info.