Severe error(s) occurred in phase MAIN_TRANSEXEC/MVNTAB_TRANS!

[Former Member]

Hi, all, I'm upgrading insurance module at SAP ERP with SUM tool.

At execution step I got this error:

Severe error(s) occurred in phase MAIN_TRANSEXEC/MVNTAB_TRANS!

Last error code set: Calling 'C:\usr\sap\CM1\DVEBMGS04\exe/tp' failed with return code 8, check C:\SUM\SUM\abap\log\TP.ECO for details<br/> Error during &quot;tp mvntabs&quot;-call - check 'TP.ECO'

TP.ECO log:

SAPup> Starting subprocess in phase 'MVNTAB_TRANS' at 20140601193822

ENV: DBMS_TYPE=hdb

ENV: PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC

ENV: PATH=C:\usr\sap\CM1\DVEBMGS04\exe;C:\Program Files\Far Manager\;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\usr\sap\CM1\SYS\exe\uc\NTAMD64;C:\usr\sap\CM1\hdbclient

ENV: SAPSYSTEMNAME=CM1

ENV: dbs_hdb_schema=SAPCM1

ENV: rsdb_ssfs_connect=0

EXECUTING C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=V" CM1

This is C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE version 380.18.68 (release 741, unicode enabled)

tp finished with return code: 8

meaning:

A tool used by tp produced errors

SAPup> Process with PID 15316 terminated with status 8 at 20140601193848!

r3trans, tp and kernel are up to date.

Thanks and Regards.

[Former Member · Accepted solution]

After running tp in debug mode with command -

C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=V" CM1 -t=9

Error:

SQLERRTEXT : invalidated view: cannot create synonym for invalidated view: line 1 col 41 (at pos 40)

appears in log.

See note 1612696

Also views wasn't activated from sap<sid> user in HANA DB.

After permissions fix and activating views problem was solved.

[Former Member]

Hi Alexander,

I am facing the same error in phase MAIN_NEWBAS/MVNTAB_UPG. Did You manage to resolve the problem?

Thank You.

Igor

[Former Member]

Hi,

I have found a solution for this problem for my system. Maybe it could help somebody else.

Log on system as DDIC -> SE14 and follow the notes 211492 and 1248769.

BR, Igor

[Former Member]

Hi Igor, with which table your problem was?

Regards, Alexander

[Former Member]

Hi Aleksander,

Problem was in DDXTT table.

BR, Igor

[Former Member]

Thank you.

[ashish_vikas]

please share PMVNTTRANS.LOG file.

best regards

ashish

[Former Member]

Hi,

1 ETQ201 Entering upgrade-phase "MAIN_TRANSEXEC/MVNTAB_TRANS" ("20140603112808")

2 ETQ367 Connect variables are set for standard instance access

4 ETQ399 System-nr = '04', GwService = 'sapgw04' Client = '000'

4 ETQ399 Environment variables:

4 ETQ399 dbs_hdb_schema=<null>

4 ETQ399 auth_shadow_upgrade=<null>

1 ETQ200 Executing actual phase 'MAIN_TRANSEXEC/MVNTAB_TRANS'.

1 ETQ399 Phase arguments:

2 ETQ399 Arg[0] = 'PARALLEL;NOBUFRESET;CHECK_MF_ALL'

2 ETQ399 Arg[1] = 'ALL'

2 ETQ399 Arg[2] = ''

2 ETQ399 Arg[3] = 'PMVNTUPG.TP0'

1 ETQ399 Using error summary log 'PMVNTUPG.ELG'.

2 ETQ399 Empty putsteps - configuring at least parallel batches and main import processes.

2 ETQ399 Distribute 3 parallel processes to 2 tp and 2 R3trans.

4 ETQ380 computing toolpath for request "TP_MOVENTABS"

4 ETQ382 translates to group "R3UP_TOOL_GROUP_SYS"

4 ETQ399 Requirement for tp maps to '<system>'

4 ETQ383 translates to path "C:\usr\sap\CM1\DVEBMGS04\exe"

2 ETQ399 Requested task ID is 'UPDATE_TABLSIZE'.

3 ETQ399 Generated TQL file 'UPDTABLSIZE.XQL' with 1 SQL statements, log file 'UPDTABLSIZE.LOG'.

4 ETQ276 Executing SQL-script "UPDTABLSIZE.XQL"

3 ETQ120 20140603112808: PID 4652 execute 'C:\SUM\SUM\abap\bin\SAPuptool execdb ..\var\UPDTABLSIZE.XQL', output written to 'C:\SUM\SUM\abap\log\SQLSTMTSTD.OUT'.

3 ETQ123 20140603112808: PID 4652 exited with status 0 (time 0.000 real)

3 ETQ399 Reading SQL output from 'UPDTABLSIZE.LOG'.

2 ETQ399 Requested task ID is 'COUNT_TABLSIZE'.

3 ETQ399 Generated TQL file 'CNTTABLSIZE.XQL' with 1 SQL statements, log file 'CNTTABLSIZE.LOG'.

4 ETQ276 Executing SQL-script "CNTTABLSIZE.XQL"

3 ETQ120 20140603112809: PID 4612 execute 'C:\SUM\SUM\abap\bin\SAPuptool execdb ..\var\CNTTABLSIZE.XQL', output written to 'C:\SUM\SUM\abap\log\SQLSTMTSTD.OUT'.

3 ETQ123 20140603112809: PID 4612 exited with status 0 (time 0.000 real)

3 ETQ399 Reading selections from 'CNTTABLSIZE.LOG'.

4 ETQ399 Read 0 entries for table 'LIST'.

2 ETQ399 No entries found for modeflag 'P' - continue

2 ETQ399 Requested task ID is 'UPDATE_TABLSIZE'.

3 ETQ399 Generated TQL file 'UPDTABLSIZE.XQL' with 1 SQL statements, log file 'UPDTABLSIZE.LOG'.

4 ETQ276 Executing SQL-script "UPDTABLSIZE.XQL"

3 ETQ120 20140603112809: PID 4640 execute 'C:\SUM\SUM\abap\bin\SAPuptool execdb ..\var\UPDTABLSIZE.XQL', output written to 'C:\SUM\SUM\abap\log\SQLSTMTSTD.OUT'.

3 ETQ123 20140603112809: PID 4640 exited with status 0 (time 0.000 real)

3 ETQ399 Reading SQL output from 'UPDTABLSIZE.LOG'.

2 ETQ399 Requested task ID is 'COUNT_TABLSIZE'.

3 ETQ399 Generated TQL file 'CNTTABLSIZE.XQL' with 1 SQL statements, log file 'CNTTABLSIZE.LOG'.

4 ETQ276 Executing SQL-script "CNTTABLSIZE.XQL"

3 ETQ120 20140603112809: PID 4676 execute 'C:\SUM\SUM\abap\bin\SAPuptool execdb ..\var\CNTTABLSIZE.XQL', output written to 'C:\SUM\SUM\abap\log\SQLSTMTSTD.OUT'.

3 ETQ123 20140603112810: PID 4676 exited with status 0 (time 0.000 real)

3 ETQ399 Reading selections from 'CNTTABLSIZE.LOG'.

4 ETQ399 Read 1 entries for table 'LIST'.

2 ETQ399 Found 1 view-on-view groups

2 ETQ399 Found 2 times TABLSIZE '0000004'.

2 ETQ000 ==================================================

2 ETQ101 Executing 4 processes sequentially.

3 ETQ121 20140603112810 #1 (1): PID 4556 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" delntabs "protyear=00" CM1' in background, output written to 'C:\SUM\SUM\abap\log\TP.001'.

3 ETQ123 20140603112812 (0): PID 4556 exited with status 0 (time 0.000 real)

3 ETQ121 20140603112812 #2 (1): PID 4340 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" actntabs "protyear=00" CM1' in background, output written to 'C:\SUM\SUM\abap\log\TP.002'.

3 ETQ123 20140603112813 (0): PID 4340 exited with status 0 (time 0.000 real)

3 ETQ121 20140603112813 #3 (1): PID 5040 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=F" "protyear=00" CM1' in background, output written to 'C:\SUM\SUM\abap\log\TP.003'.

3 ETQ123 20140603112814 (0): PID 5040 exited with status 0 (time 0.000 real)

3 ETQ121 20140603112814 #4 (1): PID 4136 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=V" "protyear=01" "seltsize=4" CM1' in background, output written to 'C:\SUM\SUM\abap\log\TP.004'.

3 ETQ123 20140603112815 (0): PID 4136 exited with status 0 (time 0.000 real)

1 ETQ000 ==================================================

4 ETQ010 Date & Time: 20140603112815

2 ETQ102 Finished execution of 4 process(es).

2 ETQ108 Analyzing logs for errors.

3 ETQ114 Analyzed 3 log files.

4 ETQ010 Starting: tp actntabs operation

3 ETQ120 20140603112815: PID 4220 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" actntabs CM1', output written to 'C:\SUM\SUM\abap\log\TP.ECO'.

3 ETQ123 20140603112816: PID 4220 exited with status 0 (time 0.000 real)

4 ETQ010 Starting: tp delntabs operation

3 ETQ120 20140603112816: PID 5084 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" delntabs CM1', output written to 'C:\SUM\SUM\abap\log\TP.ECO'.

3 ETQ123 20140603112817: PID 5084 exited with status 0 (time 0.000 real)

4 ETQ010 Starting: tp ddlntabs operation

3 ETQ120 20140603112817: PID 4704 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=F" CM1', output written to 'C:\SUM\SUM\abap\log\TP.ECO'.

3 ETQ123 20140603112818: PID 4704 exited with status 0 (time 0.000 real)

4 ETQ010 Starting: tp ddlntabs operation

3 ETQ120 20140603112818: PID 592 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=P" CM1', output written to 'C:\SUM\SUM\abap\log\TP.ECO'.

3 ETQ123 20140603112819: PID 592 exited with status 0 (time 0.000 real)

4 ETQ010 Starting: tp ddlntabs operation

3 ETQ120 20140603112819: PID 4744 execute 'C:\usr\sap\CM1\DVEBMGS04\exe\tp "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=V" CM1', output written to 'C:\SUM\SUM\abap\log\TP.ECO'.

3 ETQ123 20140603112844: PID 4744 exited with status 8 (time 0.000 real)

1EETQ399 Last error code set is: Calling 'C:\usr\sap\CM1\DVEBMGS04\exe/tp' failed with return code 8, check C:\SUM\SUM\abap\log\TP.ECO for details

Error during "tp mvntabs"-call - check 'TP.ECO'

1EETQ204 Upgrade phase "MVNTAB_TRANS" aborted with severe errors ("20140603112844")

Regards, Alexander

[Reagan]

What do the C:\SUM\SUM\abap\log\TP.ECO and C:\SUM\SUM\abap\log\TP.SAV log files say ?

Regards

RB

[Former Member]

Hi,

TP.ECO:

SAPup> Starting subprocess in phase 'MVNTAB_TRANS' at 20140603112819

ENV: DBMS_TYPE=hdb

ENV: PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC

ENV: PATH=C:\usr\sap\CM1\DVEBMGS04\exe;C:\Program Files\Far Manager\;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\Sy

stem32\WindowsPowerShell\v1.0\;C:\usr\sap\CM1\SYS\exe\uc\NTAMD64;C:\usr\sap\CM1\hdbclient

ENV: SAPSYSTEMNAME=CM1

ENV: dbs_hdb_schema=SAPCM1

ENV: rsdb_ssfs_connect=1

EXECUTING C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=V" CM1

This is C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE version 380.18.68 (release 741, unicode enabled)

tp finished with return code: 8

meaning:

A tool used by tp produced errors

SAPup> Process with PID 4744 terminated with status 8 at 20140603112844

TP.SAV in attach

TP.SAV.txt.zip

[Reagan]

any information in the SAPup.log and SAPup.ECO log files ?

[Former Member]

Hi, sorry for delay. I found several strange messages in this logs. Maybe they can be useful.

SAPup.ECO:

EXECUTING C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE "pf=C:\SUM\SUM\abap\var\ACTUPG.TPP" put CM1

This is C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE version 380.18.68 (release 741, unicode enabled)

Looking for effective putsteps ...Cannot remove 魀瑴: The system cannot find the file specified.

This warning "Cannot remove 魀瑴: The system cannot find the file specified." repeated at every task.

At SAPup.log all phases have status SUCCEEDED or SKIPPED until MAIN_TRANSEXEC/MVNTAB_TRANS phase.

After that this message repeats several times:

STARTUP 20140605163035

Current unit is 'EXECUTE', next unit is 'EXECUTE'.

-> decided to continue with unit EXECUTE.

...begin dialog at 20140605163036

...end dialog at 20140605163346

..answered at 20140605163346.

-> decided to try again.

# Executing module 'MAIN_TRANSEXEC'.

Execution of MAIN module "Downtime for transport mode" begins at 20140605163346

CURRENTPHASE MAIN_TRANSEXEC/MVNTAB_TRANS

...started at 20140605163346

# Using phase log file 'PMVNTTRANS.LOG'.

..finished at 20140605163425 with status ABORTED.

# Error message set: 'Calling 'C:\usr\sap\CM1\DVEBMGS04\exe/tp' failed with return code 8, check C:\SUM\SUM\abap\log\TP.ECO for details

Error during "tp mvntabs"-call - check 'TP.ECO''

...begin dialog at 20140605163427

...end dialog at 20140606101500

..answered at 20140606101500.

-> decided to try again.

And the the last:

CURRENTPHASE MAIN_TRANSEXEC/MVNTAB_TRANS

...started at 20140609142822

# Using phase log file 'PMVNTTRANS.LOG'.

..finished at 20140609142857 with status ABORTED.

# Error message set: 'Calling 'C:\usr\sap\CM1\DVEBMGS04\exe/tp' failed with return code 8, check C:\SUM\SUM\abap\log\TP.ECO for details

Error during "tp mvntabs"-call - check 'TP.ECO''

...begin dialog at 20140609142858

Regards and thank you. Alexander.

[former_member182657]

Hi,

Could you check for SAP Note 1952701 - DBSL supports new HANA version number

Regards,

Gaurav

[Former Member]

Hi,

I checked both notes, downloaded last lib_dbsl_31-10012513.sar adn installed it, and checked all steps at note 1639578

Still got the message :

read_con_info_ssfs(): DBSL does not support extended connect protocol

4 ETW000 ==> ssfs won't be used

Here is rsecssfx list:

C:\SUM\DBSL>rsecssfx list

|---------------------------------------------------------------------------------|

| Record Key | Status | Timestamp of last Update |

|---------------------------------------------------------------------------------|

| DB_CONNECT/DEFAULT_DB_CON_ENV | Plaintext | 2014-06-02 16:05:06 UTC |

| DB_CONNECT/DEFAULT_DB_PASSWORD | Encrypted | 2014-06-02 16:08:02 UTC |

| DB_CONNECT/DEFAULT_DB_USER | Plaintext | 2014-06-02 16:08:14 UTC |

|---------------------------------------------------------------------------------|

Summary

-------

Active Records : 3 (Encrypted : 1, Plain : 2, Wrong Key : 0, Error : 0)

C:\SUM\DBSL>

Here is trans log:

4 ETW000 C:\usr\sap\CM1\DVEBMGS04\exe\R3trans.EXE version 6.24 (release 741 - 16.05.14 - 20:14:06).

4 ETW000 unicode enabled version

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

4 ETW000

4 ETW000 date&time : 03.06.2014 - 09:39:00

4 ETW000 control file: <no ctrlfile>

4 ETW000 R3trans was called as follows: C:\usr\sap\CM1\DVEBMGS04\exe\R3trans.EXE -d

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

4 ETW000 [ dev trc,00000] Tue Jun 03 09:39:00 2014 211 0.000211

4 ETW000 [ dev trc,00000] db_con_init called 77 0.000288

4 ETW000 [ dev trc,00000] set_use_ext_con_info(): ssfs will be used to get connect information

4 ETW000 145 0.000433

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

4 ETW000 29 0.000462

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

4 ETW000 [ dev trc,00000] Loading DB library 'dbhdbslib.dll' ... 47 0.000525

4 ETW000 [ dev trc,00000] DlLoadLib success: LoadLibrary("dbhdbslib.dll"), hdl 0, count 1, addr 000007FEE60F0000

4 ETW000 5047 0.005572

4 ETW000 [ dev trc,00000] using "C:\usr\sap\CM1\DVEBMGS04\exe\dbhdbslib.dll" 20 0.005592

4 ETW000 [ dev trc,00000] Library 'dbhdbslib.dll' loaded 20 0.005612

4 ETW000 [ dev trc,00000] function DbSlExpFuns loaded from library dbhdbslib.dll 35 0.005647

4 ETW000 [ dev trc,00000] Version of 'dbhdbslib.dll' is "741.10", patchlevel (0.31) 76 0.005723

4 ETW000 [ dev trc,00000] function dsql_db_init loaded from library dbhdbslib.dll 30 0.005753

4 ETW000 [ dev trc,00000] function dbdd_exp_funs loaded from library dbhdbslib.dll 76 0.005829

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 55 0.005884

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=-1,command=39,arg_p=0000000000000000) 24 0.005908

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 18 0.005926

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=-1,command=10,arg_p=000000000214F450) 22 0.005948

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.005965

4 ETW000 [ dev trc,00000] New connection 0 created 17 0.005982

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

4 ETW000 40 0.006022

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=-1,command=10,arg_p=000000014152EDB0) 35 0.006057

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 18 0.006075

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

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

4 ETW000 23 0.006117

4 ETW000 [ dev trc,00000] find_con_by_name found the following connection: 16 0.006133

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

4 ETW000 71 0.006204

4 ETW000 [ dev trc,00000] read_con_info_ssfs(): reading connect info for connection R/3 28 0.006232

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=-1,command=74,arg_p=0000000000000000) 23 0.006255

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=15) 18 0.006273

4 ETW000 [ dev trc,00000] read_con_info_ssfs(): DBSL does not support extended connect protocol

4 ETW000 ==> ssfs won't be used 24 0.006297

4 ETW000 [ dev trc,00000] { DbSlHDBConnect(con_info_p=0000000000000000) 37 0.006334

4 ETW000 [ dev trc,00000] DBHDBSLIB : version 741.10, patch 0.031 (Make PL 0.31) 32 0.006366

4 ETW000 [ dev trc,00000] HDB shared library (dbhdbslib) patchlevels (last 10) 34 0.006400

4 ETW000 [ dev trc,00000] (0.031) New DBCON syntax for HANA (note 1983389) 21 0.006421

4 ETW000 [ dev trc,00000] (0.028) Bulk insert stop on duplicate, dbsl returncode corrected (note 1996982)

4 ETW000 29 0.006450

4 ETW000 [ dev trc,00000] (0.027) Set DBAREQ class (note 1991383) 20 0.006470

4 ETW000 [ dev trc,00000] (0.022) Get database version via dbsl call (note 1976918) 22 0.006492

4 ETW000 [ dev trc,00000] (0.020) FDA: Core Dump in SELECT ... FOR ALL ENTRIES for tables with strings (note 1970276)

4 ETW000 30 0.006522

4 ETW000 [ dev trc,00000] (0.020) SQL DDL with data aging (note 1897636) 21 0.006543

4 ETW000 [ dev trc,00000] (0.017) Datatype NCLOB missing in tablesize calculation (note 1952609)

4 ETW000 28 0.006571

4 ETW000 [ dev trc,00000] (0.014) Tablesize calculation for HANA optimized (note 1952609) 23 0.006594

4 ETW000 [ dev trc,00000] (0.014) Native SQL UPSERT with DataAging (note 1897636) 20 0.006614

4 ETW000 [ dev trc,00000] (0.014) DBSL supports HANA revision number up to 3 digits (note 1952701)

4 ETW000 26 0.006640

4 ETW000 [ dev trc,00000] 14 0.006654

4 ETW000 [ dev trc,00000] -> init() 22 0.006676

4 ETW000 [ dev trc,00000] STATEMENT_CACHE_SIZE = 1000 211 0.006887

4 ETW000 [ dev trc,00000] -> init() 533 0.007420

4 ETW000 [ dev trc,00000] -> loadClientRuntime() 25 0.007445

4 ETW000 [ dev trc,00000] Loading SQLDBC client runtime ... 19 0.007464

4 ETW000 [ dev trc,00000] SQLDBC Module : C:\usr\sap\CM1\hdbclient\libSQLDBCHDB.dll 738 0.008202

4 ETW000 [ dev trc,00000] SQLDBC Runtime : libSQLDBCHDB 1.00.68 Build 0384084-1510 50 0.008252

4 ETW000 [ dev trc,00000] SQLDBC client runtime is 1.00.68.0384084 31 0.008283

4 ETW000 [ dev trc,00000] -> getNewConnection() 23 0.008306

4 ETW000 [ dev trc,00000] <- getNewConnection(con_hdl=0) 109 0.008415

4 ETW000 [ dev trc,00000] -> checkEnvironment(con_hdl=0) 40 0.008455

4 ETW000 [ dev trc,00000] -> connect(con_info_p=0000000000000000) 28 0.008483

4 ETW000 [ dev trc,00000] Try to connect via secure store (DEFAULT) on connection 0 ... 78 0.008561

4 ETW000 [ dev trc,00000] -> check_db_params(con_hdl=0) 64807 0.073368

4 ETW000 [ dev trc,00000] Attach to HDB : 1.00.68.384084 (NewDB100_REL) 8318 0.081686

4 ETW000 [ dev trc,00000] Database release is HDB 1.00.68.384084 47 0.081733

4 ETW000 [ dev trc,00000] INFO : Database 'HDB/00' instance is running on 'hanaserver' 7377 0.089110

4 ETW000 [ dev trc,00000] INFO : Connect to DB as 'SAPCM1', connection_id=209735 60746 0.149856

4 ETW000 [ dev trc,00000] DB max. input host variables : 32767 7295 0.157151

4 ETW000 [ dev trc,00000] DB max. statement length : 1048576 31 0.157182

4 ETW000 [ dev trc,00000] DB max. array size : 100000 20 0.157202

4 ETW000 [ dev trc,00000] use decimal precision as length 18 0.157220

4 ETW000 [ dev trc,00000] ABAPVARCHARMODE is used 18 0.157238

4 ETW000 [ dev trc,00000] INFO : DBSL buffer size = 1048576 24 0.157262

4 ETW000 [ dev trc,00000] Command info enabled 18 0.157280

4 ETW000 [ dev trc,00000] Now I'm connected to HDB 18 0.157298

4 ETW000 [ dev trc,00000] 00: hanaserver-HDB/00, since=20140603093900, ABAP= <unknown> (0) 26 0.157324

4 ETW000 [ dev trc,00000] } DbSlHDBConnect(rc=0) 17 0.157341

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=30,arg_p=0000000000000000) 23 0.157364

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 18 0.157382

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=21,arg_p=000000000214F740) 22 0.157404

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.157421

4 ETW000 [ dev trc,00000] Connection 0 opened (DBSL handle 0) 41 0.157462

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=7,arg_p=000000000214F790) 25 0.157487

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.157504

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=63,arg_p=000000000214F590) 30 0.157534

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 18 0.157552

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=21,arg_p=000000000214F5E0) 11495 0.169047

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 29 0.169076

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=11,arg_p=000000000214F700) 24 0.169100

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 18 0.169118

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=22,arg_p=000000000214F670) 22 0.169140

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.169157

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=13,arg_p=000000000214F540) 26 0.169183

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 20 0.169203

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=24,arg_p=000000000214F4F0) 22 0.169225

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 18 0.169243

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=51,arg_p=000000000214F4E0) 35 0.169278

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=15) 30 0.169308

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=000000000214F7C0,op=3,da_p=000000000214F820)

4 ETW000 37 0.169345

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=000000000214F790,da_p=000000000214F820,for_explain=0,lock=0,op=3)

4 ETW000 32 0.169377

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=27,op=3,#marker=0,#lob=0) 32 0.169409

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5AB30,ss_p=000000000214F7C0) 58 0.169467

4 ETW000 [ dev trc,00000] sc_p=0000000003C5AB30,no=0,idc_p=0000000000000000,con=0,act=0,slen=27,smax=256,#vars=0,stmt=000000000A9D1740,table=SVERS

4 ETW000 40 0.169507

4 ETW000 [ dev trc,00000] SELECT VERSION FROM SVERS ; 22 0.169529

4 ETW000 [ dev trc,00000] CURSOR C_0000 PREPARE on connection 0 20 0.169549

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 6877 0.176426

4 ETW000 [ dev trc,00000] { DbSlHDBRead(con_hdl=0,ss_p=000000000214F7C0,da_p=000000000214F820)

4 ETW000 44 0.176470

4 ETW000 [ dev trc,00000] ABAP USER is not set 24 0.176494

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5AB30,da_p=000000000214F820) 24 0.176518

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AB30,in_out=0,bulk=0,da_p=000000000214F820)

4 ETW000 29 0.176547

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=0) 20 0.176567

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AB30,in_out=0,bulk=0,types=0000000000000000,#col=0,useBulkInsertWithLobs=0)

4 ETW000 52 0.176619

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=0,rec_lng=0,db_lng=0

4 ETW000 33 0.176652

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=0, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 30 0.176682

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5AB30,ss_p=000000000214F7C0,bulk=0,in_out=1,da_p=000000000214F820)

4 ETW000 37 0.176719

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5AB30,ss_p=000000000214F7C0,in_out=1,da_p=000000000214F820)

4 ETW000 96 0.176815

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0000 on connection 0 82 0.176897

4 ETW000 [ dev trc,00000] CURSOR C_0000 SET InputSize=1 27 0.176924

4 ETW000 [ dev trc,00000] CURSOR C_0000 EXECUTE on connection 0 22 0.176946

4 ETW000 [ dev trc,00000] execute() of C_0000, #rec=0, rcSQL=0, rc=0 7102 0.184048

4 ETW000 [ dev trc,00000] CURSOR C_0000, rc=0,#rec=0,#dbcount=0 32 0.184080

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AB30,in_out=1,bulk=0,da_p=000000000214F820)

4 ETW000 32 0.184112

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=1) 21 0.184133

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AB30,in_out=1,bulk=0,types=000000000214F7F8,#col=1,useBulkInsertWithLobs=0)

4 ETW000 35 0.184168

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=72,rec_lng=144,db_lng=144

4 ETW000 30 0.184198

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=144, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 31 0.184229

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=1) 26 0.184255

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 35 0.184290

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AB30,in_out=1,bulk=0,arr_size=1,types=000000000214F7F8,da_p=000000000214F820)

4 ETW000 57 0.184347

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5AB30,bulk=0,da_p=000000000214F820)

4 ETW000 48 0.184395

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 26 0.184421

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5AB30) 25 0.184446

4 ETW000 [ dev trc,00000] CURSOR C_0000 FETCH (xcnt=1) on connection 0 26 0.184472

4 ETW000 [ dev trc,00000] next() of C_0000, rc=0 32 0.184504

4 ETW000 [ dev trc,00000] fetch() of C_0000, #rec=1, rc=0, rcSQL=0 34 0.184538

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5AB30,da_p=000000000214F820,rc=0)

4 ETW000 41 0.184579

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0000) 26 0.184605

4 ETW000 [ dev trc,00000] CURSOR C_0000 CLOSE resultset on connection 0 29 0.184634

4 ETW000 [ dev trc,00000] } DbSlHDBRead(rc=0) 29 0.184663

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=43,arg_p=000000013FA7AB06) 23 0.184686

4 ETW000 [ dev trc,00000] INFO : SAP RELEASE (DB) = 740 20 0.184706

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.184723

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=41,arg_p=000000013FA7AB98) 93 0.184816

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 22 0.184838

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=14,arg_p=0000000002145B68) 22 0.184860

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.184877

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=50,arg_p=0000000002145B60) 22 0.184899

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 27 0.184926

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=52,arg_p=0000000002145BD0) 23 0.184949

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.184966

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=20,arg_p=00000001419474F0) 117 0.185083

4 ETW000 [ dev trc,00000] INFO : STMT SIZE = 1048576 22 0.185105

4 ETW000 [ dev trc,00000] INFO : MARKER_CNT = 32767 19 0.185124

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 18 0.185142

4 ETW000 [ dev trc,00000] NTAB: SELECT COMPCNT, UNICODELG FROM DDNTT WHERE TABNAME = 'SVERS'...

4 ETW000 37 0.185179

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=0000000002145440,op=3,da_p=0000000002145490)

4 ETW000 30 0.185209

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=0000000002145460,da_p=0000000002145490,for_explain=0,lock=0,op=3)

4 ETW000 31 0.185240

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=63,op=3,#marker=0,#lob=0) 22 0.185262

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5ABC8,ss_p=0000000002145440) 44 0.185306

4 ETW000 [ dev trc,00000] sc_p=0000000003C5ABC8,no=1,idc_p=0000000000000000,con=0,act=0,slen=63,smax=256,#vars=0,stmt=000000000AA49A00,table=DDNTT

4 ETW000 37 0.185343

4 ETW000 [ dev trc,00000] SELECT COMPCNT, UNICODELG FROM "DDNTT" WHERE TABNAME = 'SVERS' ; 22 0.185365

4 ETW000 [ dev trc,00000] CURSOR C_0001 PREPARE on connection 0 19 0.185384

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 7108 0.192492

4 ETW000 [ dev trc,00000] db_con_test_and_open: 1 open cursors (delta=1) 27 0.192519

4 ETW000 [ dev trc,00000] db_con_check_dirty: 1 open cursors, tx = NO , bc = NO 18 0.192537

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 15 0.192552

4 ETW000 [ dev trc,00000] { DbSlHDBBegRead(con_hdl=0,ss_p=0000000002145440,da_p=0000000002145490)

4 ETW000 33 0.192585

4 ETW000 [ dev trc,00000] ABAP USER is not set 23 0.192608

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5ABC8,da_p=0000000002145490) 23 0.192631

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5ABC8,in_out=0,bulk=0,da_p=0000000002145490)

4 ETW000 29 0.192660

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=0) 22 0.192682

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5ABC8,in_out=0,bulk=0,types=0000000000000000,#col=0,useBulkInsertWithLobs=0)

4 ETW000 36 0.192718

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=0,rec_lng=0,db_lng=0

4 ETW000 38 0.192756

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=0, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 54 0.192810

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5ABC8,ss_p=0000000002145440,bulk=0,in_out=1,da_p=0000000002145490)

4 ETW000 43 0.192853

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5ABC8,ss_p=0000000002145440,in_out=1,da_p=0000000002145490)

4 ETW000 31 0.192884

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0001 on connection 0 20 0.192904

4 ETW000 [ dev trc,00000] CURSOR C_0001 SET InputSize=1 19 0.192923

4 ETW000 [ dev trc,00000] CURSOR C_0001 EXECUTE on connection 0 20 0.192943

4 ETW000 [ dev trc,00000] execute() of C_0001, #rec=0, rcSQL=0, rc=0 8517 0.201460

4 ETW000 [ dev trc,00000] CURSOR C_0001, rc=0,#rec=0,#dbcount=0 33 0.201493

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5ABC8,in_out=1,bulk=1,da_p=0000000002145490)

4 ETW000 31 0.201524

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=2) 20 0.201544

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5ABC8,in_out=1,bulk=1,types=0000000002145520,#col=2,useBulkInsertWithLobs=0)

4 ETW000 34 0.201578

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=2,#int=0,#llong=0,#uc=0,rec_lng=16,db_lng=4

4 ETW000 28 0.201606

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=16, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=65536)

4 ETW000 31 0.201637

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=65536) 20 0.201657

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 33 0.201690

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5ABC8,in_out=1,bulk=1,arr_size=65536,types=0000000002145520,da_p=0000000002145490)

4 ETW000 36 0.201726

4 ETW000 [ dev trc,00000] } DbSlHDBBegRead(rc=0) 35 0.201761

4 ETW000 [ dev trc,00000] { DbSlHDBExeRead(con_hdl=0,ss_p=0000000002145440,da_p=0000000002145490)

4 ETW000 54 0.201815

4 ETW000 [ dev trc,00000] ABAP USER is not set 26 0.201841

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5ABC8,bulk=0,da_p=0000000002145490)

4 ETW000 33 0.201874

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 19 0.201893

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5ABC8) 20 0.201913

4 ETW000 [ dev trc,00000] CURSOR C_0001 FETCH (xcnt=1) on connection 0 20 0.201933

4 ETW000 [ dev trc,00000] next() of C_0001, rc=0 23 0.201956

4 ETW000 [ dev trc,00000] fetch() of C_0001, #rec=1, rc=0, rcSQL=0 38 0.201994

4 ETW000 [ dev trc,00000] } DbSlHDBExeRead(rc=0) 20 0.202014

4 ETW000 [ dev trc,00000] db_con_test_and_open: 0 open cursors (delta=-1) 18 0.202032

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = NO 16 0.202048

4 ETW000 [ dev trc,00000] { DbSlHDBEndRead(con_hdl=0,ss_p=0000000002145440,da_p=0000000002145490)

4 ETW000 27 0.202075

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5ABC8,da_p=0000000002145490,rc=0)

4 ETW000 28 0.202103

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0001) 19 0.202122

4 ETW000 [ dev trc,00000] CURSOR C_0001 CLOSE resultset on connection 0 19 0.202141

4 ETW000 [ dev trc,00000] } DbSlHDBEndRead(rc=0) 23 0.202164

4 ETW000 [ dev trc,00000] NTAB: Structure of NTAB on DB is VERS_B, unicodelg of executable is 2, unicodelg of SVERS in nametab is 2

4 ETW000 34 0.202198

4 ETW000 [ dev trc,00000] NTAB: standalone processing 137 0.202335

4 ETW000 [ dev trc,00000] NTAB: ntprofile(), read profile 18 0.202353

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

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

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

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

4 ETW000 [ dev trc,00000] NTAB: compute_hash_card: 2003. 17 0.202430

4 ETW000 [ dev trc,00000] NTAB: maxcnt 1000. 30 0.202460

4 ETW000 [ dev trc,00000] NTAB: hfactor 2003. 15 0.202475

4 ETW000 [ dev trc,00000] NTAB: mem_protocol_size 112 15 0.202490

4 ETW000 [ dev trc,00000] NTAB: hdr_backpack_offset 0 15 0.202505

4 ETW000 [ dev trc,00000] NTAB: hdr_backpack_size 0 14 0.202519

4 ETW000 [ dev trc,00000] NTAB: FTAB: header_size 88. 16 0.202535

4 ETW000 [ dev trc,00000] NTAB: FTAB: item_size 64. 14 0.202549

4 ETW000 [ dev trc,00000] NTAB: FTAB: item_cnt 1000. 15 0.202564

4 ETW000 [ dev trc,00000] NTAB: FTAB: unit_size 4 15 0.202579

4 ETW000 [ dev trc,00000] NTAB: FTAB: unit_cnt 128000. 70 0.202649

4 ETW000 [ dev trc,00000] NTAB: FTAB: data_size 512000. 20 0.202669

4 ETW000 [ dev trc,00000] NTAB: IREC: header_size 88. 15 0.202684

4 ETW000 [ dev trc,00000] NTAB: IREC: item_size 64. 15 0.202699

4 ETW000 [ dev trc,00000] NTAB: IREC: item_cnt 250. 15 0.202714

4 ETW000 [ dev trc,00000] NTAB: IREC: unit_size 16 15 0.202729

4 ETW000 [ dev trc,00000] NTAB: IREC: unit_cnt 6400. 23 0.202752

4 ETW000 [ dev trc,00000] NTAB: IREC: data_size 102400. 26 0.202778

4 ETW000 [ dev trc,00000] NTAB: STAB: header_size 88. 26 0.202804

4 ETW000 [ dev trc,00000] NTAB: STAB: item_size 64. 38 0.202842

4 ETW000 [ dev trc,00000] NTAB: STAB: item_cnt 250. 18 0.202860

4 ETW000 [ dev trc,00000] NTAB: STAB: unit_size 4 15 0.202875

4 ETW000 [ dev trc,00000] NTAB: STAB: unit_cnt 25600. 15 0.202890

4 ETW000 [ dev trc,00000] NTAB: STAB: data_size 102400. 15 0.202905

4 ETW000 [ dev trc,00000] NTAB: TTAB: header_size 200. 16 0.202921

4 ETW000 [ dev trc,00000] NTAB: TTAB: item_size 40. 14 0.202935

4 ETW000 [ dev trc,00000] NTAB: TTAB: item_cnt 1000. 15 0.202950

4 ETW000 [ dev trc,00000] NTAB: TTAB: unit_size 256 15 0.202965

4 ETW000 [ dev trc,00000] NTAB: TTAB: unit_cnt 1000. 15 0.202980

4 ETW000 [ dev trc,00000] NTAB: TTAB: data_size 256000. 16 0.202996

4 ETW000 [ dev trc,00000] NTAB: FTAB: hh_p 3d0dac0, hh_len 88 649 0.203645

4 ETW000 [ dev trc,00000] NTAB: FTAB: aa_p aa4a800, aa_len 16024 22 0.203667

4 ETW000 [ dev trc,00000] NTAB: FTAB: ia_p aa4e6a0, ia_len 64000 16 0.203683

4 ETW000 [ dev trc,00000] NTAB: FTAB: dat_p aa5e0b0, dat_len 512000 16 0.203699

4 ETW000 [ dev trc,00000] NTAB: IREC: hh_p 3d0da60, hh_len 88 16 0.203715

4 ETW000 [ dev trc,00000] NTAB: IREC: aa_p aadb0c0, aa_len 16024 16 0.203731

4 ETW000 [ dev trc,00000] NTAB: IREC: ia_p aadef60, ia_len 16000 26 0.203757

4 ETW000 [ dev trc,00000] NTAB: IREC: dat_p aae2df0, dat_len 102400 29 0.203786

4 ETW000 [ dev trc,00000] NTAB: STAB: hh_p 3d0ddc0, hh_len 88 23 0.203809

4 ETW000 [ dev trc,00000] NTAB: STAB: aa_p aafbe00, aa_len 16024 17 0.203826

4 ETW000 [ dev trc,00000] NTAB: STAB: ia_p aaffca0, ia_len 16000 16 0.203842

4 ETW000 [ dev trc,00000] NTAB: STAB: dat_p ab03b30, dat_len 102400 16 0.203858

4 ETW000 [ dev trc,00000] NTAB: TTAB: hh_p aa4a440, hh_len 200 15 0.203873

4 ETW000 [ dev trc,00000] NTAB: TTAB: aa_p ab1cb40, aa_len 16024 16 0.203889

4 ETW000 [ dev trc,00000] NTAB: TTAB: ia_p ab209e0, ia_len 40000 22 0.203911

4 ETW000 [ dev trc,00000] NTAB: TTAB: dat_p ab2a630, dat_len 256000 19 0.203930

4 ETW000 [ dev trc,00000] NTAB: ntab_mem_protocol aa4a440, ntab_mp_p aa4a440, ntab_proc_id 0 27 0.203957

4 ETW000 [ dev trc,00000] NTAB: FTAB: hh_p 3d0dac0, ha_list aa4a800, hi_list aa4e6a0, buffer aa5e0b0

4 ETW000 73 0.204030

4 ETW000 [ dev trc,00000] NTAB: IREC: hh_p 3d0da60, ha_list aadb0c0, hi_list aadef60, buffer aae2df0

4 ETW000 25 0.204055

4 ETW000 [ dev trc,00000] NTAB: STAB: hh_p 3d0ddc0, ha_list aafbe00, hi_list aaffca0, buffer ab03b30

4 ETW000 24 0.204079

4 ETW000 [ dev trc,00000] NTAB: TTAB: hh_p aa4a4b0, ha_list ab1cb40, hi_list ab209e0, buffer ab2a630

4 ETW000 23 0.204102

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

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

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

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

4 ETW000 [ dev trc,00000] db_con_test_and_open: 1 open cursors (delta=1) 53 0.204268

4 ETW000 [ dev trc,00000] db_con_check_dirty: 1 open cursors, tx = NO , bc = NO 19 0.204287

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 15 0.204302

4 ETW000 [ dev trc,00000] { DbSlHDBBegRead(con_hdl=0,ss_p=00000001414FAFB0,da_p=00000001414F8D80)

4 ETW000 28 0.204330

4 ETW000 [ dev trc,00000] } DbSlHDBBegRead(rc=13) 20 0.204350

4 ETW000 [ dev trc,00000] db_con_add_open_cursors called for: 16 0.204366

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

4 ETW000 34 0.204400

4 ETW000 [ dev trc,00000] db_con_add_open_cursors: 0 open cursors (delta=-1) 17 0.204417

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=00000001414FAFB0,op=3,da_p=00000001414F8D80)

4 ETW000 28 0.204445

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=00000001415040C0,da_p=00000001414F8D80,for_explain=0,lock=2,op=3)

4 ETW000 31 0.204476

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=103,op=3,#marker=1,#lob=0) 27 0.204503

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5AC60,ss_p=00000001414FAFB0) 63 0.204566

4 ETW000 [ dev trc,00000] sc_p=0000000003C5AC60,no=2,idc_p=0000000003CC6230,con=0,act=0,slen=103,smax=256,#vars=1,stmt=000000000AA497E0,table=DDNTF

4 ETW000 39 0.204605

4 ETW000 [ dev trc,00000] stmtid = <10/DDNTF /1045/20140526152644>

4 ETW000 32 0.204637

4 ETW000 [ dev trc,00000] SELECT TABNAME, BLOCKNR, FIELDSLG, FIELDS FROM "DDNTF" WHERE TABNAME = ? ORDER BY TABNAME, BLOCK\

4 ETW000 29 0.204666

4 ETW000 [ dev trc,00000] NR ; 17 0.204683

4 ETW000 [ dev trc,00000] CURSOR C_0002 PREPARE on connection 0 20 0.204703

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 7307 0.212010

4 ETW000 [ dev trc,00000] db_con_test_and_open: 1 open cursors (delta=1) 25 0.212035

4 ETW000 [ dev trc,00000] db_con_check_dirty: 1 open cursors, tx = NO , bc = NO 17 0.212052

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 15 0.212067

4 ETW000 [ dev trc,00000] { DbSlHDBBegRead(con_hdl=0,ss_p=00000001414FAFB0,da_p=00000001414F8D80)

4 ETW000 28 0.212095

4 ETW000 [ dev trc,00000] ABAP USER is not set 23 0.212118

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5AC60,da_p=00000001414F8D80) 23 0.212141

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AC60,in_out=0,bulk=0,da_p=00000001414F8D80)

4 ETW000 28 0.212169

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=1) 20 0.212189

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AC60,in_out=0,bulk=0,types=0000000141504250,#col=1,useBulkInsertWithLobs=0)

4 ETW000 33 0.212222

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=30,rec_lng=64,db_lng=60

4 ETW000 29 0.212251

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=64, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 30 0.212281

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=0,row_cnt=1) 20 0.212301

4 ETW000 [ dev trc,00000] -> allocData(in_out=0,size=1048576) 19 0.212320

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AC60,in_out=0,bulk=0,arr_size=1,types=0000000141504250,da_p=00000001414F8D80)

4 ETW000 33 0.212353

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5AC60,ss_p=00000001414FAFB0,bulk=0,in_out=1,da_p=00000001414F8D80)

4 ETW000 36 0.212389

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5AC60,ss_p=00000001414FAFB0,in_out=1,da_p=00000001414F8D80)

4 ETW000 36 0.212425

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0002 on connection 0 19 0.212444

4 ETW000 [ dev trc,00000] CURSOR C_0002 SET InputSize=1 19 0.212463

4 ETW000 [ dev trc,00000] CURSOR C_0002 EXECUTE on connection 0 25 0.212488

4 ETW000 [ dev trc,00000] execute() of C_0002, #rec=0, rcSQL=0, rc=0 7645 0.220133

4 ETW000 [ dev trc,00000] Tue Jun 03 09:39:01 2014 13630 0.233763

4 ETW000 [ dev trc,00000] CURSOR C_0002, rc=0,#rec=0,#dbcount=0 22 0.233785

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AC60,in_out=1,bulk=1,da_p=00000001414F8D80)

4 ETW000 31 0.233816

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=4) 21 0.233837

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AC60,in_out=1,bulk=1,types=0000000141504250,#col=4,useBulkInsertWithLobs=0)

4 ETW000 36 0.233873

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=16386,#int=1,#llong=0,#uc=30,rec_lng=32848,db_lng=32836

4 ETW000 31 0.233904

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=32848, lob_cnt=0, lob_pw_cnt=0, long_cnt=1, ins_bulk_lob=0, row_max=31)

4 ETW000 31 0.233935

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=31) 19 0.233954

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 29 0.233983

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AC60,in_out=1,bulk=1,arr_size=31,types=0000000141504250,da_p=00000001414F8D80)

4 ETW000 44 0.234027

4 ETW000 [ dev trc,00000] } DbSlHDBBegRead(rc=0) 28 0.234055

4 ETW000 [ dev trc,00000] { DbSlHDBExeRead(con_hdl=0,ss_p=00000001414FAFB0,da_p=00000001414F8D80)

4 ETW000 31 0.234086

4 ETW000 [ dev trc,00000] ABAP USER is not set 19 0.234105

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5AC60,bulk=0,da_p=00000001414F8D80)

4 ETW000 29 0.234134

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 19 0.234153

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5AC60) 20 0.234173

4 ETW000 [ dev trc,00000] CURSOR C_0002 FETCH (xcnt=31) on connection 0 20 0.234193

4 ETW000 [ dev trc,00000] next() of C_0002, rc=0 23 0.234216

4 ETW000 [ dev trc,00000] fetch() of C_0002, #rec=1, rc=0, rcSQL=0 6792 0.241008

4 ETW000 [ dev trc,00000] } DbSlHDBExeRead(rc=0) 41 0.241049

4 ETW000 [ dev trc,00000] db_con_test_and_open: 0 open cursors (delta=-1) 20 0.241069

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = NO 15 0.241084

4 ETW000 [ dev trc,00000] { DbSlHDBEndRead(con_hdl=0,ss_p=00000001414FAFB0,da_p=00000001414F8D80)

4 ETW000 28 0.241112

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5AC60,da_p=00000001414F8D80,rc=0)

4 ETW000 31 0.241143

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0002) 21 0.241164

4 ETW000 [ dev trc,00000] CURSOR C_0002 CLOSE resultset on connection 0 21 0.241185

4 ETW000 [ dev trc,00000] } DbSlHDBEndRead(rc=0) 6936 0.248121

4 ETW000 [ dev trc,00000] { DbSlHDBRead(con_hdl=0,ss_p=00000001414FA530,da_p=00000001414FADC0)

4 ETW000 92 0.248213

4 ETW000 [ dev trc,00000] } DbSlHDBRead(rc=13) 20 0.248233

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=00000001414FA530,op=3,da_p=00000001414FADC0)

4 ETW000 29 0.248262

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=00000001415036C0,da_p=00000001414FADC0,for_explain=0,lock=2,op=3)

4 ETW000 30 0.248292

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=246,op=3,#marker=1,#lob=0) 23 0.248315

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5ACF8,ss_p=00000001414FA530) 39 0.248354

4 ETW000 [ dev trc,00000] sc_p=0000000003C5ACF8,no=3,idc_p=0000000003CC62C0,con=0,act=0,slen=246,smax=256,#vars=1,stmt=000000000AB856B0,table=DDNTT

4 ETW000 37 0.248391

4 ETW000 [ dev trc,00000] stmtid = <10/DDNTT /11622/20140526152642>

4 ETW000 27 0.248418

4 ETW000 [ dev trc,00000] SELECT VERSION, UUID, CRTIMESTMP, ABTIMESTMP, DYTIMESTMP, FLDCNT, TABLENGTH, KEYCNT, KEYLENGTH, CL\

4 ETW000 29 0.248447

4 ETW000 [ dev trc,00000] IENTPOS, TABTYPE, TABFORM, REFNAME, HFLAG, BUFSTATE, BUFPARM, ALIGN, POINTERLG, UNICODELG, COMPCNT\

4 ETW000 28 0.248475

4 ETW000 [ dev trc,00000] , LEAFCNT FROM "DDNTT" WHERE TABNAME = ? ; 19 0.248494

4 ETW000 [ dev trc,00000] CURSOR C_0003 PREPARE on connection 0 43 0.248537

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 6857 0.255394

4 ETW000 [ dev trc,00000] { DbSlHDBRead(con_hdl=0,ss_p=00000001414FA530,da_p=00000001414FADC0)

4 ETW000 37 0.255431

4 ETW000 [ dev trc,00000] ABAP USER is not set 23 0.255454

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5ACF8,da_p=00000001414FADC0) 23 0.255477

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5ACF8,in_out=0,bulk=0,da_p=00000001414FADC0)

4 ETW000 28 0.255505

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=1) 20 0.255525

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5ACF8,in_out=0,bulk=0,types=00000001414F9C80,#col=1,useBulkInsertWithLobs=0)

4 ETW000 33 0.255558

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=30,rec_lng=64,db_lng=60

4 ETW000 31 0.255589

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=64, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 32 0.255621

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=0,row_cnt=1) 20 0.255641

4 ETW000 [ dev trc,00000] -> allocData(in_out=0,size=1048576) 19 0.255660

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5ACF8,in_out=0,bulk=0,arr_size=1,types=00000001414F9C80,da_p=00000001414FADC0)

4 ETW000 33 0.255693

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5ACF8,ss_p=00000001414FA530,bulk=0,in_out=1,da_p=00000001414FADC0)

4 ETW000 37 0.255730

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5ACF8,ss_p=00000001414FA530,in_out=1,da_p=00000001414FADC0)

4 ETW000 51 0.255781

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0003 on connection 0 33 0.255814

4 ETW000 [ dev trc,00000] CURSOR C_0003 SET InputSize=1 33 0.255847

4 ETW000 [ dev trc,00000] CURSOR C_0003 EXECUTE on connection 0 27 0.255874

4 ETW000 [ dev trc,00000] execute() of C_0003, #rec=0, rcSQL=0, rc=0 6564 0.262438

4 ETW000 [ dev trc,00000] CURSOR C_0003, rc=0,#rec=0,#dbcount=0 31 0.262469

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5ACF8,in_out=1,bulk=0,da_p=00000001414FADC0)

4 ETW000 31 0.262500

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=21) 28 0.262528

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5ACF8,in_out=1,bulk=0,types=00000001414F9CA8,#col=21,useBulkInsertWithLobs=0)

4 ETW000 44 0.262572

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=10,#int=1,#llong=0,#uc=76,rec_lng=208,db_lng=198

4 ETW000 30 0.262602

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=208, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 31 0.262633

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=1) 19 0.262652

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 20 0.262672

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5ACF8,in_out=1,bulk=0,arr_size=1,types=00000001414F9CA8,da_p=00000001414FADC0)

4 ETW000 33 0.262705

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5ACF8,bulk=0,da_p=00000001414FADC0)

4 ETW000 63 0.262768

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 32 0.262800

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5ACF8) 31 0.262831

4 ETW000 [ dev trc,00000] CURSOR C_0003 FETCH (xcnt=1) on connection 0 29 0.262860

4 ETW000 [ dev trc,00000] next() of C_0003, rc=0 25 0.262885

4 ETW000 [ dev trc,00000] fetch() of C_0003, #rec=1, rc=0, rcSQL=0 43 0.262928

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5ACF8,da_p=00000001414FADC0,rc=0)

4 ETW000 31 0.262959

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0003) 20 0.262979

4 ETW000 [ dev trc,00000] CURSOR C_0003 CLOSE resultset on connection 0 19 0.262998

4 ETW000 [ dev trc,00000] } DbSlHDBRead(rc=0) 24 0.263022

4 ETW000 [ dev trc,00000] { db_rtab( fcode = 'RT_READ_ONLY', tname = 'TADIR' ) {D:/depot/bas/741_REL/src/cts/R3trans/twdbcall.c:869}

4 ETW000 47 0.263069

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=12,arg_p=0000000141511020) 49 0.263118

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 19 0.263137

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=20,arg_p=0000000141511028) 22 0.263159

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.263176

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=2,arg_p=0000000141511038) 22 0.263198

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.263215

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=10,arg_p=0000000141511040) 21 0.263236

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 17 0.263253

4 ETW000 [ dev trc,00000] { DbSlHDBRead(con_hdl=0,ss_p=0000000141511070,da_p=0000000141510268)

4 ETW000 91 0.263344

4 ETW000 [ dev trc,00000] } DbSlHDBRead(rc=13) 19 0.263363

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=0000000141511070,op=3,da_p=0000000141510268)

4 ETW000 41 0.263404

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=0000000141510FA8,da_p=0000000141510268,for_explain=0,lock=0,op=3)

4 ETW000 30 0.263434

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=82,op=3,#marker=3,#lob=0) 28 0.263462

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5AD90,ss_p=0000000141511070) 38 0.263500

4 ETW000 [ dev trc,00000] sc_p=0000000003C5AD90,no=4,idc_p=0000000003CC6350,con=0,act=0,slen=82,smax=256,#vars=3,stmt=000000000AB8C3D0,table=TADIR

4 ETW000 37 0.263537

4 ETW000 [ dev trc,00000] stmtid = <3/TADIR /1073741825/20140124095500>

4 ETW000 28 0.263565

4 ETW000 [ dev trc,00000] SELECT TOP 1 * FROM "TADIR" WHERE "PGMID" = ? AND "OBJECT" = ? AND "OBJ_NAME" = ? ;

4 ETW000 27 0.263592

4 ETW000 [ dev trc,00000] CURSOR C_0004 PREPARE on connection 0 20 0.263612

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 6776 0.270388

4 ETW000 [ dev trc,00000] { DbSlHDBRead(con_hdl=0,ss_p=0000000141511070,da_p=0000000141510268)

4 ETW000 40 0.270428

4 ETW000 [ dev trc,00000] ABAP USER is not set 22 0.270450

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5AD90,da_p=0000000141510268) 23 0.270473

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AD90,in_out=0,bulk=0,da_p=0000000141510268)

4 ETW000 28 0.270501

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=3) 20 0.270521

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AD90,in_out=0,bulk=0,types=000000000AB865E0,#col=3,useBulkInsertWithLobs=0)

4 ETW000 33 0.270554

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=48,rec_lng=96,db_lng=96

4 ETW000 28 0.270582

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=96, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 31 0.270613

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=0,row_cnt=1) 19 0.270632

4 ETW000 [ dev trc,00000] -> allocData(in_out=0,size=1048576) 19 0.270651

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AD90,in_out=0,bulk=0,arr_size=1,types=000000000AB865E0,da_p=0000000141510268)

4 ETW000 33 0.270684

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5AD90,ss_p=0000000141511070,bulk=0,in_out=1,da_p=0000000141510268)

4 ETW000 37 0.270721

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5AD90,ss_p=0000000141511070,in_out=1,da_p=0000000141510268)

4 ETW000 86 0.270807

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0004 on connection 0 27 0.270834

4 ETW000 [ dev trc,00000] CURSOR C_0004 SET InputSize=1 19 0.270853

4 ETW000 [ dev trc,00000] CURSOR C_0004 EXECUTE on connection 0 20 0.270873

4 ETW000 [ dev trc,00000] execute() of C_0004, #rec=0, rcSQL=0, rc=0 6840 0.277713

4 ETW000 [ dev trc,00000] CURSOR C_0004, rc=0,#rec=0,#dbcount=0 60 0.277773

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AD90,in_out=1,bulk=0,da_p=0000000141510268)

4 ETW000 34 0.277807

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=22) 21 0.277828

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AD90,in_out=1,bulk=0,types=000000000AB8EA30,#col=22,useBulkInsertWithLobs=0)

4 ETW000 34 0.277862

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=232,rec_lng=464,db_lng=464

4 ETW000 88 0.277950

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=464, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 42 0.277992

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=1) 21 0.278013

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 20 0.278033

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AD90,in_out=1,bulk=0,arr_size=1,types=000000000AB8EA30,da_p=0000000141510268)

4 ETW000 33 0.278066

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5AD90,bulk=0,da_p=0000000141510268)

4 ETW000 62 0.278128

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 20 0.278148

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5AD90) 20 0.278168

4 ETW000 [ dev trc,00000] CURSOR C_0004 FETCH (xcnt=1) on connection 0 20 0.278188

4 ETW000 [ dev trc,00000] next() of C_0004, rc=0 21 0.278209

4 ETW000 [ dev trc,00000] fetch() of C_0004, #rec=1, rc=0, rcSQL=0 31 0.278240

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5AD90,da_p=0000000141510268,rc=0)

4 ETW000 31 0.278271

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0004) 19 0.278290

4 ETW000 [ dev trc,00000] CURSOR C_0004 CLOSE resultset on connection 0 20 0.278310

4 ETW000 [ dev trc,00000] } DbSlHDBRead(rc=0) 23 0.278333

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

4 ETW000 Connected to DBMS = HDB --- SERVER = '' PORT = '' --- SYSTEM = 'CM1'.

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=13,arg_p=00000001416D5290) 88 0.278440

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 22 0.278462

4 ETW000 [ dev trc,00000] { db_rtab( fcode = 'RT_INSERT', tname = 'PATCHHIST' ) {D:/depot/bas/741_REL/src/flat/saprel.c:1461}

4 ETW000 115 0.278577

4 ETW000 [ dev trc,00000] { DbSlHDBRead(con_hdl=0,ss_p=00000001414FA530,da_p=00000001414FADC0)

4 ETW000 34 0.278611

4 ETW000 [ dev trc,00000] ABAP USER is not set 23 0.278634

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5ACF8,da_p=00000001414FADC0) 23 0.278657

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5ACF8,in_out=0,bulk=0,da_p=00000001414FADC0)

4 ETW000 28 0.278685

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=1) 20 0.278705

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5ACF8,in_out=0,bulk=0,types=00000001414F9C80,#col=1,useBulkInsertWithLobs=0)

4 ETW000 33 0.278738

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=30,rec_lng=64,db_lng=60

4 ETW000 34 0.278772

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=64, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 31 0.278803

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=0,row_cnt=1) 20 0.278823

4 ETW000 [ dev trc,00000] -> allocData(in_out=0,size=1048576) 19 0.278842

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5ACF8,in_out=0,bulk=0,arr_size=1,types=00000001414F9C80,da_p=00000001414FADC0)

4 ETW000 34 0.278876

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5ACF8,ss_p=00000001414FA530,bulk=0,in_out=1,da_p=00000001414FADC0)

4 ETW000 35 0.278911

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5ACF8,ss_p=00000001414FA530,in_out=1,da_p=00000001414FADC0)

4 ETW000 32 0.278943

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0003 on connection 0 19 0.278962

4 ETW000 [ dev trc,00000] CURSOR C_0003 SET InputSize=1 19 0.278981

4 ETW000 [ dev trc,00000] CURSOR C_0003 EXECUTE on connection 0 20 0.279001

4 ETW000 [ dev trc,00000] execute() of C_0003, #rec=0, rcSQL=0, rc=0 6730 0.285731

4 ETW000 [ dev trc,00000] CURSOR C_0003, rc=0,#rec=0,#dbcount=0 45 0.285776

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5ACF8,in_out=1,bulk=0,da_p=00000001414FADC0)

4 ETW000 51 0.285827

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=21) 23 0.285850

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5ACF8,in_out=1,bulk=0,types=00000001414F9CA8,#col=21,useBulkInsertWithLobs=0)

4 ETW000 34 0.285884

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=10,#int=1,#llong=0,#uc=76,rec_lng=208,db_lng=198

4 ETW000 29 0.285913

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=208, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 30 0.285943

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=1) 20 0.285963

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 19 0.285982

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5ACF8,in_out=1,bulk=0,arr_size=1,types=00000001414F9CA8,da_p=00000001414FADC0)

4 ETW000 33 0.286015

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5ACF8,bulk=0,da_p=00000001414FADC0)

4 ETW000 49 0.286064

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 20 0.286084

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5ACF8) 22 0.286106

4 ETW000 [ dev trc,00000] CURSOR C_0003 FETCH (xcnt=1) on connection 0 22 0.286128

4 ETW000 [ dev trc,00000] next() of C_0003, rc=0 22 0.286150

4 ETW000 [ dev trc,00000] fetch() of C_0003, #rec=1, rc=0, rcSQL=0 32 0.286182

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5ACF8,da_p=00000001414FADC0,rc=0)

4 ETW000 31 0.286213

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0003) 19 0.286232

4 ETW000 [ dev trc,00000] CURSOR C_0003 CLOSE resultset on connection 0 19 0.286251

4 ETW000 [ dev trc,00000] } DbSlHDBRead(rc=0) 23 0.286274

4 ETW000 [ dev trc,00000] db_con_test_and_open: 1 open cursors (delta=1) 23 0.286297

4 ETW000 [ dev trc,00000] db_con_check_dirty: 1 open cursors, tx = NO , bc = NO 17 0.286314

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 16 0.286330

4 ETW000 [ dev trc,00000] { DbSlHDBBegRead(con_hdl=0,ss_p=00000001414FAFB0,da_p=00000001414F8D80)

4 ETW000 27 0.286357

4 ETW000 [ dev trc,00000] ABAP USER is not set 22 0.286379

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5AC60,da_p=00000001414F8D80) 23 0.286402

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AC60,in_out=0,bulk=0,da_p=00000001414F8D80)

4 ETW000 28 0.286430

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=1) 20 0.286450

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AC60,in_out=0,bulk=0,types=0000000141504250,#col=1,useBulkInsertWithLobs=0)

4 ETW000 33 0.286483

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=30,rec_lng=64,db_lng=60

4 ETW000 28 0.286511

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=64, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 30 0.286541

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=0,row_cnt=1) 20 0.286561

4 ETW000 [ dev trc,00000] -> allocData(in_out=0,size=1048576) 19 0.286580

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AC60,in_out=0,bulk=0,arr_size=1,types=0000000141504250,da_p=00000001414F8D80)

4 ETW000 33 0.286613

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5AC60,ss_p=00000001414FAFB0,bulk=0,in_out=1,da_p=00000001414F8D80)

4 ETW000 35 0.286648

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5AC60,ss_p=00000001414FAFB0,in_out=1,da_p=00000001414F8D80)

4 ETW000 31 0.286679

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0002 on connection 0 19 0.286698

4 ETW000 [ dev trc,00000] CURSOR C_0002 SET InputSize=1 32 0.286730

4 ETW000 [ dev trc,00000] CURSOR C_0002 EXECUTE on connection 0 36 0.286766

4 ETW000 [ dev trc,00000] execute() of C_0002, #rec=0, rcSQL=0, rc=0 6787 0.293553

4 ETW000 [ dev trc,00000] CURSOR C_0002, rc=0,#rec=0,#dbcount=0 32 0.293585

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AC60,in_out=1,bulk=1,da_p=00000001414F8D80)

4 ETW000 32 0.293617

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=4) 20 0.293637

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AC60,in_out=1,bulk=1,types=0000000141504250,#col=4,useBulkInsertWithLobs=0)

4 ETW000 34 0.293671

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=16386,#int=1,#llong=0,#uc=30,rec_lng=32848,db_lng=32836

4 ETW000 30 0.293701

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=32848, lob_cnt=0, lob_pw_cnt=0, long_cnt=1, ins_bulk_lob=0, row_max=31)

4 ETW000 31 0.293732

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=31) 34 0.293766

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 35 0.293801

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AC60,in_out=1,bulk=1,arr_size=31,types=0000000141504250,da_p=00000001414F8D80)

4 ETW000 56 0.293857

4 ETW000 [ dev trc,00000] } DbSlHDBBegRead(rc=0) 28 0.293885

4 ETW000 [ dev trc,00000] { DbSlHDBExeRead(con_hdl=0,ss_p=00000001414FAFB0,da_p=00000001414F8D80)

4 ETW000 29 0.293914

4 ETW000 [ dev trc,00000] ABAP USER is not set 20 0.293934

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5AC60,bulk=0,da_p=00000001414F8D80)

4 ETW000 29 0.293963

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 19 0.293982

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5AC60) 19 0.294001

4 ETW000 [ dev trc,00000] CURSOR C_0002 FETCH (xcnt=31) on connection 0 21 0.294022

4 ETW000 [ dev trc,00000] next() of C_0002, rc=0 21 0.294043

4 ETW000 [ dev trc,00000] fetch() of C_0002, #rec=1, rc=0, rcSQL=0 6462 0.300505

4 ETW000 [ dev trc,00000] } DbSlHDBExeRead(rc=0) 34 0.300539

4 ETW000 [ dev trc,00000] db_con_test_and_open: 0 open cursors (delta=-1) 19 0.300558

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = NO 16 0.300574

4 ETW000 [ dev trc,00000] { DbSlHDBEndRead(con_hdl=0,ss_p=00000001414FAFB0,da_p=00000001414F8D80)

4 ETW000 28 0.300602

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5AC60,da_p=00000001414F8D80,rc=0)

4 ETW000 31 0.300633

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0002) 22 0.300655

4 ETW000 [ dev trc,00000] CURSOR C_0002 CLOSE resultset on connection 0 20 0.300675

4 ETW000 [ dev trc,00000] } DbSlHDBEndRead(rc=0) 6540 0.307215

4 ETW000 [ dev trc,00000] db_con_check_dirty: 0 open cursors, tx = YES, bc = NO 35 0.307250

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 17 0.307267

4 ETW000 [ dev trc,00000] { DbSlHDBModify(con_hdl=0,ss_p=0000000141511070,mod=0,da_p=0000000141510268)

4 ETW000 29 0.307296

4 ETW000 [ dev trc,00000] } DbSlHDBModify(rc=13) 20 0.307316

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=0000000141511070,op=0,da_p=0000000141510268)

4 ETW000 30 0.307346

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=0000000141510FA8,da_p=0000000141510268,for_explain=0,lock=0,op=0)

4 ETW000 31 0.307377

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=64,op=0,#marker=8,#lob=0) 23 0.307400

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5AE28,ss_p=0000000141511070) 39 0.307439

4 ETW000 [ dev trc,00000] sc_p=0000000003C5AE28,no=5,idc_p=0000000003CC63E0,con=0,act=0,slen=64,smax=256,#vars=8,stmt=000000000AB96B20,table=PATCHHIST

4 ETW000 48 0.307487

4 ETW000 [ dev trc,00000] stmtid = <3/PATCHHIST /268435456/20140124110937>

4 ETW000 38 0.307525

4 ETW000 [ dev trc,00000] INSERT INTO "PATCHHIST" VALUES( ? , ? , ? , ? , ? , ? , ? , ? ) ; 21 0.307546

4 ETW000 [ dev trc,00000] CURSOR C_0005 PREPARE on connection 0 19 0.307565

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 7032 0.314597

4 ETW000 [ dev trc,00000] db_con_check_dirty: 0 open cursors, tx = YES, bc = NO 26 0.314623

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 16 0.314639

4 ETW000 [ dev trc,00000] { DbSlHDBModify(con_hdl=0,ss_p=0000000141511070,mod=0,da_p=0000000141510268)

4 ETW000 29 0.314668

4 ETW000 [ dev trc,00000] ABAP USER is not set 22 0.314690

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5AE28,da_p=0000000141510268) 23 0.314713

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AE28,in_out=0,bulk=0,da_p=0000000141510268)

4 ETW000 35 0.314748

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=8) 32 0.314780

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AE28,in_out=0,bulk=0,types=000000000AB865E0,#col=8,useBulkInsertWithLobs=0)

4 ETW000 43 0.314823

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=99,rec_lng=208,db_lng=198

4 ETW000 35 0.314858

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=208, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 31 0.314889

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=0,row_cnt=1) 19 0.314908

4 ETW000 [ dev trc,00000] -> allocData(in_out=0,size=1048576) 20 0.314928

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AE28,in_out=0,bulk=0,arr_size=1,types=000000000AB865E0,da_p=0000000141510268)

4 ETW000 48 0.314976

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5AE28,ss_p=0000000141511070,bulk=0,in_out=0,da_p=0000000141510268)

4 ETW000 53 0.315029

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5AE28,ss_p=0000000141511070,in_out=0,da_p=0000000141510268)

4 ETW000 32 0.315061

4 ETW000 [ dev trc,00000] EXECUTE CURSOR C_0005 on connection 0 20 0.315081

4 ETW000 [ dev trc,00000] CURSOR C_0005 SET InputSize=1 18 0.315099

4 ETW000 [ dev trc,00000] CURSOR C_0005 EXECUTE on connection 0 19 0.315118

4 ETW000 [ dev trc,00000] execute() of C_0005, #rec=1, rcSQL=0, rc=0 8017 0.323135

4 ETW000 [ dev trc,00000] CURSOR C_0005, rc=0,#rec=1,#dbcount=1 27 0.323162

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5AE28,da_p=0000000141510268,rc=0)

4 ETW000 29 0.323191

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0005) 19 0.323210

4 ETW000 [ dev trc,00000] } DbSlHDBModify(rc=0) 20 0.323230

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

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

4 ETW000 name=UNKNOWN, stmtid=NULL,

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

4 ETW000 [ dev trc,00000] db_con_test_and_open: 1 open cursors (delta=1) 31 0.323324

4 ETW000 [ dev trc,00000] db_con_check_dirty: 1 open cursors, tx = YES, bc = NO 17 0.323341

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 15 0.323356

4 ETW000 [ dev trc,00000] -> dsql_hdb_open(head_p=000000000214ECA0,stmt_p=000000000214EC70,input_p=0000000000000000,#input=0)

4 ETW000 31 0.323387

4 ETW000 [ dev trc,00000] -> cursor_new() 18 0.323405

4 ETW000 [ dev trc,00000] <- cursor_new(cu_id=0) 18 0.323423

4 ETW000 [ dev trc,00000] -> build_dbsl_stmt(cu_id=0) 19 0.323442

4 ETW000 [ dev trc,00000] -> cursor_open(cu_id=0) 21 0.323463

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=000007FEE62583C8,op=3,da_p=000007FEE6258330)

4 ETW000 34 0.323497

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=000007FEE62583A8,da_p=000007FEE6258330,for_explain=0,lock=0,op=3)

4 ETW000 30 0.323527

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=304,op=3,#marker=0,#lob=0) 24 0.323551

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5AEC0,ss_p=000007FEE62583C8) 49 0.323600

4 ETW000 [ dev trc,00000] sc_p=0000000003C5AEC0,no=6,idc_p=0000000000000000,con=0,act=0,slen=304,smax=512,#vars=0,stmt=000000000AB9ACD0,table=

4 ETW000 37 0.323637

4 ETW000 [ dev trc,00000] SELECT EXECUTABLE,SAPRELEASE,HOSTNAME,PLATFORM,DBVENDOR,TIMESTAMP,PATCHNO,DBSLPATCHN FROM PATCHHIST \

4 ETW000 29 0.323666

4 ETW000 [ dev trc,00000] WHERE EXECUTABLE = 'R3trans' AND TIMESTAMP = (SELECT MAX(TIMESTAMP) FROM PATCHHIST WHERE EXECUTABLE \

4 ETW000 28 0.323694

4 ETW000 [ dev trc,00000] = 'R3trans' AND SAPRELEASE = '741' AND HOSTNAME = 'PHLAPP010' AND PLATFORM = 'NT' AND DBVENDOR = 'HD\

4 ETW000 28 0.323722

4 ETW000 [ dev trc,00000] B') ; 16 0.323738

4 ETW000 [ dev trc,00000] CURSOR C_0006 PREPARE on connection 0 20 0.323758

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 7096 0.330854

4 ETW000 [ dev trc,00000] { DbSlHDBBegRead(con_hdl=0,ss_p=000007FEE62583C8,da_p=000007FEE6258330)

4 ETW000 36 0.330890

4 ETW000 [ dev trc,00000] ABAP USER is not set 22 0.330912

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5AEC0,da_p=000007FEE6258330) 24 0.330936

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AEC0,in_out=0,bulk=0,da_p=000007FEE6258330)

4 ETW000 27 0.330963

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=0) 20 0.330983

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AEC0,in_out=0,bulk=0,types=0000000000000000,#col=0,useBulkInsertWithLobs=0)

4 ETW000 33 0.331016

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=0,rec_lng=0,db_lng=0

4 ETW000 28 0.331044

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=0, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 29 0.331073

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5AEC0,ss_p=000007FEE62583C8,bulk=0,in_out=1,da_p=000007FEE6258330)

4 ETW000 32 0.331105

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5AEC0,ss_p=000007FEE62583C8,in_out=1,da_p=000007FEE6258330)

4 ETW000 34 0.331139

4 ETW000 [ dev trc,00000] OPEN CURSOR C_0006 on connection 0 22 0.331161

4 ETW000 [ dev trc,00000] CURSOR C_0006 SET InputSize=1 18 0.331179

4 ETW000 [ dev trc,00000] CURSOR C_0006 EXECUTE on connection 0 20 0.331199

4 ETW000 [ dev trc,00000] execute() of C_0006, #rec=0, rcSQL=0, rc=0 12495 0.343694

4 ETW000 [ dev trc,00000] CURSOR C_0006, rc=0,#rec=0,#dbcount=0 32 0.343726

4 ETW000 [ dev trc,00000] } DbSlHDBBegRead(rc=0) 20 0.343746

4 ETW000 [ dev trc,00000] <- dsql_hdb_open(cu_id=0) 19 0.343765

4 ETW000 [ dev trc,00000] } dsql_open()=0, *cu_id_p=0 16 0.343781

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

4 ETW000 [ dev trc,00000] -> dsql_hdb_fetch(head_p=000000000214ECA0,cu_id=0,output_p=000000000AB9D660,#output=8,#package=1)

4 ETW000 32 0.343836

4 ETW000 [ dev trc,00000] { DbSlHDBExeRead(con_hdl=0,ss_p=000007FEE62583C8,da_p=000007FEE6258330)

4 ETW000 39 0.343875

4 ETW000 [ dev trc,00000] ABAP USER is not set 20 0.343895

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AEC0,in_out=1,bulk=1,da_p=000007FEE6258330)

4 ETW000 30 0.343925

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=1,col_cnt=8) 19 0.343944

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AEC0,in_out=1,bulk=1,types=000000000AB9D840,#col=8,useBulkInsertWithLobs=0)

4 ETW000 34 0.343978

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=99,rec_lng=208,db_lng=198

4 ETW000 29 0.344007

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=208, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=5041)

4 ETW000 31 0.344038

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=1,row_cnt=5041) 19 0.344057

4 ETW000 [ dev trc,00000] -> allocData(in_out=1,size=1048576) 20 0.344077

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AEC0,in_out=1,bulk=1,arr_size=5041,types=000000000AB9D840,da_p=000007FEE6258330)

4 ETW000 33 0.344110

4 ETW000 [ dev trc,00000] -> exec_fetch(sc_hdl=0000000003C5AEC0,bulk=0,da_p=000007FEE6258330)

4 ETW000 39 0.344149

4 ETW000 [ dev trc,00000] xcnt=1,row_i=0,row_pcnt=0 20 0.344169

4 ETW000 [ dev trc,00000] -> stmt_fetch(sc_hdl=0000000003C5AEC0) 19 0.344188

4 ETW000 [ dev trc,00000] CURSOR C_0006 FETCH (xcnt=5041) on connection 0 20 0.344208

4 ETW000 [ dev trc,00000] next() of C_0006, rc=0 53 0.344261

4 ETW000 [ dev trc,00000] fetch() of C_0006, #rec=1, rc=0, rcSQL=0 71 0.344332

4 ETW000 [ dev trc,00000] } DbSlHDBExeRead(rc=0) 24 0.344356

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

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

4 ETW000 [ dev trc,00000] db_con_test_and_open: 0 open cursors (delta=-1) 17 0.344408

4 ETW000 [ dev trc,00000] db_con_check_dirty: 0 open cursors, tx = YES, bc = NO 17 0.344425

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 15 0.344440

4 ETW000 [ dev trc,00000] -> dsql_hdb_close(head_p=000000000214ECA0,cu_id=0) 20 0.344460

4 ETW000 [ dev trc,00000] -> cursor_close(cu_id=0) 18 0.344478

4 ETW000 [ dev trc,00000] { DbSlHDBEndRead(con_hdl=0,ss_p=000007FEE62583C8,da_p=000007FEE6258330)

4 ETW000 27 0.344505

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5AEC0,da_p=000007FEE6258330,rc=0)

4 ETW000 35 0.344540

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0006) 19 0.344559

4 ETW000 [ dev trc,00000] CURSOR C_0006 CLOSE resultset on connection 0 20 0.344579

4 ETW000 [ dev trc,00000] } DbSlHDBEndRead(rc=0) 24 0.344603

4 ETW000 [ dev trc,00000] -> cursor_free(cu_id=0) 19 0.344622

4 ETW000 [ dev trc,00000] } dsql_close()=0 15 0.344637

4 ETW000 [ dev trc,00000] { db_rtab( fcode = 'RT_DELETE', tname = 'PATCHHIST' ) {D:/depot/bas/741_REL/src/flat/saprel.c:1573}

4 ETW000 26 0.344663

4 ETW000 [ dev trc,00000] db_con_check_dirty: 0 open cursors, tx = YES, bc = NO 22 0.344685

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 16 0.344701

4 ETW000 [ dev trc,00000] { DbSlHDBModify(con_hdl=0,ss_p=0000000141511070,mod=1,da_p=0000000141510268)

4 ETW000 28 0.344729

4 ETW000 [ dev trc,00000] } DbSlHDBModify(rc=13) 19 0.344748

4 ETW000 [ dev trc,00000] { DbSlHDBPrepare(con_hdl=0,ss_p=0000000141511070,op=1,da_p=0000000141510268)

4 ETW000 30 0.344778

4 ETW000 [ dev trc,00000] -> buildSQLStmt(stmt_p=0000000141510FA8,da_p=0000000141510268,for_explain=0,lock=0,op=1)

4 ETW000 30 0.344808

4 ETW000 [ dev trc,00000] <- buildSQLStmt(len=145,op=1,#marker=6,#lob=0) 24 0.344832

4 ETW000 [ dev trc,00000] -> stmt_prepare(sc_hdl=0000000003C5AF58,ss_p=0000000141511070) 38 0.344870

4 ETW000 [ dev trc,00000] sc_p=0000000003C5AF58,no=7,idc_p=0000000003CC6470,con=0,act=0,slen=145,smax=256,#vars=6,stmt=000000000A9D1520,table=PATCHHIST

4 ETW000 37 0.344907

4 ETW000 [ dev trc,00000] stmtid = <3/PATCHHIST /805306369/20140124110937>

4 ETW000 33 0.344940

4 ETW000 [ dev trc,00000] DELETE FROM "PATCHHIST" WHERE "EXECUTABLE" = ? AND "SAPRELEASE" = ? AND "HOSTNAME" = ? AND "PLATFORM\

4 ETW000 52 0.344992

4 ETW000 [ dev trc,00000] " = ? AND "DBVENDOR" = ? AND "TIMESTAMP" = ? ; 22 0.345014

4 ETW000 [ dev trc,00000] CURSOR C_0007 PREPARE on connection 0 19 0.345033

4 ETW000 [ dev trc,00000] } DbSlHDBPrepare(rc=0) 6832 0.351865

4 ETW000 [ dev trc,00000] db_con_check_dirty: 0 open cursors, tx = YES, bc = NO 25 0.351890

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = YES 16 0.351906

4 ETW000 [ dev trc,00000] { DbSlHDBModify(con_hdl=0,ss_p=0000000141511070,mod=1,da_p=0000000141510268)

4 ETW000 28 0.351934

4 ETW000 [ dev trc,00000] ABAP USER is not set 22 0.351956

4 ETW000 [ dev trc,00000] -> activate_stmt(sc_hdl=0000000003C5AF58,da_p=0000000141510268) 23 0.351979

4 ETW000 [ dev trc,00000] -> bind_variables(sc_hdl=0000000003C5AF58,in_out=0,bulk=0,da_p=0000000141510268)

4 ETW000 28 0.352007

4 ETW000 [ dev trc,00000] -> allocParameter(in_out=0,col_cnt=6) 19 0.352026

4 ETW000 [ dev trc,00000] -> calculate_record_length(sc_hdl=0000000003C5AF58,in_out=0,bulk=0,types=000000000AB865E0,#col=6,useBulkInsertWithLobs=0)

4 ETW000 33 0.352059

4 ETW000 [ dev trc,00000] #float=0,#lob=0,itab=0,#short=0,#int=0,#llong=0,#uc=79,rec_lng=160,db_lng=158

4 ETW000 29 0.352088

4 ETW000 [ dev trc,00000] <- calculate_record_length(row_size=160, lob_cnt=0, lob_pw_cnt=0, long_cnt=0, ins_bulk_lob=0, row_max=1)

4 ETW000 30 0.352118

4 ETW000 [ dev trc,00000] -> allocIndicator(in_out=0,row_cnt=1) 20 0.352138

4 ETW000 [ dev trc,00000] -> allocData(in_out=0,size=1048576) 19 0.352157

4 ETW000 [ dev trc,00000] -> bind_type_and_length(sc_hdl=0000000003C5AF58,in_out=0,bulk=0,arr_size=1,types=000000000AB865E0,da_p=0000000141510268)

4 ETW000 33 0.352190

4 ETW000 [ dev trc,00000] -> exec_modify(sc_hdl=0000000003C5AF58,ss_p=0000000141511070,bulk=0,in_out=0,da_p=0000000141510268)

4 ETW000 38 0.352228

4 ETW000 [ dev trc,00000] -> stmt_execute(sc_hdl=0000000003C5AF58,ss_p=0000000141511070,in_out=0,da_p=0000000141510268)

4 ETW000 32 0.352260

4 ETW000 [ dev trc,00000] EXECUTE CURSOR C_0007 on connection 0 20 0.352280

4 ETW000 [ dev trc,00000] CURSOR C_0007 SET InputSize=1 18 0.352298

4 ETW000 [ dev trc,00000] CURSOR C_0007 EXECUTE on connection 0 19 0.352317

4 ETW000 [ dev trc,00000] execute() of C_0007, #rec=1, rcSQL=0, rc=0 7326 0.359643

4 ETW000 [ dev trc,00000] CURSOR C_0007, rc=0,#rec=1,#dbcount=1 29 0.359672

4 ETW000 [ dev trc,00000] -> deactivate_stmt(sc_hdl=0000000003C5AF58,da_p=0000000141510268,rc=0)

4 ETW000 31 0.359703

4 ETW000 [ dev trc,00000] -> StmtCacheFree(DBSL:C_0007) 19 0.359722

4 ETW000 [ dev trc,00000] } DbSlHDBModify(rc=0) 20 0.359742

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

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) 41 0.359801

4 ETW000 [ dev trc,00000] { DbSlHDBCommit(con_hdl=0,forced=1,hold=0) 34 0.359835

4 ETW000 [ dev trc,00000] -> dsql_sapdb_free(con_hdl=0,hold=0) 20 0.359855

4 ETW000 [ dev trc,00000] -> commit(con_hdl=0) 19 0.359874

4 ETW000 [ dev trc,00000] close all opened locators of connection 0 20 0.359894

4 ETW000 [ dev trc,00000] } DbSlHDBCommit(rc=0) 7980 0.367874

4 ETW000 [ dev trc,00000] db_con_check_dirty: db_con_dirty = NO 24 0.367898

4 ETW000 [ dev trc,00000] -> dsql_hdb_sync() 39 0.367937

4 ETW000 [ dev trc,00000] { DbSlHDBControl(con_hdl=0,command=12,arg_p=000000014192FD74) 37 0.367974

4 ETW000 [ dev trc,00000] } DbSlHDBControl(rc=0) 19 0.367993

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 : 03.06.2014 - 09:39:01

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: 145 0.000145

4 ETW000 [ dev trc,00000] Disconnecting from connection 0 ... 54027 0.054172

4 ETW000 [ dev trc,00000] Now I'm disconnected from HDB 6552 0.060724

4 ETW000 [ dev trc,00000] Disconnected from connection 0 69 0.060793

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

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

4 ETW000 Disconnected from database.

4 ETW000 End of Transport (0000).

4 ETW000 date&time: 03.06.2014 - 09:39:01

Regards

[former_member182657]

Hi,

Have you followed the SAP Note 1639578 - SSFS as password storage for primary database connect .

Also please share the trans.log file for analysis.

Regards,

Gaurav

[ACE-SAP]

Hello

What is your source system version, is it an Ehp 7 system with basis 740 & kernel 741 ?

If so do not downgrade to 720.

Regards

[former_member185239]

Hi Alexander,

You can downgrade the kernel to 720 EXT patch 500 and continue with upgrade.

If not , then you have to configure SSFS as Gaurav as suggested.

1. 1. http://scn.sap.com/docs/DOC-46252

2. http://scn.sap.com/docs/DOC-54123

With Regards

Ashutosh Chaturvedi

[former_member182657]

Hi Alexander,

As you are using kernel 741 for the procedure but as per error details your env parameter

ENV: rsdb_ssfs_connect=0

is with value 0.Here i suggest you to set the value of this parameter with 1 as kernel 741 supports only SSFS methods to connect the database.For more on SSFS please follow link

After setting try try run R3trans -d from the upgrade directory and share the result for trans.log file and re try the phase again.

Hope this will solve your issue.

Regards,

Gaurav

[Former Member]

Hi Gaurav, thank you for answer. I switched SSFS on at it says in the guide, but error remains the same:

SAPup> Starting subprocess in phase 'MVNTAB_TRANS' at 20140602134557

ENV: DBMS_TYPE=hdb

ENV: PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC

ENV: PATH=C:\usr\sap\CM1\DVEBMGS04\exe;C:\Program Files\Far Manager\;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\usr\sap\CM1\SYS\exe\uc\NTAMD64;C:\usr\sap\CM1\hdbclient

ENV: SAPSYSTEMNAME=CM1

ENV: dbs_hdb_schema=SAPCM1

ENV: rsdb_ssfs_connect=1

EXECUTING C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE "pf=C:\SUM\SUM\abap\var\PMVNTUPG.TPP" "-Dbuffreset=no" ddlntabs "ddlmode=V" CM1

This is C:\usr\sap\CM1\DVEBMGS04\exe\tp.EXE version 380.18.68 (release 741, unicode enabled)

tp finished with return code: 8

meaning:

A tool used by tp produced errors

SAPup> Process with PID 8000 terminated with status 8 at 20140602134623!

Here is R3trans log:

UPD: Sorry looks like ssfs doesn't work, will check later.

read_con_info_ssfs(): DBSL does not support extended connect protocol

4 ETW000 ==> ssfs won't be used

UPD2: I'm stuck on this "DBSL does not support extended connect protocol" DBSL Lib is up to date, but the message still appear. I created new SCN question about this -