cancel
Showing results for 
Search instead for 
Did you mean: 

disp+work.exe stopped: ERROR => ThInit: db_connect

Former Member
0 Kudos

Dear all,

we hadt to stop our upgrade from NetWeaver 7.3 to 7.4 using SUM.

After the 'reset upgrade' and 'stop upgrade' in SUM we can't start our SAP-system anymore.

I am pasting the dev_w0 for your reference. Please check it and let me know what needs to be done to get it working.

Many thanks for your support!

dev_w0

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

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

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

*

*  ACTIVE TRACE LEVEL           1

*  ACTIVE TRACE COMPONENTS      all, MJ

*

M sysno      00

M sid        B2T

M systemid   562 (PC with Windows NT)

M relno      7210

M patchlevel 0

M patchno    100

M intno      20020600

M make       multithreaded, Unicode, 64 bit, optimized

M profile    \\bw2\sapmnt\B2T\SYS\profile\B2T_DVEBMGS00_bw2

M pid        4820

M

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

M  length of sys_adm_ext is 588 bytes

M  ***LOG Q0Q=> tskh_init, WPStart (Workp. 0 4820) [dpxxdisp.c   1376]

I  MtxInit: 30000 0 0

M  DpSysAdmExtCreate: ABAP is active

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

M  DpIPCInit2: read dp-profile-values from sys_adm_ext

M  DpShMCreate: sizeof(wp_adm)        42864    (2256)

M  DpShMCreate: sizeof(tm_adm)        5517056    (27448)

M  DpShMCreate: sizeof(wp_ca_adm)        64000    (64)

M  DpShMCreate: sizeof(appc_ca_adm)    64000    (64)

M  DpCommTableSize: max/headSize/ftSize/tableSize=500/16/1384064/1384080

M  DpShMCreate: sizeof(comm_adm)        1384080    (2744)

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

M  DpShMCreate: sizeof(slock_adm)        0    (296)

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

M  DpShMCreate: sizeof(file_adm)        0    (80)

M  DpShMCreate: sizeof(vmc_adm)        0    (2160)

M  DpShMCreate: sizeof(wall_adm)        (41664/42896/64/192)

M  DpShMCreate: sizeof(gw_adm)    48

M  DpShMCreate: sizeof(j2ee_adm)    3952

M  DpShMCreate: SHM_DP_ADM_KEY        (addr: 000000000C3A0050, size: 7174832)

M  DpShMCreate: allocated sys_adm at 000000000C3A0060

M  DpShMCreate: allocated wp_adm_list at 000000000C3A30B0

M  DpShMCreate: allocated wp_adm at 000000000C3A32A0

M  DpShMCreate: allocated tm_adm_list at 000000000C3ADA20

M  DpShMCreate: allocated tm_adm at 000000000C3ADA70

M  DpShMCreate: allocated wp_ca_adm at 000000000C8F0980

M  DpShMCreate: allocated appc_ca_adm at 000000000C900390

M  DpShMCreate: allocated comm_adm at 000000000C90FDA0

M  DpShMCreate: system runs without slock table

M  DpShMCreate: system runs without file table

M  DpShMCreate: allocated vmc_adm_list at 000000000CA61C40

M  DpShMCreate: system runs without vmc_adm

M  DpShMCreate: allocated gw_adm at 000000000CA61CF0

M  DpShMCreate: allocated j2ee_adm at 000000000CA61D30

M  DpShMCreate: allocated ca_info at 000000000CA62CB0

M  DpShMCreate: allocated wall_adm at 000000000CA62D40

M  DpCommAttachTable: attached comm table (header=000000000C90FDA0/ft=000000000C90FDB0)

M  DpRqQInit: use protect_queue / slots_per_queue 0 / 2001 from sys_adm

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

X  EmInit: MmSetImplementation( 2 ).

X  MM global diagnostic options set: 0

X  <ES> client 0 initializing ....

X  <ES> EsILock: use spinlock for locking

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  DpVmcSetActive: set vmc state DP_VMC_NOT_ACTIVE

M  ThStart: taskhandler started

M  ThInit: initializing DIA work process W0

M Sat Aug 10 18:21:57 2013

M  ThInit: running on host bw2

M Sat Aug 10 18:21:59 2013

M  calling db_connect ...

B  Loading DB library 'C:\usr\sap\B2T\DVEBMGS00\exe\dbmssslib.dll' ...

B  Library 'C:\usr\sap\B2T\DVEBMGS00\exe\dbmssslib.dll' loaded

B  Version of 'C:\usr\sap\B2T\DVEBMGS00\exe\dbmssslib.dll' is "721.02", patchlevel (0.44)

C  Callback functions for dynamic profile parameter registered

C  Warning: Env(MSSQL_SCHEMA) [b2t] <> Prof(dbs/mss/schema) [B2T]. Profile value will be used.

C  Thread ID:988

C  Thank You for using the SLODBC-interface

C  Using dynamic link library 'C:\usr\sap\B2T\DVEBMGS00\exe\dbmssslib.dll'

C  dbmssslib.dll patch info

C    SAP patchlevel  0

C    SAP patchno  100

C    Last MSSQL DBSL patchlevel 0

C    Last MSSQL DBSL patchno         44

C    Last MSSQL DBSL patchcomment DBSL support for Microsoft ODBC Driver 11 for SQL Server (1816426)

C  ODBC Driver chosen: SQL Server Native Client 10.0 native

C  lpc:(local) connection used on bw2

C  lpc:(local) connection used on bw2

C  Driver: sqlncli10.dll Driver release: 10.50.2769

C  GetDbRelease: 10.50.2769.00

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

C  ERROR: -1 in function ExecuteAndFlush (SQLExecDirect) [line 6828]

C  (102) [42000] [Microsoft][SQL Server Native Client 10.0][SQL Server]Incorrect syntax near 'execute.'.

C  <if user_name() != 'B2T' execute as login = suser_sname(suser_sid('B2T')) with no revert> failed -- connect terminated

B  ***LOG BY2=> sql error 102    performing CON [dbsh         1246]

B  ***LOG BY0=> Incorrect syntax near 'execute.'. [dbsh         1246]

B  ***LOG BY2=> sql error 102    performing CON [dblink       544]

B  ***LOG BY0=> Incorrect syntax near 'execute.'. [dblink       544]

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

M  in_ThErrHandle: 1

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

M  Info for wp 0

M    pid = 4820

M    severity = 0

M    status = 0

M    stat = WP_NEW

M    waiting_for = NO_WAITING

M    reqtype = DP_RQ_DIAWP

M    act_reqtype = NO_REQTYPE

M    req.req_info =

M    req.tid = -1

M    req.uid = 4294967295

M    req.mode = 255

M    req.len = 0

M    req.rq_id = 65535

M    req.rq_source =

M    last_tid = 0

M    last_uid = 0

M    last_mode = 0

M    act_cs_count = 0

M    csTrack = 0

M    csTrackRwExcl = 0

M    csTrackRwShrd = 0

M    mode_cleaned_counter = 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    attachedVm = no VM

M  ThIErrHandle: current request:

M  -IN-- sender_id ?                 tid  -1    wp_ca_blk   -1      wp_id -1

M  -IN-- action    -                 uid  -1    appc_ca_blk -1      type  -   

M  -IN-- new_stat  NO_CHANGE         mode 255   len         0       rq_id -1

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

M  *

M  *  LOCATION    SAP-Server bw2_B2T_00 on host bw2 (wp 0)

M  *  ERROR       ThInit: db_connect

M  *

M  *  TIME        Sat Aug 10 18:21:59 2013

M  *  RELEASE     721

M  *  COMPONENT   Taskhandler

M  *  VERSION     1

M  *  RC          13

M  *  MODULE      thxxhead.c

M  *  LINE        11629

M  *  COUNTER     1

M  *

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

M  PfStatDisconnect: disconnect statistics

M  Entering TH_CALLHOOKS

M  ThCallHooks: call hook >ThrBtcCallLgCl< for event BEFORE_DUMP

M  ThCallHooks: hook >ThrBtcCallLgCl< o.k.

M  ThCallHooks: call hook >ThrSaveSPAFields< for event BEFORE_DUMP

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

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

M  ThIErrHandle: 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 (Workp. 0 4820) [dpnttool.c   339]

Accepted Solutions (1)

Accepted Solutions (1)

Former Member
0 Kudos

Hello guys,

the problem was solved!

For our system landscape the environment variable "MSSQL_SCHEMA=SID" was wrong set.

Regarding Note "98678 - SQL Server Connection Issues" we deleted the environment variable "MSSQL_SCHEMA=SID" and the system starts succesfully.

Thank you so much for your support!

Kind regards,

Sascha

Answers (6)

Answers (6)

Former Member
0 Kudos

Hi there.

SAP support currently checks our system. I'll keep you up to date.

Regards, Sascha

raj_sapbasis
Explorer
0 Kudos

Hi

Check your hosts file for IP address assignment and current IP address. If they are different, disp+work.exe will not be started.

cherry_tian
Employee
Employee
0 Kudos

Hi, Sascha,

Did you reset the upgrade clearly? Had the shadow instance been started before you reset the upgrade?

Please run the following commands and check whether there are some inconsistencies between schema_id and principal_id.

select substring(name,1,20) as name, schema_id, principal_id from sys.schemas

Please also run the command to check whether you have the login b2t.

select name from master..syslogins

I also recommend you to run the SCHEMA4SAP.vbs (note 1294762). The script will help you to repair the user or login permission problem.

Best regards.

Cherry

Sriram2009
Active Contributor
0 Kudos

Hi Sascha

Kindly update the Latest DB library patch 130 as per you log its showing 7.21 patch level 40  for your reference i have attached the screen shots

Thanks

Sriram

Former Member
0 Kudos

Hello Sriram,

we have downgraded back to the SAP KERNEL 7.20 64-BIT UNICODE with the actual lib_dbsl and disp+work package on Patchlevel 440.

Kind regards,

Sascha

Sriram2009
Active Contributor
0 Kudos

Hi

Thanks for your informations

Kindly go thru the SAP note

Note 1844224 - Sporadic gateway crash when starting application server

1561674  - SAP startup problem after kernel upgrade

Note 409839 - Instance does not start due to inconsistent patch number

Regards

Sriram

former_member215981
Active Participant
0 Kudos

Hello,

Kindly refer to note 1294762 (schema repair script) to correct any

missing/wrong authorization.

Best Regards,

Yong Luo

Sriram2009
Active Contributor
0 Kudos

Hi Kertzel

Kindly go thru the SAP Note 1665882 - DBCON-connect: Incorrect syntax near 'max' & updated the latest kernel & DBSL patch

Thanks

Sriram

Former Member
0 Kudos

Hello Siriam,

thank you for your note!


The error described in Note 1665882 I can not find on our system.
In advance I have already implemented the current kernel.

Kind regards,

Sascha

Sriram2009
Active Contributor
0 Kudos

Hi Kertzel

Kindly check the environment variables in 'B2TADM' user id as mention below

DBMS_TYPE=MSS

JAVA_HOME=D:\usr\sap\SID\DVEBMGS01\exe\sapjvm_4
MSSQL_DBNAME=SID
MSSQL_SCHEMA=SID
MSSQL_SERVER=Local Host
SAPEXE=D:\usr\sap\SID\SYS\exe\uc\NTAMD64
SAPLOCALHOST=Local Host
SAPSYSTEMNAME=SID

TEMP=D:\usr\sap\Temp
TMP=D:\usr\sap\Temp

And also kindly refer the SAP Note 1684408 - MSSCONCHECK: Cannot open database requested by the login

Thanks

Sriram

Former Member
0 Kudos

Hello Sriram,

thank you for your reply.

I have checked the environment variables described in note 1684408. They are set as described in the note.

Kind regards,

Sascha

0 Kudos

Check if the SAP is recognizing the environment variables with this command:

sapcontrol -nr <Instance number> -function GetEnvironment

If so you can add missing environment like following to instance profile and restart the sapstartsrv:

SETENV_00 = dbms_type=MSS

SETENV_01 = dbs_ora_tnsname=<SID>

SETENV_02 = ...

former_member184473
Active Contributor
0 Kudos

Hello Sascha,

Using <sid>adm type "r3trans -d" (without quotation marks) in a cmd window.

Then provide the trans.log.

Regards,

Eduardo Rezende

Former Member
0 Kudos

Hello Eduardo,

Here you can find the output of r3trans -d

C:\Users\b2tadm>r3trans -d

This is r3trans version 6.23 (release 720 - 31.10.12 - 20:12:06).

unicode enabled version

R3trans=>sapparam(1c): No Profile used.

R3trans=>sapparam: SAPSYSTEMNAME neither in Profile nor in Commandline

r3trans finished (0000).

C:\Users\b2tadm>set sapsystemname

SAPSYSTEMNAME=B2T

Thank you in advance!

Sascha

Here is the output of trans.log:

4 ETW000 r3trans version 6.23 (release 720 - 31.10.12 - 20:12:06).

4 ETW000 unicode enabled version

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

4 ETW000

4 ETW000 date&time   : 13.08.2013 - 19:37:32

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]  Tue Aug 13 19:37:32 2013                                                  61  0.000061

4 ETW000  [     dev trc,00000]  db_con_init called                                                        18  0.000079

4 ETW000  [     dev trc,00000]  set_use_ext_con_info(): rsdb/ssfs_connect not set => ssfs not used        30  0.000109

4 ETW000  [     dev trc,00000]  determine_block_commit: no con_hdl found as blocked for con_name = R/3

4 ETW000                                                                                                  20  0.000129

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

4 ETW000  [     dev trc,00000]  Loading DB library 'dbmssslib.dll' ...                                    29  0.000171

4 ETW000  [     dev trc,00000]  DlLoadLib success: LoadLibrary("dbmssslib.dll"), hdl 0, addr 000007FEEF110000

4 ETW000                                                                                                2187  0.002358

4 ETW000  [     dev trc,00000]      using "C:\usr\sap\B2T\SYS\exe\uc\NTAMD64\dbmssslib.dll"               14  0.002372

4 ETW000  [     dev trc,00000]  Library 'dbmssslib.dll' loaded                                            14  0.002386

4 ETW000  [     dev trc,00000]  function DbSlExpFuns loaded from library dbmssslib.dll                    16  0.002402

4 ETW000  [     dev trc,00000]  Version of 'dbmssslib.dll' is "720.00", patchlevel (0.440)               105  0.002507

4 ETW000  [     dev trc,00000]  function dsql_db_init loaded from library dbmssslib.dll                   19  0.002526

4 ETW000  [     dev trc,00000]  function dbdd_exp_funs loaded from library dbmssslib.dll                  13  0.002539

4 ETW000  [     dev trc,00000]  New connection 0 created                                                  19  0.002558

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

4 ETW000                                                                                                  32  0.002590

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

4 ETW000  [     dev trc,00000]  determine_block_commit: no con_hdl found as blocked for con_name = R/3

4 ETW000                                                                                                  18  0.002623

4 ETW000  [     dev trc,00000]  find_con_by_name found the following connection:                          12  0.002635

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

4 ETW000                                                                                                  26  0.002661

4 ETW000  [     dev trc,00000]  Thread ID:3124                                                           791  0.003452

4 ETW000  [     dev trc,00000]  Thank You for using the SLODBC-interface                                  20  0.003472

4 ETW000  [     dev trc,00000]  Using dynamic link library 'C:\usr\sap\B2T\SYS\exe\uc\NTAMD64\dbmssslib.dll'

4 ETW000                                                                                                  25  0.003497

4 ETW000  [     dev trc,00000]  dbmssslib.dll patch info                                                  40  0.003537

4 ETW000  [     dev trc,00000]    SAP patchlevel  0                                                       15  0.003552

4 ETW000  [     dev trc,00000]    SAP patchno  440                                                        48  0.003600

4 ETW000  [     dev trc,00000]    Last MSSQL DBSL patchlevel 0                                            16  0.003616

4 ETW000  [     dev trc,00000]    Last MSSQL DBSL patchno         440                                     15  0.003631

4 ETW000  [     dev trc,00000]    Last MSSQL DBSL patchcomment UPSERT using MERGE and a correction for ADBC (1887175)

4 ETW000                                                                                                  23  0.003654

4 ETW000  [     dev trc,00000]  ODBC Driver chosen: SQL Server Native Client 10.0 native                 506  0.004160

4 ETW000  [     dev trc,00000]  lpc:(local) connection used on bw2                                        87  0.004247

4 ETW000  [     dev trc,00000]  lpc:(local) connection used on bw2                                     43582  0.047829

4 ETW000  [     dev trc,00000]  lpc:(local) connection used on bw2                                      2847  0.050676

4 ETW000  [     dev trc,00000]  Driver: sqlncli10.dll Driver release: 10.50.2769                        2823  0.053499

4 ETW000  [     dev trc,00000]  GetDbRelease: 10.50.2769.00                                              292  0.053791

4 ETW000  [     dev trc,00000]  GetDbRelease: Got DB release numbers (10,50,2769,0)                       21  0.053812

4 ETW000  [     dev trc,00000]  Tue Aug 13 19:37:33 2013                                                4124  0.057936

4 ETW000  [     dev trc,00000]  Connection 0 opened (DBSL handle 0)                                       19  0.057955

4 ETW000  [     dev trc,00000]  NTAB: Structure of NTAB on DB is VERS_B                                  658  0.058613

4 ETW000  [     dev trc,00000]  NTAB: unicodelg of executable is 2                                        18  0.058631

4 ETW000  [     dev trc,00000]  NTAB: unicodelg of SVERS in nametab is 2                                  12  0.058643

4 ETW000  [     dev trc,00000]  NTAB: standalone processing                                               38  0.058681

4 ETW000  [     dev trc,00000]  NTAB: ntprofile(), read profile                                           12  0.058693

4 ETW000  [     dev trc,00000]  NTAB: rsdb/ntab/entrycount 1000.                                          12  0.058705

4 ETW000  [     dev trc,00000]  NTAB: rsdb/ntab/ftabsize 500.                                             11  0.058716

4 ETW000  [     dev trc,00000]  NTAB: rsdb/ntab/irbdsize 100.                                             10  0.058726

4 ETW000  [     dev trc,00000]  NTAB: rsdb/ntab/sntabsize 100.                                            11  0.058737

4 ETW000  [     dev trc,00000]  NTAB: compute_hash_card: 2003.                                            19  0.058756

4 ETW000  [     dev trc,00000]  NTAB: maxcnt 1000.                                                        20  0.058776

4 ETW000  [     dev trc,00000]  NTAB: hfactor 2003.                                                       10  0.058786

4 ETW000  [     dev trc,00000]  NTAB: mem_protocol_size 112                                               11  0.058797

4 ETW000  [     dev trc,00000]  NTAB: hdr_backpack_offset 0                                               10  0.058807

4 ETW000  [     dev trc,00000]  NTAB: hdr_backpack_size 0                                                 11  0.058818

4 ETW000  [     dev trc,00000]  NTAB: FTAB: header_size 88.                                               11  0.058829

4 ETW000  [     dev trc,00000]  NTAB: FTAB: item_size 64.                                                 10  0.058839

4 ETW000  [     dev trc,00000]  NTAB: FTAB: item_cnt 1000.                                                11  0.058850

4 ETW000  [     dev trc,00000]  NTAB: FTAB: unit_size 4                                                   11  0.058861

4 ETW000  [     dev trc,00000]  NTAB: FTAB: unit_cnt 128000.                                              10  0.058871

4 ETW000  [     dev trc,00000]  NTAB: FTAB: data_size 512000.                                             14  0.058885

4 ETW000  [     dev trc,00000]  NTAB: IREC: header_size 88.                                               11  0.058896

4 ETW000  [     dev trc,00000]  NTAB: IREC: item_size 64.                                                 11  0.058907

4 ETW000  [     dev trc,00000]  NTAB: IREC: item_cnt 250.                                                 10  0.058917

4 ETW000  [     dev trc,00000]  NTAB: IREC: unit_size 16                                                  11  0.058928

4 ETW000  [     dev trc,00000]  NTAB: IREC: unit_cnt 6400.                                                10  0.058938

4 ETW000  [     dev trc,00000]  NTAB: IREC: data_size 102400.                                             11  0.058949

4 ETW000  [     dev trc,00000]  NTAB: STAB: header_size 88.                                               10  0.058959

4 ETW000  [     dev trc,00000]  NTAB: STAB: item_size 64.                                                 11  0.058970

4 ETW000  [     dev trc,00000]  NTAB: STAB: item_cnt 250.                                                 10  0.058980

4 ETW000  [     dev trc,00000]  NTAB: STAB: unit_size 4                                                   10  0.058990

4 ETW000  [     dev trc,00000]  NTAB: STAB: unit_cnt 25600.                                               11  0.059001

4 ETW000  [     dev trc,00000]  NTAB: STAB: data_size 102400.                                             10  0.059011

4 ETW000  [     dev trc,00000]  NTAB: TTAB: header_size 200.                                              11  0.059022

4 ETW000  [     dev trc,00000]  NTAB: TTAB: item_size 40.                                                 11  0.059033

4 ETW000  [     dev trc,00000]  NTAB: TTAB: item_cnt 1000.                                                10  0.059043

4 ETW000  [     dev trc,00000]  NTAB: TTAB: unit_size 256                                                 11  0.059054

4 ETW000  [     dev trc,00000]  NTAB: TTAB: unit_cnt 1000.                                                10  0.059064

4 ETW000  [     dev trc,00000]  NTAB: TTAB: data_size 256000.                                             11  0.059075

4 ETW000  [     dev trc,00000]  NTAB: FTAB: hh_p 46fb800, hh_len 88                                      404  0.059479

4 ETW000  [     dev trc,00000]  NTAB: FTAB: aa_p 2a83ed0, aa_len 16024                                    18  0.059497

4 ETW000  [     dev trc,00000]  NTAB: FTAB: ia_p 2a87d70, ia_len 64000                                    12  0.059509

4 ETW000  [     dev trc,00000]  NTAB: FTAB: dat_p a9c0080, dat_len 512000                                 12  0.059521

4 ETW000  [     dev trc,00000]  NTAB: IREC: hh_p 46f5ca0, hh_len 88                                       11  0.059532

4 ETW000  [     dev trc,00000]  NTAB: IREC: aa_p 2a97780, aa_len 16024                                    11  0.059543

4 ETW000  [     dev trc,00000]  NTAB: IREC: ia_p 2a9b620, ia_len 16000                                    12  0.059555

4 ETW000  [     dev trc,00000]  NTAB: IREC: dat_p 2a9f4b0, dat_len 102400                                 12  0.059567

4 ETW000  [     dev trc,00000]  NTAB: STAB: hh_p 46ffb40, hh_len 88                                       11  0.059578

4 ETW000  [     dev trc,00000]  NTAB: STAB: aa_p 2ab84c0, aa_len 16024                                    11  0.059589

4 ETW000  [     dev trc,00000]  NTAB: STAB: ia_p 2abc360, ia_len 16000                                    12  0.059601

4 ETW000  [     dev trc,00000]  NTAB: STAB: dat_p 2ac01f0, dat_len 102400                                 11  0.059612

4 ETW000  [     dev trc,00000]  NTAB: TTAB: hh_p 46ffba0, hh_len 200                                      12  0.059624

4 ETW000  [     dev trc,00000]  NTAB: TTAB: aa_p 2ad9200, aa_len 16024                                    11  0.059635

4 ETW000  [     dev trc,00000]  NTAB: TTAB: ia_p 2add0a0, ia_len 40000                                    12  0.059647

4 ETW000  [     dev trc,00000]  NTAB: TTAB: dat_p aa3d090, dat_len 256000                                 11  0.059658

4 ETW000  [     dev trc,00000]  NTAB: ntab_mem_protocol 46ffba0, ntab_mp_p 46ffba0, ntab_proc_id 0        23  0.059681

4 ETW000  [     dev trc,00000]  NTAB: FTAB: hh_p 46fb800, ha_list 2a83ed0, hi_list 2a87d70, buffer a9c0080

4 ETW000                                                                                                  52  0.059733

4 ETW000  [     dev trc,00000]  NTAB: IREC: hh_p 46f5ca0, ha_list 2a97780, hi_list 2a9b620, buffer 2a9f4b0

4 ETW000                                                                                                  19  0.059752

4 ETW000  [     dev trc,00000]  NTAB: STAB: hh_p 46ffb40, ha_list 2ab84c0, hi_list 2abc360, buffer 2ac01f0

4 ETW000                                                                                                  19  0.059771

4 ETW000  [     dev trc,00000]  NTAB: TTAB: hh_p 46ffc10, ha_list 2ad9200, hi_list 2add0a0, buffer aa3d090

4 ETW000                                                                                                  18  0.059789

4 ETW000  [     dev trc,00000]  NTAB: mem_handler: alloc for 500 elems, task 0, art 0                     17  0.059806

4 ETW000  [     dev trc,00000]  NTAB: mem_handler: alloc for 500 elems, task 0, art 1                     20  0.059826

4 ETW000  [     dev trc,00000]  NTAB: mem_handler: alloc for 500 elems, task 0, art 2                     19  0.059845

4 ETW000  [     dev trc,00000]  NTAB: mem_handler: alloc for 500 elems, task 0, art 3                     18  0.059863

4 ETW000  [     dev trc,00000]  { db_rtab( fcode = 'RT_READ_ONLY', tname = 'TADIR' ) {twdbcall.c:764}

4 ETW000                                                                                                 672  0.060535

4 ETW000  [     dev trc,00000]  } db_rtab( fcode = 'RT_READ_ONLY', retcode = 0 )                         382  0.060917

4 ETW000  [     dev trc,00000]  { db_rtab( fcode = 'RT_READ_ONLY', tname = 'MTSETTINGS' ) {twdbcall.c:764}

4 ETW000                                                                                                  52  0.060969

4 ETW000  [     dev trc,00000]  } db_rtab( fcode = 'RT_READ_ONLY', retcode = 64 )                        581  0.061550

4 ETW000 Connected to DBMS = MSSQL ---  SERVER = 'bw2' DBNAME = 'B2T' --- SYSTEM = 'B2T'.

4 ETW000  [     dev trc,00000]  { db_rtab( fcode = 'RT_INSERT', tname = 'PATCHHIST' ) {saprel.c:1436}

4 ETW000                                                                                                 158  0.061708

4 ETW000  [     dev trc,00000]  } db_rtab( fcode = 'RT_INSERT', retcode = 0 )                            616  0.062324

4 ETW000  [     dev trc,00000]  { dsql_open (con_da=(0,R/3),#binds=0,#input=0,                            41  0.062365

4 ETW000                          name=UNKNOWN, stmtid=NULL,

4 ETW000                          stmt="SELECT EXECUTABLE,SAPRELEASE,HOSTNAME,PLATFORM,DBVENDOR,TIMESTAMP,PATCHNO,DBSLPATCHN FROM  ...")

4 ETW000  [     dev trc,00000]  } dsql_open()=0, *cu_id_p=1                                              282  0.062647

4 ETW000  [     dev trc,00000]  { dsql_fetch (con_da=(0,R/3),cu_id=1,#output=8)                           22  0.062669

4 ETW000  [     dev trc,00000]  } dsql_fetch()=0, rec_cnt=1, mod_cnt=1                                    57  0.062726

4 ETW000  [     dev trc,00000]  { dsql_close (con_da=(0,R/3),cu_id=1)                                     17  0.062743

4 ETW000  [     dev trc,00000]  } dsql_close()=0                                                          17  0.062760

4 ETW000  [     dev trc,00000]  { db_rtab( fcode = 'RT_DELETE', tname = 'PATCHHIST' ) {saprel.c:1548}

4 ETW000                                                                                                  20  0.062780

4 ETW000  [     dev trc,00000]  } db_rtab( fcode = 'RT_DELETE', retcode = 0 )                            228  0.063008

4 ETW690 COMMIT "0" "0"

4 ETW000  [     dev trc,00000]  db_con_commit (con_da={R/3,0,0},th_commit=1,tx=1,hold_cursor=0)           36  0.063044

4 ETW000  [     dev trc,00000]  dbsync[db_syflush]: to_be_committed = 1                                   55  0.063099

4 ETW000  trace to file pointer closed

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

4 ETW000

4 ETW000 ================== STEP 1 =====================

4 ETW000 date&time        : 13.08.2013 - 19:37:33

4 ETW000 function         : CONNECT

4 ETW000 buffersync       : YES

4 ETW000 clients          : default

4 ETW000 commit           : 100000

4 ETW000 table cache      : dynamic

4 ETW000

4 ETW000  [     dev trc,00000]  Disconnecting from ALL connections:                                      112  0.000112

4 ETW000  [     dev trc,00000]  Disconnected from connection 0                                           689  0.000801

4 ETW000  [     dev trc,00000]  statistics db_con_commit (com_total=1, com_tx=1)                          22  0.000823

4 ETW000  [     dev trc,00000]  statistics db_con_rollback (roll_total=0, roll_tx=0)                      15  0.000838

4 ETW000 Disconnected from database.

4 ETW000 End of Transport (0000).

4 ETW000 date&time: 13.08.2013 - 19:37:33

former_member184473
Active Contributor
0 Kudos

Hello Sascha,

The R3trans did not face any problem (RC 0).

Do you observe any error message during the startup (dev_ms, dev_w0, dev_disp, ...)?

Regards,

Eduardo

Former Member
0 Kudos

Hello Eduardo,

you can find the error message in dev_w0 - see also my first post.

Kind regards,

Sascha

C  ERROR: -1 in function ExecuteAndFlush (SQLExecDirect) [line 6828]

C  (102) [42000] [Microsoft][SQL Server Native Client 10.0][SQL Server]Incorrect syntax near 'execute.'.

C  <if user_name() != 'B2T' execute as login = suser_sname(suser_sid('B2T')) with no revert> failed -- connect terminated

B  ***LOG BY2=> sql error 102    performing CON [dbsh         1246]

B  ***LOG BY0=> Incorrect syntax near 'execute.'. [dbsh         1246]

B  ***LOG BY2=> sql error 102    performing CON [dblink       544]

B  ***LOG BY0=> Incorrect syntax near 'execute.'. [dblink       544]

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

M  in_ThErrHandle: 1

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

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

M  *

M  *  LOCATION    SAP-Server bw2_B2T_00 on host bw2 (wp 0)

M  *  ERROR       ThInit: db_connect

M  *

M  *  TIME        Sat Aug 10 18:21:59 2013

M  *  RELEASE     721

M  *  COMPONENT   Taskhandler

M  *  VERSION     1

M  *  RC          13

M  *  MODULE      thxxhead.c

M  *  LINE        11629

M  *  COUNTER     1

M  *

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

Former Member
0 Kudos

Here you can find the log from dev_disp:

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

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

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

sysno      00

sid        B2T

systemid   562 (PC with Windows NT)

relno      7200

patchlevel 0

patchno    440

intno      20020600

make       multithreaded, Unicode, 64 bit, optimized

profile    \\bw2\sapmnt\B2T\SYS\profile\B2T_DVEBMGS00_bw2

pid        4796

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

length of sys_adm_ext is 588 bytes

*** SWITCH TRC-HIDE on ***

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

    shared lib "dw_xml.dll" version 440 successfully loaded

    shared lib "dw_xtc.dll" version 440 successfully loaded

    shared lib "dw_stl.dll" version 440 successfully loaded

    shared lib "dw_gui.dll" version 440 successfully loaded

    shared lib "dw_mdm.dll" version 440 successfully loaded

    shared lib "dw_rndrt.dll" version 440 successfully loaded

    shared lib "dw_abp.dll" version 440 successfully loaded

    shared lib "dw_sym.dll" version 440 successfully loaded

    shared lib "dw_aci.dll" version 440 successfully loaded

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

use internal message server connection to port 3901

rdisp/dynamic_wp_check : 1

rdisp/calculateLoadAverage : 1

Mon Aug 12 22:25:18 2013

*** 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  6457]

MtxInit: 30000 0 0

DpSysAdmExtInit: ABAP is active

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

DpIPCInit2: write dp-profile-values into sys_adm_ext

DpIPCInit2: start server >bw2_B2T_00                              <

DpShMCreate: sizeof(wp_adm)        42864    (2256)

DpShMCreate: sizeof(tm_adm)        5517056    (27448)

DpShMCreate: sizeof(wp_ca_adm)        64000    (64)

DpShMCreate: sizeof(appc_ca_adm)    64000    (64)

DpCommTableSize: max/headSize/ftSize/tableSize=500/16/1384064/1384080

DpShMCreate: sizeof(comm_adm)        1384080    (2744)

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

DpShMCreate: sizeof(slock_adm)        0    (296)

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

DpShMCreate: sizeof(file_adm)        0    (80)

DpShMCreate: sizeof(vmc_adm)        0    (2160)

DpShMCreate: sizeof(wall_adm)        (41664/42896/64/192)

DpShMCreate: sizeof(gw_adm)    48

DpShMCreate: sizeof(j2ee_adm)    3952

DpShMCreate: SHM_DP_ADM_KEY        (addr: 0000000004190050, size: 7174832)

DpShMCreate: allocated sys_adm at 0000000004190060

DpShMCreate: allocated wp_adm_list at 00000000041930B0

DpShMCreate: allocated wp_adm at 00000000041932A0

DpShMCreate: allocated tm_adm_list at 000000000419DA20

DpShMCreate: allocated tm_adm at 000000000419DA70

DpShMCreate: allocated wp_ca_adm at 00000000046E0980

DpShMCreate: allocated appc_ca_adm at 00000000046F0390

DpShMCreate: allocated comm_adm at 00000000046FFDA0

DpShMCreate: system runs without slock table

DpShMCreate: system runs without file table

DpShMCreate: allocated vmc_adm_list at 0000000004851C40

DpShMCreate: system runs without vmc_adm

DpShMCreate: allocated gw_adm at 0000000004851CF0

DpShMCreate: allocated j2ee_adm at 0000000004851D30

DpShMCreate: allocated ca_info at 0000000004852CB0

DpShMCreate: allocated wall_adm at 0000000004852D40

DpCommAttachTable: attached comm table (header=00000000046FFDA0/ft=00000000046FFDB0)

DpSysAdmIntInit: initialize sys_adm

rdisp/test_roll : roll strategy is DP_NORMAL_ROLL

dia token check not active (10 token)

MBUF state OFF

DpCommInitTable: init table for 500 entries

DpRqQInit: keep protect_queue / slots_per_queue 0 / 2001 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.

<ES> Info: em/initial_size_MB( 16382MB) not multiple of em/blocksize_KB( 4096KB)

<ES> Info: em/initial_size_MB rounded up to 16384MB

Using implementation view

<EsNT> Using memory model view.

<EsNT> Memory Reset disabled as NT default

<ES> 4095 blocks reserved for free list.

ES initialized.

mm.dump: set maximum dump mem to 192 MB

mm.dump: set global maximum dump mem to 192 MB

EsRegisterEmCheck: Register EmGetEsHandles at 0000000141915570

DpVmcSetActive: set vmc state DP_VMC_NOT_ACTIVE

MPI: dynamic quotas disabled.

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

rdisp/http_min_wait_dia_wp : 1 -> 1

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

***LOG Q0K=> DpMsAttach, mscon ( bw2) [dpxxdisp.c   12693]

MBUF state LOADING

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

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

CCMS: Initialized monitoring segment of size 60000000.

CCMS: Initialized CCMS Headers in the shared monitoring segment.

CCMS: Checking Downtime Configuration of Monitoring Segment.

CCMS: AlMsUpload called by wp 1024.

Mon Aug 12 22:25:19 2013

CCMS: AlMsUpload successful for C:\usr\sap\B2T\DVEBMGS00\log\ALMTTREE.DAT (1582 MTEs).

Mon Aug 12 22:25:20 2013

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 800 (80 %)

MBUF component UP

DpMsgProcess: 1 server in MBUF

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

Mon Aug 12 22:25:58 2013

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

*** DISPATCHER EMERGENCY SHUTDOWN ***

increase tracelevel of WPs

NiWait: sleep (10000ms) ...

NiISelect: timeout 10000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:08 2013

NiISelect: TIMEOUT occured (10000ms)

dump system status

Workprocess Table (long)            Mon Aug 12 20:26:08 2013

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

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 UPD       -1 Ended         no    no     1   0    0                                    NO_ACTION                          

11 ENQ       -1 Ended         no    no     1   0    0                                    NO_ACTION                          

12 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                          

13 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                          

14 BTC       -1 Ended         no    no     1   0    0                                    NO_ACTION                          

15 SPO       -1 Ended         no    no     1   0    0                                    NO_ACTION                          

16 UP2       -1 Ended         no    no     1   0    0                                    NO_ACTION                          

Dispatcher Queue Statistics            Mon Aug 12 20:26:08 2013

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

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

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

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

| NOWP |      0 |      4 |   2000 |          5 |          5 |

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

|  DIA |      4 |      4 |   2000 |          4 |          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        9

wake_evt_udp_now    0

wake events          total     7,  udp     2 ( 28%),  shm     5 ( 71%)

since last update    total     7,  udp     2 ( 28%),  shm     5 ( 71%)

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

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

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

Dispatcher Load Statistics

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

|  Typ |     LA1 |     LA5 |    LA15 |

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

|  DIA |   0.000 |   0.000 |   0.044 |

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

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:            Mon Aug 12 20:26:08 2013

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

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

Workprocess Comm. Area Blocks            Mon Aug 12 20:26:08 2013

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

Slots: 1000, Used: 1, Max: 2

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

|   id | owner        |   pid    | eyecatcher  |

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

|    0 | DISPATCHER   |       -1 | 0xdeadbeef |

NiWait: sleep (5000ms) ...

NiISelect: timeout 5000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:13 2013

NiISelect: TIMEOUT occured (5000ms)

DpHalt: shutdown server >bw2_B2T_00                              < (normal)

DpJ2eeDisableRestart

DpMBufRead: read server list from MBUF (1 entries)

DpMBufMod: name [bw2_B2T_00                              ], types 191 state 3 (0x0 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 00000001417CA150

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

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 00000001417CAFE0

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

DpWpBlksLow: wp_blks_in_queue/limit/max = 1/800/1000

DpLockWpBlkEx: lock wp ca_blk 1

make DISP owner of wp_ca_blk 1

DpRqIPutIntoQ: put elem in queue DIA, elems=5

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

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 (1432) (SOFT_KILL)

DpHalt: stop icman

killing process (324) (SOFT_KILL)

DpHalt: terminate gui connections

DpHalt: wait for end of work processes

DpHalt: wait for end of gateway

[DpProcDied] Process lives  (PID:1432  HANDLE:820)

DpHalt: waiting for termination of gateway ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:14 2013

NiISelect: TIMEOUT occured (1000ms)

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

DpHalt: wait for end of icman

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:15 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:16 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:17 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:18 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:19 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:20 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:21 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:22 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:23 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:24 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:25 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:26 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:27 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:28 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:29 2013

NiISelect: TIMEOUT occured (1000ms)

[DpProcDied] Process lives  (PID:324  HANDLE:828)

DpHalt: waiting for termination of icman ...

NiWait: sleep (1000ms) ...

NiISelect: timeout 1000ms

NiISelect: maximum fd=989

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Mon Aug 12 22:26:30 2013

NiISelect: TIMEOUT occured (1000ms)

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

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

DpStartStopMsg: Write AD_STARTSTOP message with type=  0, name=bw2_B2T_00          , sapsysnr= 0, hostname=bw2                                                            

AdGetSelfIdentRecord: >                                                                           <

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

AdCallRegisteredCvtToExt: opcode 60

AdCallRegisteredCvtToExt: opcode 60 call 00000001417CA150

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 00000001417C5EB0

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)

Mon Aug 12 22:26:31 2013

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   13090]

NiBufSend starting

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

MsINiWrite: sent 110 bytes

MsIDetach: send logout to msg_server (bw2 / 3901)

MsIDetach: call exit function

DpMsShutdownHook called

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

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

NiBufISelRemove: remove hdl 17 from set0

SiSelNRemove: removed sock 816 (pos=2)

SiSelNRemove: removed sock 816

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 00000001417CA150

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 00000001417CAFE0

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 00000001417CAFE0

AdCallRegisteredCvtToExt: opcode 40 exit rc=SAP_O_K

DpWpBlksLow: wp_blks_in_queue/limit/max = 2/800/1000

DpLockWpBlkEx: lock wp ca_blk 2

make DISP owner of wp_ca_blk 2

DpRqIPutIntoQ: put elem in queue DIA, elems=6

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

MBUF component DOWN

NiICloseHandle: shutdown and close hdl 17/sock 816

NiBufIClose: clear extension for hdl 17

MsIDetach: detach MS-system (bw2 / 3901)

DpHalt: cleanup EM

EsCleanup( )

EsCleanup ....

EmCleanup() -> 0

Es2Cleanup: Cleanup ES2

***LOG Q05=> DpHalt, DPStop ( 4796) [dpxxdisp.c   11600]

DpHalt: Good Bye .....

former_member184473
Active Contributor
0 Kudos

Hello Sascha,

Check note 1294762 (schema repair).

Regards,

Eduardo

Reagan
Advisor
Advisor
0 Kudos

Hello

In what phase did you reset the upgrade ?

Check the message server trace file (dev_ms).

May be some issue with the port.

Regards

RB

Former Member
0 Kudos

Hello RB,

thank you for your answer!

In dev_ms I can't find any errors. See the trace file below.

Kind regards,

Sascha

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

trc file: "dev_ms", trc level: 1, release: "720"

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

[Thr 3624] Mon Aug 12 08:24:38 2013

[Thr 3624] ms/http_max_clients = 500 -> 500

[Thr 3624] MsSSetTrcLog: trc logging active, max size = 52428800 bytes

systemid   562 (PC with Windows NT)

relno      7200

patchlevel 0

patchno    101

intno      20020600

make       multithreaded, Unicode, 64 bit, optimized

pid        3492

[Thr 3624] ***LOG Q01=> MsSInit, MSStart (Msg Server 1 3492) [msxxserv.c   2278]

[Thr 3624] load acl file = C:\usr\sap\B2T\SYS\global\ms_acl_info.DAT

[Thr 3624] MsGetOwnIpAddr: my host addresses are :

[Thr 3624]   1 : [192.168.2.228] bw2 (HOSTNAME)

[Thr 3624]   2 : [127.0.0.1] bw2.bi2b.lan (LOCALHOST)

[Thr 3624] MsHttpInit: full qualified hostname = bw2

[Thr 3624] HTTP logging is switch off

[Thr 3624] set HTTP state to LISTEN

[Thr 3624] *** HTTP port 8101 state LISTEN ***

[Thr 3624] *** I listen to port sapmsB2T (3601) ***

[Thr 3624] *** I listen to internal port 3901 (3901) ***

[Thr 3624] *** HTTP port 8101 state LISTEN ***

[Thr 3624] CUSTOMER KEY: >B1210164203<

[Thr 3624] build version=720.2011.10.26