cancel
Showing results for 
Search instead for 
Did you mean: 

SAPSR3 password issue when installing NW731

Former Member
0 Kudos

Hi Experts,

Hope someone can help me with this. I am trying to install NW731 on a windows 2008 server which already has ECC6 installed on it. The ECC 6 existing install is a network domain install and the new install of NW731 is a local domain install.

At the time of installation the installation stops at the Create ICM userstore phase with this error:

An error occurred while processing option SAP Enhancement Package 1 for SAP NetWeaver 7.3 > Oracle > SAP Systems > Application Server ABAP > Standard System > Standard System( Last error reported by the step: Process call 'F:\usr\sap\MBW\SYS\exe\uc\NTAMD64\saplikey pf=\\srvdc1127\sapmnt\MBW\SYS\profile\MBW_DVEBMGS01_srvdc1127 -install_first_temp K0327848492 XXXXXX' exits with error code 2. For details see log file(s) saplikey.log, dev_slic.).

This is what it shows when I look at the dev_likey:

Connecting as SAPSR3/<pwd>@MBW on connection 0 (nls 0) ... (dbsl 720 081211, UNICODE[2])

Attaching to database server MBW (con=0, svchp=0AA7E3F8, srvhp=0AA82CC0)

Sat Mar 29 20:19:06 2014

Starting user session: OCISessionBegin(con=0, usr='SAPSR3', svc=0AA7E3F8, srv=0AA82CC0, usr=0AA919B0)

Sat Mar 29 20:20:07 2014

   OCISessionBegin(OCI_DEFAULT) failed with SQL error 3113:

   ORA-03113: end-of-file on communication channel                                                                          

Server handle (con=0,svchp=0AA7E3F8,srvhp=0AA82CC0) detached

*** ERROR => CONNECT failed with sql error '3113'

[dbsloci.c    14533]

***LOG BY2=> sql error 3113   performing CON [dblink       540]

***LOG BY0=> ORA-03113: end-of-file on communication channel [dblink       540]

*** ERROR => saplikey: failed to connect to the database. [saplikey_w.c 762]

I have run the below command to make sure the password is correct and have also used the SAPSR3 user to query a table which was successful.

brconnect -u system/<password> -f chpass -o SAPSR3 -p <password>

After running the above command I am able to log in and connect to Oracle DB using the above command and query tables.

I have also tried to start SAP through sapmmc and looking at the logs I see this:

C  Starting user session: OCISessionBegin(con=0, usr='SAPSR3', svc=1515F818, srv=15160100, usr=1516F390)

C Sat Mar 29 21:03:25 2014

C     OCISessionBegin(OCI_DEFAULT) failed with SQL error 1017:

C     ORA-01017: invalid username/password; logon denied                                                                       

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

[dbsloci.c    14533]

Would be grateful if someone can help me overcome this issue.

Also, is there anyway to see what password is being used by SAP to perform the setup using the user SAPSR3? This to me is the most likely cause of the issue.

Thanks in advance.

Regards,

Manish

Accepted Solutions (0)

Answers (3)

Answers (3)

JamesZ
Advisor
Advisor
0 Kudos

Hello Manish,

The problem is very clear SAP kernel gets wrong SAPSR3 database password.

Please run os command "R3trans -d", then there will be a file named "trans.log" generated in
currently directory.

Please give me trans.log file, so that we can know if you are using ssfs.

However most likely, ssfs is used, you can try to reset password with command
rsecssfx put DB_CONNECT/DEFAULT_DB_PASSWORD <pwd>. For details, you can refer to SAP note 1639578.

Best regards,
James

Former Member
0 Kudos

Hi James,

This is what I get with r3trans -d:

C:\Windows\system32>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

2EETW169 no connect possible: "DBMS = ORACLE                           --- dbs_o

ra_tnsname = 'MBW'"

r3trans finished (0012).

The I ran the command

rsecssfx put DB_CONNECT/DEFAULT_DB_PASSWORD pwd

and then I ran r3trans -d again and this is what I see in the trans.log

4 ETW000  [ dev trc,00000]  Fri Apr 04 10:27:47 2014                                         16423899  16.489112
4 ETW000  [ dev trc,00000]  Assigning server context 03C48B10 to service context 03C48288         45  16.489157
4 ETW000  [ dev trc,00000] OCIHandleAlloc(OCI_HTYPE_SESSION, con=0, usrhp=03C5BA10)           82  16.489239
4 ETW000  [ dev trc,00000]  Assigning username to user session: con=0, usrhp=03C5BA10             25  16.489264
4 ETW000  [ dev trc,00000]  Assigning password to user session: con=0, usrhp=03C5BA10             37  16.489301
4 ETW000  [ dev trc,00000]  Starting user session: OCISessionBegin(con=0, usr='SAPSR3', svc=03C48288, srv=03C48B10, usr=03C5BA10)
4 ETW000                                                                                             165  16.489466
4 ETW000  [ dev trc,00000] OCISessionBegin(OCI_DEFAULT) failed with -1=OCI_ERROR            2844  16.492310
4 ETW000  [ dev trc,00000] OCISessionBegin(OCI_DEFAULT) failed with SQL error 1034:          101  16.492411
4 ETW000  [ dev trc,00000] ORA-01034: ORACLE not available                                    25  16.492436
4 ETW000                    ORA-27101: shared memory realm does not exist                                       
4 ETW000  [ dev trc,00000]  server_detach(con=0,stale=1,srvhp=03C48B10)                           23  16.492459
4 ETW000  [ dev trc,00000]  Server handle (con=0,svchp=03C48288,srvhp=03C48B10) detached        1622  16.494081
4 ETW000  [ dev trc,00000]  Free server handle srvhp=03C48B10 of con=0                            24  16.494105
4 ETW000  [dbsloci.,00000]  *** ERROR => CONNECT failed with sql error '1034'                     47  16.494152
4 ETW000  [ dev trc,00000] set_ocica() -> SQL error code 1034                                 20  16.494172
4 ETW000  [ dev trc,00000]  -->oci_get_errmsg (con=0, rc=1034)                                    15  16.494187
4 ETW000  [ dev trc,00000] OCIErrorGet -> SQL error code: 1034                                67  16.494254
4 ETW000  [ dev trc,00000] ORA-01034: ORACLE not available                                    33  16.494287
4 ETW000                    ORA-27101: shared memory realm does not exist                                       
4 ETW000  [ dev trc,00000] DbSlConnect(con=0) -> orc=0, 99=DBSL_ERR_DB                        25  16.494312
4 ETW000  [dblink  ,00000]  ***LOG BY2=>sql error 1034   performing CON                           52  16.494364
4 ETW000  [dblink  ,00000]  ***LOG BY0=>ORA-01034: ORACLE not available                           92  16.494456
4 ETW000                    ORA-27101: shared memory realm does not exist
4 ETW000  [ dev trc,00000]  -->DbSlControl(con=-1,cmd=41=DBSL_CMD_RELINFO_GET)                    39  16.494495
4 ETW000  [ dev trc,00000]  -->DbSlControl(con=-1,cmd=14=DBSL_CMD_VERSION_GET)                    17  16.494512
4 ETW000  [ dev trc,00000]  -->DbSlControl(con=-1,cmd=50=DBSL_CMD_GET_PATCH_INFO)                 16  16.494528
4 ETW000  [ dev trc,00000]  -->DbSlControl(con=-1,cmd=13=DBSL_CMD_VENDOR_NAME_GET)                56  16.494584
4 ETW000  [ dev trc,00000]  -->DbSlControl(con=-1,cmd=52=DBSL_CMD_GET_DBLIB_VERSION)              18  16.494602
4 ETW000  [ dev trc,00000]             -> 'OCI_11201_SHARE, 11.2.0.2.0'                       16  16.494618
2EETW169 no connect possible: "DBMS = ORACLE                       --- dbs_ora_tnsname = 'MBW'"

Thanks for your help so far. I feel I am so close yet so far sometimes ...

Regards,

Manish

Former Member
0 Kudos

Check the state of your Oracle instance. Check alert_<SID>.log file.

Reagan
Advisor
Advisor
0 Kudos

OCISessionBegin(OCI_DEFAULT) failed with -1=OCI_ERROR

OCISessionBegin(OCI_DEFAULT) failed with SQL error 1034:

ORA-01034: ORACLE not available

ORA-27101: shared memory realm does not exist

Is the database is up and running.

Check whether the listener is running

sqlplus "/as sysdba"

select name, open_mode from v$database;

Regards

RB

Former Member
0 Kudos

Hello,

check you database, definitly is not runing or your tnsnames.ora is right configured. Start the database and send the requested logs.

regards,

Michael

Former Member
0 Kudos

Hi Michael,

I started the DB and ran the command below:

SQL> startup

ORA-32004: obsolete or deprecated parameter(s) specified for RDBMS instance

ORACLE instance started.

Total System Global Area 1006358528 bytes

Fixed Size                  2259304 bytes

Variable Size             511706776 bytes

Database Buffers          486539264 bytes

Redo Buffers                5853184 bytes

Database mounted.

Database opened.

SQL> select name, open_mode from v$database;

NAME      OPEN_MODE

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

MBW       READ WRITE

After doing this I restarted the install and still the same thing:

An error occurred while processing option SAP Enhancement Package 1 for SAP NetWeaver 7.3 > Oracle > SAP Systems > Application Server ABAP > Standard System > Standard System( Last error reported by the step: ABAP processes of instance MBW/DVEBMGS01 [ABAP: ACTIVE] did not start after 10:00 minutes. Giving up.). You can now:

Regards,

Manish

Former Member
0 Kudos

Hi,

Now please attach this log files dev_w0, dev_ms, dev_disp and dev_rd.

Let us know

Reagan
Advisor
Advisor
0 Kudos

You need to run R3trans -d after starting the database and provide the trans.log file

Regards

RB

Former Member
0 Kudos

Here is the log after starting the DB and running r3trans -d

4 ETW000 r3trans version 6.24 (release 721 - 26.09.13 - 20:13:07 ).

4 ETW000 unicode enabled version

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

4 ETW000

4 ETW000 date&time   : 04.04.2014 - 22:31:53

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]  Fri Apr 04 22:31:55 2014                                                2210  0.002210

4 ETW000  [     dev trc,00000]  db_con_init called                                                        35  0.002245

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

4 ETW000                                                                                                  69  0.002314

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

4 ETW000                                                                                                  72  0.002386

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

4 ETW000  [     dev trc,00000]  Loading DB library 'dboraslib.dll' ...                                    43  0.002441

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

4 ETW000                                                                                              210636  0.213077

4 ETW000  [     dev trc,00000]      using "F:\usr\sap\MBW\SYS\exe\uc\NTAMD64\dboraslib.dll"               15  0.213092

4 ETW000  [     dev trc,00000]  Library 'dboraslib.dll' loaded                                            18  0.213110

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

4 ETW000  [     dev trc,00000]  Version of 'dboraslib.dll' is "721.02", patchlevel (0.138)               223  0.213348

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

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

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=-1,cmd=47=DBSL_CMD_IMP_FUNS_SET) -> changed=30, tagset=1

4 ETW000                                                                                                 192  0.213607

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=-1,cmd=39=DBSL_CMD_SET_SINGLETASK)                     17  0.213624

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=-1,cmd=10=DBSL_CMD_DBCA_PTR_GET)                       14  0.213638

4 ETW000  [     dev trc,00000]  New connection 0 created                                                  11  0.213649

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                                                                                                  36  0.213685

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=-1,cmd=10=DBSL_CMD_DBCA_PTR_GET)                       23  0.213708

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

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

4 ETW000                                                                                                  17  0.213750

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

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                                                                                                  22  0.213784

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

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=-1,cmd=74=DBSL_CMD_CON_INFO_EXT_SUPPORT) -> rc=0=DBSL_ERR_OK, con_vers=0

4 ETW000                                                                                                  46  0.213879

4 ETW000  [     dev trc,00000]  con_info_ext_support(): DBSL supports extended connect protocol           20  0.213899

4 ETW000                          ==> connect info for default DB will be read from ssfs

4 ETW000  [     dev trc,00000]  con_info_ext_support(): auth/shadow_upgrade = undefined                   23  0.213922

4 ETW000                         ==> Using default key set to get connect info from ssfs

4 ETW000  [     dev trc,00000]  read_ssfs_record(): register callback ssfs_dbi_trace and max level 2

4 ETW000                                                                                                  19  0.213941

4 ETW000  [     dev trc,00000]  RSecSSFs: Entering function "RSecSSFsGetRecord" [rsecssfs.c 872]        1510  0.215451

4 ETW000  [     dev trc,00000]  RSecSSFs: Configuration data read from environment parameters [rsecssfs.c 4353]

4 ETW000                                                                                                  78  0.215529

4 ETW000  [     dev trc,00000]  RSecSSFs: Data file "\\srvdc1127\sapmnt\MBW\SYS\global\security\rsecssfs\data\SSFS_MBW.DAT" opened for read [rsecssfs.c 2539]

4 ETW000                                                                                              409293  0.624822

4 ETW000  [     dev trc,00000]  RSecSSFs: Key file "\\srvdc1127\sapmnt\MBW\SYS\global\security\rsecssfs\key\SSFS_MBW.KEY" not found, using default key [rsecssfs.c 1424]

4 ETW000                                                                                               84322  0.709144

4 ETW000  [     dev trc,00000]  Fri Apr 04 22:31:56 2014                                              250233  0.959377

4 ETW000  [     dev trc,00000]  RSecSSFs: Exiting function "RSecSSFsGetRecord" with return code 0 (message: <No message available>) [rsecssfs.c 940]

4 ETW000                                                                                                  27  0.959404

4 ETW000  [     dev trc,00000]  read_ssfs_record(): DB_CONNECT/DEFAULT_DB_USER read successfully from ssfs

4 ETW000                                                                                                  22  0.959426

4 ETW000  [     dev trc,00000]  read_ssfs_record(): deregister callback ssfs_dbi_trace                    13  0.959439

4 ETW000  [     dev trc,00000]  read_ssfs_record(): register callback ssfs_dbi_trace and max level 2

4 ETW000                                                                                                  15  0.959454

4 ETW000  [     dev trc,00000]  RSecSSFs: Entering function "RSecSSFsGetRecord" [rsecssfs.c 872]          18  0.959472

4 ETW000  [     dev trc,00000]  RSecSSFs: Configuration data read from environment parameters [rsecssfs.c 4353]

4 ETW000                                                                                                  69  0.959541

4 ETW000  [     dev trc,00000]  RSecSSFs: Data file "\\srvdc1127\sapmnt\MBW\SYS\global\security\rsecssfs\data\SSFS_MBW.DAT" opened for read [rsecssfs.c 2539]

4 ETW000                                                                                              542559  1.502100

4 ETW000  [     dev trc,00000]  RSecSSFs: Key file "\\srvdc1127\sapmnt\MBW\SYS\global\security\rsecssfs\key\SSFS_MBW.KEY" not found, using default key [rsecssfs.c 1424]

4 ETW000                                                                                              200739  1.702839

4 ETW000  [     dev trc,00000]  RSecSSFs: Exiting function "RSecSSFsGetRecord" with return code 0 (message: <No message available>) [rsecssfs.c 940]

4 ETW000                                                                                                6423  1.709262

4 ETW000  [     dev trc,00000]  read_ssfs_record(): DB_CONNECT/DEFAULT_DB_PASSWORD read successfully from ssfs

4 ETW000                                                                                                  63  1.709325

4 ETW000  [     dev trc,00000]  read_ssfs_record(): deregister callback ssfs_dbi_trace                    19  1.709344

4 ETW000  [     dev trc,00000]  read_ssfs_record(): register callback ssfs_dbi_trace and max level 2

4 ETW000                                                                                                  16  1.709360

4 ETW000  [     dev trc,00000]  RSecSSFs: Entering function "RSecSSFsGetRecord" [rsecssfs.c 872]          19  1.709379

4 ETW000  [     dev trc,00000]  RSecSSFs: Configuration data read from environment parameters [rsecssfs.c 4353]

4 ETW000                                                                                                  69  1.709448

4 ETW000  [     dev trc,00000]  Fri Apr 04 22:31:57 2014                                              947495  2.656943

4 ETW000  [     dev trc,00000]  RSecSSFs: Data file "\\srvdc1127\sapmnt\MBW\SYS\global\security\rsecssfs\data\SSFS_MBW.DAT" opened for read [rsecssfs.c 2539]

4 ETW000                                                                                                  25  2.656968

4 ETW000  [     dev trc,00000]  RSecSSFs: Key file "\\srvdc1127\sapmnt\MBW\SYS\global\security\rsecssfs\key\SSFS_MBW.KEY" not found, using default key [rsecssfs.c 1424]

4 ETW000                                                                                               63211  2.720179

4 ETW000  [     dev trc,00000]  RSecSSFs: Record with key "DB_CONNECT/DEFAULT_DB_CON_ENV" not found in secure storage [rsecssfs.c 2561]

4 ETW000                                                                                                 132  2.720311

4 ETW000  [     dev trc,00000]  RSecSSFs: Exiting function "RSecSSFsGetRecord" with return code 1 (message: Record with key "DB_CONNECT/DEFAULT_DB_CON_ENV" not found in secure storage) [rsecssfs.c 940]

4 ETW000                                                                                               56875  2.777186

4 ETW000  [     dev trc,00000]  read_ssfs_record(): not found is accepted, RSecSSFsGetRecord(DB_CONNECT/DEFAULT_DB_CON_ENV)=1 : Record with key "DB_CONNECT/DEFAULT_DB_CON_ENV" not found in secure storage

4 ETW000                                                                                                  27  2.777213

4 ETW000  [     dev trc,00000]  read_ssfs_record(): deregister callback ssfs_dbi_trace                    13  2.777226

4 ETW000  [     dev trc,00000]  Fri Apr 04 22:31:58 2014                                               31051  2.808277

4 ETW000  [     dev trc,00000]  -->DbSlConnect(vers=0, type=0, env='[default env]', usr='SAPSR3', kernel dbsl='721')

4 ETW000                                                                                                2136  2.810413

4 ETW000  [     dev trc,00000]  DIR_CLIENT_ORAHOME is not set as profile nor env. parameter.            5874  2.816287

4 ETW000                        Assuming using instant client with unspecified location.

4 ETW000  [     dev trc,00000]  Register application info.                                               421  2.816708

4 ETW000  [     dev trc,00000]  Fri Apr 04 22:31:59 2014                                             1894472  4.711180

4 ETW000  [     dev trc,00000]  Oracle client version: 11.2.0.2.0, (dbsl 721 041013, UNICODE[2])          24  4.711204

4 ETW000  [     dev trc,00000]  Detailed Oracle client (patch) info not available.                       927  4.712131

4 ETW000  [     dev trc,00000]    Default connection: tnsname ='MBW'                                      20  4.712151

4 ETW000  [     dev trc,00000]  -->oci_initialize(con=0, char='UTF16', nchar='AL16UTF16', nls=-1),uc_ln=2

4 ETW000                                                                                                  65  4.712216

4 ETW000  [     dev trc,00000]  Fri Apr 04 22:32:02 2014                                             2426871  7.139087

4 ETW000  [     dev trc,00000]  OCIEnvCreate(mode=16384=OCI_UTF16) returned 0 (for default NLS handle)

4 ETW000                                                                                                  58  7.139145

4 ETW000  [     dev trc,00000]     OCIHandleAlloc(OCI_HTYPE_ERROR, con=0, ErrHp=03B78108)                 90  7.139235

4 ETW000  [     dev trc,00000]     OCIHandleAlloc(OCI_HTYPE_ERROR, con=0, ErrBt=03B79188)                 27  7.139262

4 ETW000  [     dev trc,00000]  NLS env. settings: lang=41004D00450052004900430041004E00               20443  7.159705

4 ETW000  [     dev trc,00000]                     terr=41004D0045005200490043004100                      25  7.159730

4 ETW000  [     dev trc,00000]                     char=55005400460031003600                              21  7.159751

4 ETW000  [     dev trc,00000]  Client NLS setting (by OCINlsGetInfo(con=0)): 'AMERICAN_AMERICA.UTF16'

4 ETW000                                                                                                  19  7.159770

4 ETW000  [     dev trc,00000]  charset='UTF16', ncharset='AL16UTF16', UNI_ASC=FALSE                      23  7.159793

4 ETW000  [     dev trc,00000]  Connecting as SAPSR3/<pwd>@MBW on connection 0 (nls 0) ... (dbsl 721 041013, UNICODE[2])

4 ETW000                                                                                                  70  7.159863

4 ETW000  [     dev trc,00000]     OCIHandleAlloc(OCI_HTYPE_SVCCTX, con=0, svchp=03B79038)              3209  7.163072

4 ETW000  [     dev trc,00000]  Allocating server context handle                                          30  7.163102

4 ETW000  [     dev trc,00000]  Attaching to database server MBW (con=0, svchp=03B79038, srvhp=03B798C0)

4 ETW000                                                                                                  58  7.163160

4 ETW000  [     dev trc,00000]  Fri Apr 04 22:32:24 2014                                             22415857  29.579017

4 ETW000  [     dev trc,00000]  Assigning server context 03B798C0 to service context 03B79038             21  29.579038

4 ETW000  [     dev trc,00000]     OCIHandleAlloc(OCI_HTYPE_SESSION, con=0, usrhp=03B887C0)             7650  29.586688

4 ETW000  [     dev trc,00000]  Assigning username to user session: con=0, usrhp=03B887C0                 25  29.586713

4 ETW000  [     dev trc,00000]  Assigning password to user session: con=0, usrhp=03B887C0               5382  29.592095

4 ETW000  [     dev trc,00000]  Starting user session: OCISessionBegin(con=0, usr='SAPSR3', svc=03B79038, srv=03B798C0, usr=03B887C0)

4 ETW000                                                                                               56739  29.648834

4 ETW000  [     dev trc,00000]  Fri Apr 04 22:32:25 2014                                              187129  29.835963

4 ETW000  [     dev trc,00000]  Assigning user session usrhp=03B887C0 to service context svchp=03B79038

4 ETW000                                                                                                  26  29.835989

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected                          77  29.836066

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=39,stp=03B86758)                            18  29.836084

4 ETW000  [     dev trc,00000]         SELECT SID FROM V$MYSTAT WHERE ROWNUM<2                                                                                  

4 ETW000                                                                                                  22  29.836106

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_INT , l=  4, con=0, stp=03B86758, pv=0214E530, in=0214E534,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                4970  29.841076

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected, new transaction       2948  29.844024

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#1: con=0,stp=03B86758,itr=1,off=0,bat=0)             23  29.844047

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  77  29.844124

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,031s]                            43826  29.887950

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, iters-errors = 1-0) [dur. 0,031s]               34  29.887984

4 ETW000  [     dev trc,00000]  Now 'SAPSR3/<pwd>@MBW' is connected: con=0, nls=0, session=145, time='2014-04-04 22:32:25'

4 ETW000                                                                                                  64  29.888048

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected                          24  29.888072

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=33,stp=03B86758)                            28  29.888100

4 ETW000  [     dev trc,00000]         ALTER SESSION SET NLS_SORT=BINARY                                                                                        

4 ETW000                                                                                                  21  29.888121

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected, new transaction         28  29.888149

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#2: con=0,stp=03B86758,itr=1,off=0,bat=0)             16  29.888165

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  29.888185

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,000s]                              325  29.888510

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, iters-errors = 1-0) [dur. 0,000s]               22  29.888532

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected                          40  29.888572

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=110,stp=03B86758)                           16  29.888588

4 ETW000  [     dev trc,00000]         SELECT UPPER(INSTANCE_NAME),HOST_NAME,VERSION,TO_CHAR(STARTUP_TIME,'MON DD, YYYY, HH24:MI:SS') FROM V$INSTANCE           

4 ETW000                                                                                                  22  29.888610

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_STR , l= 66, con=0, stp=03B86758, pv=7FEE28B9230, in=0214E4F0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  42  29.888652

4 ETW000  [     dev trc,00000]  -  oci_define  ( 1: SQLT_STR , l=130, con=0, stp=03B86758, pv=7FEE28B9478, in=0214E4F0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  39  29.888691

4 ETW000  [     dev trc,00000]  -  oci_define  ( 2: SQLT_STR , l= 66, con=0, stp=03B86758, pv=7FEE28B91EE, in=0214E4F0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  29  29.888720

4 ETW000  [     dev trc,00000]  -  oci_define  ( 3: SQLT_STR , l= 60, con=0, stp=03B86758, pv=0214E600, in=0214E4F0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  29.888746

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected, new transaction         19  29.888765

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#3: con=0,stp=03B86758,itr=1,off=0,bat=0)             15  29.888780

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  29.888800

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,000s]                              374  29.889174

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, iters-errors = 1-0) [dur. 0,000s]               25  29.889199

4 ETW000  [     dev trc,00000]  DB instance MBW is running on SRVDC1127 with ORACLE version 11.2.0.2.0 since APR 04, 2014, 22:27:37.

4 ETW000                                                                                                  30  29.889229

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected                          23  29.889252

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=74,stp=03B86758)                            15  29.889267

4 ETW000  [     dev trc,00000]         SELECT SUBSTR(NAME,1,3), TO_CHAR(CREATED,'YYYYMMDDHHMMSS') FROM V$DATABASE                                               

4 ETW000                                                                                                  20  29.889287

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_CHR , l=  6, con=0, stp=03B86758, pv=0214E730, in=0214E5B0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  52  29.889339

4 ETW000  [     dev trc,00000]  -  oci_define  ( 1: SQLT_STR , l= 30, con=0, stp=03B86758, pv=0214E640, in=0214E5B0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  67  29.889406

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected, new transaction         24  29.889430

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#4: con=0,stp=03B86758,itr=1,off=0,bat=0)             16  29.889446

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  21  29.889467

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,047s]                            52065  29.941532

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, iters-errors = 1-0) [dur. 0,047s]               24  29.941556

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected                          43  29.941599

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=172,stp=03B86758)                           17  29.941616

4 ETW000  [     dev trc,00000]         SELECT VALUE FROM V$NLS_PARAMETERS WHERE PARAMETER IN ('NLS_LANGUAGE','NLS_TERRITORY','NLS_CHARACTERSET','NLS_NCHAR_CHARAC

4 ETW000                                                                                                  21  29.941637

4 ETW000  [     dev trc,00000]         TERSET','NLS_LENGTH_SEMANTICS') ORDER BY PARAMETER                                                                       

4 ETW000                                                                                                  22  29.941659

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_STR , l=202, con=0, stp=03B86758, pv=0214E150, in=0214E030,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  43  29.941702

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 not connected, new transaction         29  29.941731

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#5: con=0,stp=03B86758,itr=5,off=0,bat=0)             16  29.941747

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  29.941767

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,000s]                              370  29.942137

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, iters-errors = 5-0) [dur. 0,000s]               22  29.942159

4 ETW000  [     dev trc,00000]  con=0, V$NLS_PARAMETERS: NLS_LANG=AMERICAN_AMERICA.UTF8, NLS_NCHAR=UTF8

4 ETW000                                                                                                  28  29.942187

4 ETW000  [     dev trc,00000]  V$NLS_PARAMETERS: NLS_LENGTH_SEMANTICS='BYTE'                             21  29.942208

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

4 ETW000                                                                                                  24  29.942232

4 ETW000  [     dev trc,00000]    0 UTF16                             AL16UTF16                         03B6CCA0         03B78108         03B79188       

4 ETW000                                                                                                  62  29.942294

4 ETW000  [     dev trc,00000]     DbSlConnect(con=0) -> orc=0, 0=DBSL_ERR_OK                             17  29.942311

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=21=DBSL_CMD_DBHOST_NAME_GET)                     17  29.942328

4 ETW000  [     dev trc,00000]  Connection 0 opened (DBSL handle 0)                                       14  29.942342

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=7=DBSL_CMD_DBNAME_GET)                           15  29.942357

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=63=DBSL_CMD_DBSID_GET)                           13  29.942370

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=21=DBSL_CMD_DBHOST_NAME_GET)                     19  29.942389

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=11=DBSL_CMD_USERID_GET) -> USERID='SAPSR3'       15  29.942404

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=22=DBSL_CMD_OWNERID_GET) -> OWNERID='SAPSR3'

4 ETW000                                                                                                  18  29.942422

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=13=DBSL_CMD_VENDOR_NAME_GET)                     14  29.942436

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=24=DBSL_CMD_DBVERSION_GET)                       14  29.942450

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=51=DBSL_CMD_NLS_CHARACTERSET_GET)                15  29.942465

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_SELECT, con=0, ss_p=0214F6F0, da_p=0214F760), stp=0214F6C0, stid=0000NULL

4 ETW000                                                                                                  49  29.942514

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                8346  29.950860

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              19  29.950879

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=26,stp=03B86758); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                  24  29.950903

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

4 ETW000                                                                                                  22  29.950925

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=0, mrk=0,sch=03B39150, scp=03B425E0, tab='SVERS') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  39  29.950964

4 ETW000  [     dev trc,00000]  -->DbSlRead(con=0, ssp=0214F6F0, dap=0214F760), sch=03B39150, scp=03B425E0, tab='SVERS'

4 ETW000                                                                                                  26  29.950990

4 ETW000  [     dev trc,00000]    stmt=SELECT VERSION FROM SVERS                                                                                                

4 ETW000                                                                                                  26  29.951016

4 ETW000  [     dev trc,00000]  STID: ssp=0214F6F0->stmtid_ptr=0000NULL                                   17  29.951033

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_SHORT_CHAR        (len=144, max=144, dec=0)           24  29.951057

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 0 input variables, col=0                          18  29.951075

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 1 output variables                                21  29.951096

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=144, max=144, dec=0                 15  29.951111

4 ETW000  [     dev trc,00000]     bind_ind_and_values(OUT,con=0,arr=890,col=1,rc=0,rl=0,flt=0,int=0,sh=0,uc=72)

4 ETW000                                                                                                  65  29.951176

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_CHR , l=144, con=0, stp=03B86758, pv=03B974B4, in=03B96DC0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  30  29.951206

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  55  29.951261

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             29  29.951290

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#6: con=0,stp=03B86758,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  23  29.951313

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  21  29.951334

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,016s]                            26130  29.977464

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,016s]

4 ETW000                                                                                                  28  29.977492

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=0, 0=DBSL_ERR_OK                                   16  29.977508

4 ETW000  [     dev trc,00000]     DbSlRead() --> 0=DBSL_ERR_OK                                           44  29.977552

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=43=DBSL_CMD_SAPRELEASE_SET)                      21  29.977573

4 ETW000  [     dev trc,00000]     DbSlControl(DBSL_CMD_SAPRELEASE_SET,abap='731')                        18  29.977591

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=41=DBSL_CMD_RELINFO_GET)                         54  29.977645

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=14=DBSL_CMD_VERSION_GET)                         18  29.977663

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=50=DBSL_CMD_GET_PATCH_INFO)                      13  29.977676

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=52=DBSL_CMD_GET_DBLIB_VERSION)                   34  29.977710

4 ETW000  [     dev trc,00000]                 -> 'OCI_10201_SHARE, 11.2.0.2.0'                           15  29.977725

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=20=DBSL_CMD_DBFEATURES_PTR_GET)                 124  29.977849

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_SELECT, con=0, ss_p=02145350, da_p=021453A0), stp=02145380, stid=0000NULL

4 ETW000                                                                                                6671  29.984520

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  98  29.984618

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              59  29.984677

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=62,stp=03B846A8); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                  28  29.984705

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

4 ETW000                                                                                                  22  29.984727

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=0, mrk=0,sch=03B391E8, scp=03B42700, tab='DDNTT') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  41  29.984768

4 ETW000  [     dev trc,00000]  -->DbSlBegRead(#0: con=0, ssp=02145350, dap=021453A0), In=0, Out=2, sch=03B391E8, scp=03B42700, tab='DDNTT'

4 ETW000                                                                                                  28  29.984796

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

4 ETW000                                                                                                  23  29.984819

4 ETW000  [     dev trc,00000]  STID: ssp=02145350->stmtid_ptr=0000NULL                                   15  29.984834

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               14  29.984848

4 ETW000  [     dev trc,00000]   Out  01: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.984861

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 0 input variables, col=0                          23  29.984884

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 2 output variables                                14  29.984898

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.984911

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.984924

4 ETW000  [     dev trc,00000]     bind_ind_and_values(OUT,con=0,arr=10833,col=2,rc=0,rl=0,flt=0,int=2,sh=0,uc=0)

4 ETW000                                                                                                  21  29.984945

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_INT , l=  4, con=0, stp=03B846A8, pv=03B96DC0, in=03BAC048,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  24  29.984969

4 ETW000  [     dev trc,00000]  -  oci_define  ( 1: SQLT_INT , l=  4, con=0, stp=03B846A8, pv=03BA1704, in=03BB14EA,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  55  29.985024

4 ETW000  [     dev trc,00000]     DbSlBegRead(#0) -> 0=DBSL_ERR_OK                                       18  29.985042

4 ETW000  [     dev trc,00000]  -->DbSlExeRead(#1: con=0, ss_p=02145350, da_p=021453A0)                   17  29.985059

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  27  29.985086

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             17  29.985103

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#7: con=0,stp=03B846A8,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  29.985124

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  29.985144

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                              327  29.985471

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,000s]

4 ETW000                                                                                                  31  29.985502

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=0, 0=DBSL_ERR_OK                                   14  29.985516

4 ETW000  [     dev trc,00000]     DbSlExeRead(#1,con=0) -> 0=DBSL_ERR_OK, dbcount=1, lob_pw=0            26  29.985542

4 ETW000  [     dev trc,00000]  -->DbSlEndRead(#1: con=0, ss_p=02145350, da_p=021453A0, sch=03B391E8) -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  23  29.985565

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

4 ETW000  [     dev trc,00000]  NTAB: unicodelg of executable is 2                                        10  29.985587

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

4 ETW000  [     dev trc,00000]  NTAB: standalone processing                                               58  29.985655

4 ETW000  [     dev trc,00000]  NTAB: ntprofile(), read profile                                           14  29.985669

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

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

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

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

4 ETW000  [     dev trc,00000]  NTAB: compute_hash_card: 2003.                                            20  29.985729

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

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

4 ETW000  [     dev trc,00000]  NTAB: mem_protocol_size 112                                               10  29.985769

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

4 ETW000  [     dev trc,00000]  NTAB: hdr_backpack_size 0                                                 10  29.985789

4 ETW000  [     dev trc,00000]  NTAB: FTAB: header_size 88.                                               10  29.985799

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

4 ETW000  [     dev trc,00000]  NTAB: FTAB: item_cnt 1000.                                                10  29.985819

4 ETW000  [     dev trc,00000]  NTAB: FTAB: unit_size 4                                                   10  29.985829

4 ETW000  [     dev trc,00000]  NTAB: FTAB: unit_cnt 128000.                                              36  29.985865

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

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

4 ETW000  [     dev trc,00000]  NTAB: IREC: item_size 64.                                                 10  29.985906

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

4 ETW000  [     dev trc,00000]  NTAB: IREC: unit_size 16                                                  10  29.985926

4 ETW000  [     dev trc,00000]  NTAB: IREC: unit_cnt 6400.                                                11  29.985937

4 ETW000  [     dev trc,00000]  NTAB: IREC: data_size 102400.                                             10  29.985947

4 ETW000  [     dev trc,00000]  NTAB: STAB: header_size 88.                                               12  29.985959

4 ETW000  [     dev trc,00000]  NTAB: STAB: item_size 64.                                                 12  29.985971

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

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

4 ETW000  [     dev trc,00000]  NTAB: STAB: unit_cnt 25600.                                               10  29.986001

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

4 ETW000  [     dev trc,00000]  NTAB: TTAB: header_size 200.                                              10  29.986021

4 ETW000  [     dev trc,00000]  NTAB: TTAB: item_size 40.                                                 21  29.986042

4 ETW000  [     dev trc,00000]  NTAB: TTAB: item_cnt 1000.                                                11  29.986053

4 ETW000  [     dev trc,00000]  NTAB: TTAB: unit_size 256                                                 14  29.986067

4 ETW000  [     dev trc,00000]  NTAB: TTAB: unit_cnt 1000.                                                11  29.986078

4 ETW000  [     dev trc,00000]  NTAB: TTAB: data_size 256000.                                             14  29.986092

4 ETW000  [     dev trc,00000]  NTAB: FTAB: hh_p 3c2ff70, hh_len 88                                      633  29.986725

4 ETW000  [     dev trc,00000]  NTAB: FTAB: aa_p 3bb73e0, aa_len 16024                                    17  29.986742

4 ETW000  [     dev trc,00000]  NTAB: FTAB: ia_p 3bbb280, ia_len 64000                                    11  29.986753

4 ETW000  [     dev trc,00000]  NTAB: FTAB: dat_p fcd0080, dat_len 512000                                 11  29.986764

4 ETW000  [     dev trc,00000]  NTAB: IREC: hh_p 3c28930, hh_len 88                                       11  29.986775

4 ETW000  [     dev trc,00000]  NTAB: IREC: aa_p 3bcac90, aa_len 16024                                    10  29.986785

4 ETW000  [     dev trc,00000]  NTAB: IREC: ia_p fd4d090, ia_len 16000                                    17  29.986802

4 ETW000  [     dev trc,00000]  NTAB: IREC: dat_p fd50f20, dat_len 102400                                 15  29.986817

4 ETW000  [     dev trc,00000]  NTAB: STAB: hh_p 3bceb30, hh_len 88                                       15  29.986832

4 ETW000  [     dev trc,00000]  NTAB: STAB: aa_p fd69f30, aa_len 16024                                    11  29.986843

4 ETW000  [     dev trc,00000]  NTAB: STAB: ia_p fd6ddd0, ia_len 16000                                    11  29.986854

4 ETW000  [     dev trc,00000]  NTAB: STAB: dat_p fd71c60, dat_len 102400                                 10  29.986864

4 ETW000  [     dev trc,00000]  NTAB: TTAB: hh_p 3bceb90, hh_len 200                                      11  29.986875

4 ETW000  [     dev trc,00000]  NTAB: TTAB: aa_p fd8ac70, aa_len 16024                                    10  29.986885

4 ETW000  [     dev trc,00000]  NTAB: TTAB: ia_p fd8eb10, ia_len 40000                                    11  29.986896

4 ETW000  [     dev trc,00000]  NTAB: TTAB: dat_p fd98760, dat_len 256000                                 10  29.986906

4 ETW000  [     dev trc,00000]  NTAB: ntab_mem_protocol 3bceb90, ntab_mp_p 3bceb90, ntab_proc_id 0        32  29.986938

4 ETW000  [     dev trc,00000]  NTAB: FTAB: hh_p 3c2ff70, ha_list 3bb73e0, hi_list 3bbb280, buffer fcd0080

4 ETW000                                                                                                  46  29.986984

4 ETW000  [     dev trc,00000]  NTAB: IREC: hh_p 3c28930, ha_list 3bcac90, hi_list fd4d090, buffer fd50f20

4 ETW000                                                                                                  17  29.987001

4 ETW000  [     dev trc,00000]  NTAB: STAB: hh_p 3bceb30, ha_list fd69f30, hi_list fd6ddd0, buffer fd71c60

4 ETW000                                                                                                  22  29.987023

4 ETW000  [     dev trc,00000]  NTAB: TTAB: hh_p 3bcec00, ha_list fd8ac70, hi_list fd8eb10, buffer fd98760

4 ETW000                                                                                                  18  29.987041

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

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

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

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

4 ETW000  [     dev trc,00000]  -->DbSlBegRead(#0: con=0, ssp=141C2C460, dap=141C2A360), sch=0000NULL

4 ETW000                                                                                                2924  29.990073

4 ETW000  [     dev trc,00000]  Wrong statement ID or statement not prepared.                             15  29.990088

4 ETW000  [     dev trc,00000]     DbSlBegRead(#0) -> 13=DBSL_ERR_STMTID                                  13  29.990101

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_SELECT, con=0, ss_p=141C2C460, da_p=141C2A360), stp=141C35220, stid=141C34CA0

4 ETW000                                                                                                  23  29.990124

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  79  29.990203

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              16  29.990219

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=101,stp=03B825F8); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                  22  29.990241

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

4 ETW000                                                                                                  21  29.990262

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=1, mrk=1,sch=03B39280, scp=03B42820, tab='DDNTF') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  47  29.990309

4 ETW000  [     dev trc,00000]  -->DbSlBegRead(#0: con=0, ssp=141C2C460, dap=141C2A360), In=1, Out=4, sch=03B39280, scp=03B42820, tab='DDNTF'

4 ETW000                                                                                                  27  29.990336

4 ETW000  [     dev trc,00000]    stmt=SELECT TABNAME, BLOCKNR, FIELDSLG, FIELDS FROM "DDNTF" WHERE TABNAME = :A0  ORDER BY TABNAME, BLOCKNR                    

4 ETW000                                                                                                  23  29.990359

4 ETW000  [     dev trc,00000]  STID: ssp=141C2C460->stmtid_ptr=141C34CA0->stid='10.DDNTF                                   .      1042.20131204125323'

4 ETW000                                                                                                  26  29.990385

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             15  29.990400

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             13  29.990413

4 ETW000  [     dev trc,00000]   Out  01: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.990426

4 ETW000  [     dev trc,00000]   Out  02: DBSL_DATA_INTEGER_4         (len=4, max=4, dec=0)               12  29.990438

4 ETW000  [     dev trc,00000]   Out  03: DBSL_DATA_LONG_RAW          (len=32768, max=32768, dec=0)       18  29.990456

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 1 input variables, col=1                          14  29.990470

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  29.990483

4 ETW000  [     dev trc,00000]     bind_ind_and_values(IN,con=0,arr=2031,col=1,rc=0,rl=1,flt=0,int=0,sh=0,uc=30)

4 ETW000                                                                                                  21  29.990504

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 0: SQLT_AFC , l= 60, con=0, stp=03B825F8, pv=03B98D7C, in=03B96DC0,rl=03B97D9E,rc=0000NULL)

4 ETW000                                                                                                  37  29.990541

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 4 output variables                              5819  29.996360

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   21  29.996381

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     14  29.996395

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_INTEGER_4         , len=4, max=4, dec=0                     13  29.996408

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_LONG_RAW          , len=32768, max=32768, dec=0             14  29.996422

4 ETW000  [     dev trc,00000]     bind_ind_and_values(OUT,con=0,arr=3,col=4,rc=0,rl=1,flt=0,int=2,sh=0,uc=30)

4 ETW000                                                                                                  37  29.996459

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_CHR , l= 60, con=0, stp=03B825F8, pv=0FDF2886, in=0FDF2868,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  24  29.996483

4 ETW000  [     dev trc,00000]  -  oci_define  ( 1: SQLT_INT , l=  4, con=0, stp=03B825F8, pv=0FDF2850, in=0FDF286E,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  30  29.996513

4 ETW000  [     dev trc,00000]  -  oci_define  ( 2: SQLT_INT , l=  4, con=0, stp=03B825F8, pv=0FDF285C, in=0FDF2874,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  27  29.996540

4 ETW000  [     dev trc,00000]  -  oci_define  ( 3: SQLT_LBI , l=32770, con=0, stp=03B825F8, pv=0FDF293A, in=0FDF287A,rl=0FDF2880,rc=0000NULL)

4 ETW000                                                                                                  27  29.996567

4 ETW000  [     dev trc,00000]     DbSlBegRead(#0) -> 0=DBSL_ERR_OK                                      161  29.996728

4 ETW000  [     dev trc,00000]  -->DbSlExeRead(#1: con=0, ss_p=141C2C460, da_p=141C2A360)                 23  29.996751

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  28  29.996779

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             17  29.996796

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#8: con=0,stp=03B825F8,itr=3,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  29.996817

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  29.996837

4 ETW000  [     dev trc,00000]  OCIStmtExecute() completed, rc=100=OCI_NO_DATA, no further data          759  29.997596

4 ETW000  [     dev trc,00000]     -> rc=1403=OCI_ERR_NOT_FOUND                                           20  29.997616

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                               30  29.997646

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=1403, rows processed 1, iters-errors = 3-0) [dur. 0,000s]

4 ETW000                                                                                                  34  29.997680

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=1403, 0=DBSL_ERR_OK                                19  29.997699

4 ETW000  [     dev trc,00000]     DbSlExeRead(#1,con=0) -> 0=DBSL_ERR_OK, dbcount=1, lob_pw=0            44  29.997743

4 ETW000  [     dev trc,00000]  -->DbSlEndRead(#1: con=0, ss_p=141C2C460, da_p=141C2A360, sch=03B39280) -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  26  29.997769

4 ETW000  [     dev trc,00000]  -->DbSlRead(con=0, ssp=141C2B940, dap=141C2C260), sch=0000NULL            72  29.997841

4 ETW000  [     dev trc,00000]  Wrong statement ID or statement not prepared.                             16  29.997857

4 ETW000  [     dev trc,00000]     DbSlRead() --> 13=DBSL_ERR_STMTID                                      13  29.997870

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_SELECT, con=0, ss_p=141C2B940, da_p=141C2C260), stp=141C348C0, stid=141C2A060

4 ETW000                                                                                                  22  29.997892

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  73  29.997965

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              16  29.997981

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=245,stp=0FEB3228); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                  22  29.998003

4 ETW000  [     dev trc,00000]         SELECT VERSION, UUID, CRTIMESTMP, ABTIMESTMP, DYTIMESTMP, FLDCNT,   TABLENGTH, KEYCNT, KEYLENGTH, CLIENTPOS, TABTYPE, TABF

4 ETW000                                                                                                  87  29.998090

4 ETW000  [     dev trc,00000]         ORM, REFNAME,   HFLAG, BUFSTATE, BUFPARM, ALIGN, POINTERLG, UNICODELG, COMPCNT,   LEAFCNT FROM "DDNTT" WHERE TABNAME = :A0

4 ETW000                                                                                                  22  29.998112

4 ETW000  [     dev trc,00000]                                                                                                                                  

4 ETW000                                                                                                  32  29.998144

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=1, mrk=1,sch=03B39318, scp=03B42940, tab='DDNTT') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  75  29.998219

4 ETW000  [     dev trc,00000]  -->DbSlRead(con=0, ssp=141C2B940, dap=141C2C260), sch=03B39318, scp=03B42940, tab='DDNTT'

4 ETW000                                                                                                  25  29.998244

4 ETW000  [     dev trc,00000]    stmt=SELECT VERSION, UUID, CRTIMESTMP, ABTIMESTMP, DYTIMESTMP, FLDCNT,   TABLENGTH, KEYCNT, KEYLENGTH, CLIENTPOS, TABTYPE, TABF

4 ETW000                                                                                                  26  29.998270

4 ETW000  [     dev trc,00000]         ORM, REFNAME,   HFLAG, BUFSTATE, BUFPARM, ALIGN, POINTERLG, UNICODELG, COMPCNT,   LEAFCNT FROM "DDNTT" WHERE TABNAME = :A0

4 ETW000                                                                                                  22  29.998292

4 ETW000  [     dev trc,00000]                                                                                                                                  

4 ETW000                                                                                                  24  29.998316

4 ETW000  [     dev trc,00000]  STID: ssp=141C2B940->stmtid_ptr=141C2A060->stid='10.DDNTT                                   .     11978.20131204125321'

4 ETW000                                                                                                  28  29.998344

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             15  29.998359

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  29.998372

4 ETW000  [     dev trc,00000]   Out  01: DBSL_DATA_SHORT_RAW         (len=16, max=16, dec=0)             14  29.998386

4 ETW000  [     dev trc,00000]   Out  02: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  29.998399

4 ETW000  [     dev trc,00000]   Out  03: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  29.998412

4 ETW000  [     dev trc,00000]   Out  04: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  29.998425

4 ETW000  [     dev trc,00000]   Out  05: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998438

4 ETW000  [     dev trc,00000]   Out  06: DBSL_DATA_INTEGER_4         (len=4, max=4, dec=0)               12  29.998450

4 ETW000  [     dev trc,00000]   Out  07: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998463

4 ETW000  [     dev trc,00000]   Out  08: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998476

4 ETW000  [     dev trc,00000]   Out  09: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998489

4 ETW000  [     dev trc,00000]   Out  10: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               18  29.998507

4 ETW000  [     dev trc,00000]   Out  11: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               18  29.998525

4 ETW000  [     dev trc,00000]   Out  12: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             18  29.998543

4 ETW000  [     dev trc,00000]   Out  13: DBSL_DATA_SHORT_RAW         (len=6, max=6, dec=0)               14  29.998557

4 ETW000  [     dev trc,00000]   Out  14: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  29.998570

4 ETW000  [     dev trc,00000]   Out  15: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998583

4 ETW000  [     dev trc,00000]   Out  16: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               12  29.998595

4 ETW000  [     dev trc,00000]   Out  17: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998608

4 ETW000  [     dev trc,00000]   Out  18: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998621

4 ETW000  [     dev trc,00000]   Out  19: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998634

4 ETW000  [     dev trc,00000]   Out  20: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  29.998647

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 1 input variables, col=1                          12  29.998659

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   14  29.998673

4 ETW000  [     dev trc,00000]     bind_ind_and_values(IN,con=0,arr=2031,col=1,rc=0,rl=1,flt=0,int=0,sh=0,uc=30)

4 ETW000                                                                                                  24  29.998697

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 0: SQLT_AFC , l= 60, con=0, stp=0FEB3228, pv=0FDF480C, in=0FDF2850,rl=0FDF382E,rc=0000NULL)

4 ETW000                                                                                                  25  29.998722

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 21 output variables                               22  29.998744

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     14  29.998758

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_RAW         , len=16, max=16, dec=0                   17  29.998775

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   14  29.998789

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   13  29.998802

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   13  29.998815

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.998828

4 ETW000  [     dev trc,00000]   6: DBSL_DATA_INTEGER_4         , len=4, max=4, dec=0                     12  29.998840

4 ETW000  [     dev trc,00000]   7: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.998853

4 ETW000  [     dev trc,00000]   8: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     12  29.998865

4 ETW000  [     dev trc,00000]   9: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.998878

4 ETW000  [     dev trc,00000]  10: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     22  29.998900

4 ETW000  [     dev trc,00000]  11: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     80  29.998980

4 ETW000  [     dev trc,00000]  12: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   18  29.998998

4 ETW000  [     dev trc,00000]  13: DBSL_DATA_SHORT_RAW         , len=6, max=6, dec=0                     13  29.999011

4 ETW000  [     dev trc,00000]  14: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     12  29.999023

4 ETW000  [     dev trc,00000]  15: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.999036

4 ETW000  [     dev trc,00000]  16: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.999049

4 ETW000  [     dev trc,00000]  17: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  29.999062

4 ETW000  [     dev trc,00000]  18: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     21  29.999083

4 ETW000  [     dev trc,00000]  19: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     16  29.999099

4 ETW000  [     dev trc,00000]  20: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     18  29.999117

4 ETW000  [     dev trc,00000]     bind_ind_and_values(OUT,con=0,arr=492,col=21,rc=0,rl=2,flt=0,int=11,sh=0,uc=76)

4 ETW000                                                                                                  32  29.999149

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_CHR , l=  2, con=0, stp=0FEB3228, pv=03BA1AB8, in=03B9C250,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999174

4 ETW000  [     dev trc,00000]  -  oci_define  ( 1: SQLT_LBI , l= 16, con=0, stp=0FEB3228, pv=03BB3ED8, in=03B9C628,rl=03BA1308,rc=0000NULL)

4 ETW000                                                                                                  31  29.999205

4 ETW000  [     dev trc,00000]  -  oci_define  ( 2: SQLT_CHR , l= 28, con=0, stp=0FEB3228, pv=03BA1E90, in=03B9CA00,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  27  29.999232

4 ETW000  [     dev trc,00000]  -  oci_define  ( 3: SQLT_CHR , l= 28, con=0, stp=0FEB3228, pv=03BA5460, in=03B9CDD8,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  29.999258

4 ETW000  [     dev trc,00000]  -  oci_define  ( 4: SQLT_CHR , l= 28, con=0, stp=0FEB3228, pv=03BA8A30, in=03B9D1B0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999283

4 ETW000  [     dev trc,00000]  -  oci_define  ( 5: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B96DC0, in=03B9D588,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  87  29.999370

4 ETW000  [     dev trc,00000]  -  oci_define  ( 6: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B97570, in=03B9D960,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  35  29.999405

4 ETW000  [     dev trc,00000]  -  oci_define  ( 7: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B97D20, in=03B9DD38,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  29.999431

4 ETW000  [     dev trc,00000]  -  oci_define  ( 8: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B984D0, in=03B9E110,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999456

4 ETW000  [     dev trc,00000]  -  oci_define  ( 9: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B98C80, in=03B9E4E8,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999481

4 ETW000  [     dev trc,00000]  -  oci_define  (10: SQLT_CHR , l=  2, con=0, stp=0FEB3228, pv=03BAC000, in=03B9E8C0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999506

4 ETW000  [     dev trc,00000]  -  oci_define  (11: SQLT_CHR , l=  2, con=0, stp=0FEB3228, pv=03BAC3D8, in=03B9EC98,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                 109  29.999615

4 ETW000  [     dev trc,00000]  -  oci_define  (12: SQLT_CHR , l= 60, con=0, stp=0FEB3228, pv=03BAC7B0, in=03B9F070,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  29  29.999644

4 ETW000  [     dev trc,00000]  -  oci_define  (13: SQLT_LBI , l=  6, con=0, stp=0FEB3228, pv=03BB5D98, in=03B9F448,rl=03BA16E0,rc=0000NULL)

4 ETW000                                                                                                  28  29.999672

4 ETW000  [     dev trc,00000]  -  oci_define  (14: SQLT_CHR , l=  2, con=0, stp=0FEB3228, pv=03BB3B00, in=03B9F820,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999697

4 ETW000  [     dev trc,00000]  -  oci_define  (15: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B99430, in=03B9FBF8,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999722

4 ETW000  [     dev trc,00000]  -  oci_define  (16: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B99BE0, in=03B9FFD0,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999747

4 ETW000  [     dev trc,00000]  -  oci_define  (17: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B9A390, in=03BA03A8,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  29.999772

4 ETW000  [     dev trc,00000]  -  oci_define  (18: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B9AB40, in=03BA0780,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  35  29.999807

4 ETW000  [     dev trc,00000]  -  oci_define  (19: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B9B2F0, in=03BA0B58,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  30  29.999837

4 ETW000  [     dev trc,00000]  -  oci_define  (20: SQLT_INT , l=  4, con=0, stp=0FEB3228, pv=03B9BAA0, in=03BA0F30,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  33  29.999870

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  36  29.999906

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             18  29.999924

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#9: con=0,stp=0FEB3228,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  29.999945

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  29.999965

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                              493  30.000458

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,000s]

4 ETW000                                                                                                  28  30.000486

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=0, 0=DBSL_ERR_OK                                   14  30.000500

4 ETW000  [     dev trc,00000]     DbSlRead() --> 0=DBSL_ERR_OK                                           16  30.000516

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

4 ETW000                                                                                                  69  30.000585

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=12=DBSL_CMD_VENDOR_ID_GET)                     7283  30.007868

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=20=DBSL_CMD_DBFEATURES_PTR_GET)                  21  30.007889

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=2=DBSL_CMD_DBSTATS_PTR_GET)                      14  30.007903

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=10=DBSL_CMD_DBCA_PTR_GET)                        13  30.007916

4 ETW000  [     dev trc,00000]  -->DbSlRead(con=0, ssp=141819080, dap=141818298), sch=0000NULL          5534  30.013450

4 ETW000  [     dev trc,00000]  Wrong statement ID or statement not prepared.                             20  30.013470

4 ETW000  [     dev trc,00000]     DbSlRead() --> 13=DBSL_ERR_STMTID                                      13  30.013483

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_SELECT, con=0, ss_p=141819080, da_p=141818298), stp=141817FF8, stid=141818220

4 ETW000                                                                                                  30  30.013513

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  88  30.013601

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              16  30.013617

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=76,stp=0FE1B638); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                1409  30.015026

4 ETW000  [     dev trc,00000]         SELECT * FROM "TADIR" WHERE "PGMID"=:A0 AND "OBJECT"=:A1 AND "OBJ_NAME"=:A2                                              

4 ETW000                                                                                                  31  30.015057

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=3, mrk=3,sch=03B393B0, scp=03B42A60, tab='TADIR') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  51  30.015108

4 ETW000  [     dev trc,00000]  -->DbSlRead(con=0, ssp=141819080, dap=141818298), sch=03B393B0, scp=03B42A60, tab='TADIR'

4 ETW000                                                                                                  26  30.015134

4 ETW000  [     dev trc,00000]    stmt=SELECT * FROM "TADIR" WHERE "PGMID"=:A0 AND "OBJECT"=:A1 AND "OBJ_NAME"=:A2                                              

4 ETW000                                                                                                  28  30.015162

4 ETW000  [     dev trc,00000]  STID: ssp=141819080->stmtid_ptr=141818220->stid=' 3.TADIR                                   .1073741825.20110915182822'

4 ETW000                                                                                                  27  30.015189

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=8, max=8, dec=0)               15  30.015204

4 ETW000  [     dev trc,00000]   In   01: DBSL_DATA_SHORT_CHAR        (len=8, max=8, dec=0)               13  30.015217

4 ETW000  [     dev trc,00000]   In   02: DBSL_DATA_SHORT_CHAR        (len=80, max=80, dec=0)             13  30.015230

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_SHORT_CHAR        (len=8, max=8, dec=0)               13  30.015243

4 ETW000  [     dev trc,00000]   Out  01: DBSL_DATA_SHORT_CHAR        (len=8, max=8, dec=0)               13  30.015256

4 ETW000  [     dev trc,00000]   Out  02: DBSL_DATA_SHORT_CHAR        (len=80, max=80, dec=0)             12  30.015268

4 ETW000  [     dev trc,00000]   Out  03: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             14  30.015282

4 ETW000  [     dev trc,00000]   Out  04: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             12  30.015294

4 ETW000  [     dev trc,00000]   Out  05: DBSL_DATA_SHORT_CHAR        (len=24, max=24, dec=0)             13  30.015307

4 ETW000  [     dev trc,00000]   Out  06: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.015320

4 ETW000  [     dev trc,00000]   Out  07: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             13  30.015333

4 ETW000  [     dev trc,00000]   Out  08: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.015346

4 ETW000  [     dev trc,00000]   Out  09: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.015359

4 ETW000  [     dev trc,00000]   Out  10: DBSL_DATA_SHORT_CHAR        (len=16, max=16, dec=0)             13  30.015372

4 ETW000  [     dev trc,00000]   Out  11: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               12  30.015384

4 ETW000  [     dev trc,00000]   Out  12: DBSL_DATA_SHORT_CHAR        (len=40, max=40, dec=0)             14  30.015398

4 ETW000  [     dev trc,00000]   Out  13: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               12  30.015410

4 ETW000  [     dev trc,00000]   Out  14: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.015423

4 ETW000  [     dev trc,00000]   Out  15: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             13  30.015436

4 ETW000  [     dev trc,00000]   Out  16: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             13  30.015449

4 ETW000  [     dev trc,00000]   Out  17: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.015462

4 ETW000  [     dev trc,00000]   Out  18: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.015475

4 ETW000  [     dev trc,00000]   Out  19: DBSL_DATA_NUMC              (len=16, max=16, dec=0)             13  30.015488

4 ETW000  [     dev trc,00000]   Out  20: DBSL_DATA_NUMC              (len=16, max=16, dec=0)             13  30.015501

4 ETW000  [     dev trc,00000]   Out  21: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             12  30.015513

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 3 input variables, col=3                          13  30.015526

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=8, max=8, dec=0                     13  30.015539

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_CHAR        , len=8, max=8, dec=0                     13  30.015552

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=80, max=80, dec=0                   13  30.015565

4 ETW000  [     dev trc,00000]     bind_ind_and_values(IN,con=0,arr=1203,col=3,rc=0,rl=3,flt=0,int=0,sh=0,uc=48)

4 ETW000                                                                                                  25  30.015590

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 0: SQLT_AFC , l=  8, con=0, stp=0FE1B638, pv=03B9A624, in=03B96DC0,rl=03B989F2,rc=0000NULL)

4 ETW000                                                                                                  25  30.015615

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 1: SQLT_AFC , l=  8, con=0, stp=0FE1B638, pv=03B9CBBC, in=03B97726,rl=03B99358,rc=0000NULL)

4 ETW000                                                                                                  33  30.015648

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 2: SQLT_AFC , l= 80, con=0, stp=0FE1B638, pv=03B9F154, in=03B9808C,rl=03B99CBE,rc=0000NULL)

4 ETW000                                                                                                  28  30.015676

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 22 output variables                               18  30.015694

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=8, max=8, dec=0                     14  30.015708

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_CHAR        , len=8, max=8, dec=0                     12  30.015720

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=80, max=80, dec=0                   13  30.015733

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   13  30.015746

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   13  30.015759

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_SHORT_CHAR        , len=24, max=24, dec=0                   12  30.015771

4 ETW000  [     dev trc,00000]   6: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.015784

4 ETW000  [     dev trc,00000]   7: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.015797

4 ETW000  [     dev trc,00000]   8: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     12  30.015809

4 ETW000  [     dev trc,00000]   9: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.015822

4 ETW000  [     dev trc,00000]  10: DBSL_DATA_SHORT_CHAR        , len=16, max=16, dec=0                   13  30.015835

4 ETW000  [     dev trc,00000]  11: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     12  30.015847

4 ETW000  [     dev trc,00000]  12: DBSL_DATA_SHORT_CHAR        , len=40, max=40, dec=0                   13  30.015860

4 ETW000  [     dev trc,00000]  13: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     12  30.015872

4 ETW000  [     dev trc,00000]  14: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.015885

4 ETW000  [     dev trc,00000]  15: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.015898

4 ETW000  [     dev trc,00000]  16: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   12  30.015910

4 ETW000  [     dev trc,00000]  17: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.015923

4 ETW000  [     dev trc,00000]  18: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.015936

4 ETW000  [     dev trc,00000]  19: DBSL_DATA_NUMC              , len=16, max=16, dec=0                   12  30.015948

4 ETW000  [     dev trc,00000]  20: DBSL_DATA_NUMC              , len=16, max=16, dec=0                   13  30.015961

4 ETW000  [     dev trc,00000]  21: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.015974

4 ETW000  [     dev trc,00000]     bind_ind_and_values(OUT,con=0,arr=255,col=22,rc=0,rl=0,flt=0,int=0,sh=0,uc=232)

4 ETW000                                                                                                  21  30.015995

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_CHR , l=  8, con=0, stp=0FE1B638, pv=0FDF5424, in=0FDF2850,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  29  30.016024

4 ETW000  [     dev trc,00000]  -  oci_define  ( 1: SQLT_CHR , l=  8, con=0, stp=0FE1B638, pv=0FDF5C1C, in=0FDF2A4E,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  29  30.016053

4 ETW000  [     dev trc,00000]  -  oci_define  ( 2: SQLT_CHR , l= 80, con=0, stp=0FE1B638, pv=0FDF6414, in=0FDF2C4C,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016078

4 ETW000  [     dev trc,00000]  -  oci_define  ( 3: SQLT_CHR , l= 20, con=0, stp=0FE1B638, pv=0FDFB3C4, in=0FDF2E4A,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  36  30.016114

4 ETW000  [     dev trc,00000]  -  oci_define  ( 4: SQLT_CHR , l= 20, con=0, stp=0FE1B638, pv=0FDFC7B0, in=0FDF3048,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  30.016140

4 ETW000  [     dev trc,00000]  -  oci_define  ( 5: SQLT_CHR , l= 24, con=0, stp=0FE1B638, pv=0FDFDB9C, in=0FDF3246,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016165

4 ETW000  [     dev trc,00000]  -  oci_define  ( 6: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FDFF384, in=0FDF3444,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  24  30.016189

4 ETW000  [     dev trc,00000]  -  oci_define  ( 7: SQLT_CHR , l= 60, con=0, stp=0FE1B638, pv=0FDFF582, in=0FDF3642,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016214

4 ETW000  [     dev trc,00000]  -  oci_define  ( 8: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FE03146, in=0FDF3840,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016239

4 ETW000  [     dev trc,00000]  -  oci_define  ( 9: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FE03344, in=0FDF3A3E,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  28  30.016267

4 ETW000  [     dev trc,00000]  -  oci_define  (10: SQLT_CHR , l= 16, con=0, stp=0FE1B638, pv=0FE03542, in=0FDF3C3C,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016292

4 ETW000  [     dev trc,00000]  -  oci_define  (11: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FE04532, in=0FDF3E3A,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016317

4 ETW000  [     dev trc,00000]  -  oci_define  (12: SQLT_CHR , l= 40, con=0, stp=0FE1B638, pv=0FE04730, in=0FDF4038,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016342

4 ETW000  [     dev trc,00000]  -  oci_define  (13: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FE06F08, in=0FDF4236,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  24  30.016366

4 ETW000  [     dev trc,00000]  -  oci_define  (14: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FE07106, in=0FDF4434,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016391

4 ETW000  [     dev trc,00000]  -  oci_define  (15: SQLT_CHR , l= 60, con=0, stp=0FE1B638, pv=0FE07304, in=0FDF4632,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  28  30.016419

4 ETW000  [     dev trc,00000]  -  oci_define  (16: SQLT_CHR , l= 20, con=0, stp=0FE1B638, pv=0FE0AEC8, in=0FDF4830,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  28  30.016447

4 ETW000  [     dev trc,00000]  -  oci_define  (17: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FE0C2B4, in=0FDF4A2E,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  30.016473

4 ETW000  [     dev trc,00000]  -  oci_define  (18: SQLT_CHR , l=  2, con=0, stp=0FE1B638, pv=0FE0C4B2, in=0FDF4C2C,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  27  30.016500

4 ETW000  [     dev trc,00000]  -  oci_define  (19: SQLT_CHR , l= 16, con=0, stp=0FE1B638, pv=0FE0C6B0, in=0FDF4E2A,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016525

4 ETW000  [     dev trc,00000]  -  oci_define  (20: SQLT_CHR , l= 16, con=0, stp=0FE1B638, pv=0FE0D6A0, in=0FDF5028,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.016550

4 ETW000  [     dev trc,00000]  -  oci_define  (21: SQLT_CHR , l= 60, con=0, stp=0FE1B638, pv=0FE0E690, in=0FDF5226,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  30.016576

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  27  30.016603

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             18  30.016621

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#10: con=0,stp=0FE1B638,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  20  30.016641

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.016661

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,031s]                            32351  30.049012

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,031s]

4 ETW000                                                                                                  28  30.049040

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=0, 0=DBSL_ERR_OK                                   15  30.049055

4 ETW000  [     dev trc,00000]     DbSlRead() --> 0=DBSL_ERR_OK                                           15  30.049070

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

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

4 ETW000                                                                                                 106  30.049189

4 ETW000  [     dev trc,00000]  -->DbSlRead(con=0, ssp=141C2B940, dap=141C2C260), sch=03B39318, scp=03B42940, tab='DDNTT'

4 ETW000                                                                                                  29  30.049218

4 ETW000  [     dev trc,00000]    stmt=SELECT VERSION, UUID, CRTIMESTMP, ABTIMESTMP, DYTIMESTMP, FLDCNT,   TABLENGTH, KEYCNT, KEYLENGTH, CLIENTPOS, TABTYPE, TABF

4 ETW000                                                                                                  27  30.049245

4 ETW000  [     dev trc,00000]         ORM, REFNAME,   HFLAG, BUFSTATE, BUFPARM, ALIGN, POINTERLG, UNICODELG, COMPCNT,   LEAFCNT FROM "DDNTT" WHERE TABNAME = :A0

4 ETW000                                                                                                  25  30.049270

4 ETW000  [     dev trc,00000]                                                                                                                                  

4 ETW000                                                                                                  23  30.049293

4 ETW000  [     dev trc,00000]  STID: ssp=141C2B940->stmtid_ptr=141C2A060->stid='10.DDNTT                                   .     11978.20131204125321'

4 ETW000                                                                                                  26  30.049319

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             15  30.049334

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.049347

4 ETW000  [     dev trc,00000]   Out  01: DBSL_DATA_SHORT_RAW         (len=16, max=16, dec=0)             13  30.049360

4 ETW000  [     dev trc,00000]   Out  02: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.049373

4 ETW000  [     dev trc,00000]   Out  03: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.049386

4 ETW000  [     dev trc,00000]   Out  04: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.049399

4 ETW000  [     dev trc,00000]   Out  05: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.049412

4 ETW000  [     dev trc,00000]   Out  06: DBSL_DATA_INTEGER_4         (len=4, max=4, dec=0)               13  30.049425

4 ETW000  [     dev trc,00000]   Out  07: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.049438

4 ETW000  [     dev trc,00000]   Out  08: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               12  30.049450

4 ETW000  [     dev trc,00000]   Out  09: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.049463

4 ETW000  [     dev trc,00000]   Out  10: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.049476

4 ETW000  [     dev trc,00000]   Out  11: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.049489

4 ETW000  [     dev trc,00000]   Out  12: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             13  30.049502

4 ETW000  [     dev trc,00000]   Out  13: DBSL_DATA_SHORT_RAW         (len=6, max=6, dec=0)               12  30.049514

4 ETW000  [     dev trc,00000]   Out  14: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               24  30.049538

4 ETW000  [     dev trc,00000]   Out  15: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               76  30.049614

4 ETW000  [     dev trc,00000]   Out  16: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               14  30.049628

4 ETW000  [     dev trc,00000]   Out  17: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               14  30.049642

4 ETW000  [     dev trc,00000]   Out  18: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.049655

4 ETW000  [     dev trc,00000]   Out  19: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.049668

4 ETW000  [     dev trc,00000]   Out  20: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.049681

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 1 input variables, col=1                          13  30.049694

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.049707

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 21 output variables                               14  30.049721

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     14  30.049735

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_RAW         , len=16, max=16, dec=0                   12  30.049747

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   23  30.049770

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   19  30.049789

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   20  30.049809

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     18  30.049827

4 ETW000  [     dev trc,00000]   6: DBSL_DATA_INTEGER_4         , len=4, max=4, dec=0                     17  30.049844

4 ETW000  [     dev trc,00000]   7: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     14  30.049858

4 ETW000  [     dev trc,00000]   8: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     12  30.049870

4 ETW000  [     dev trc,00000]   9: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.049883

4 ETW000  [     dev trc,00000]  10: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.049896

4 ETW000  [     dev trc,00000]  11: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.049909

4 ETW000  [     dev trc,00000]  12: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   12  30.049921

4 ETW000  [     dev trc,00000]  13: DBSL_DATA_SHORT_RAW         , len=6, max=6, dec=0                     13  30.049934

4 ETW000  [     dev trc,00000]  14: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.049947

4 ETW000  [     dev trc,00000]  15: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     12  30.049959

4 ETW000  [     dev trc,00000]  16: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.049972

4 ETW000  [     dev trc,00000]  17: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.049985

4 ETW000  [     dev trc,00000]  18: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     12  30.049997

4 ETW000  [     dev trc,00000]  19: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.050010

4 ETW000  [     dev trc,00000]  20: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.050023

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  29  30.050052

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             18  30.050070

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#11: con=0,stp=0FEB3228,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  30.050091

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.050111

4 ETW000  [     dev trc,00000]  OCIStmtExecute() completed, rc=100=OCI_NO_DATA, no further data          204  30.050315

4 ETW000  [     dev trc,00000]     -> rc=1403=OCI_ERR_NOT_FOUND                                           19  30.050334

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,000s]                               25  30.050359

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=1403, rows processed 0, iters-errors = 1-0) [dur. 0,000s]

4 ETW000                                                                                                  20  30.050379

4 ETW000  [     dev trc,00000]  -->oci_get_errmsg (con=0, rc=1403)                                        49  30.050428

4 ETW000  [     dev trc,00000]     OCIErrorGet() -> SQL error code: 1403                                  94  30.050522

4 ETW000  [     dev trc,00000]  ORA-01403: no data found                                                                                                        

4 ETW000                                                                                                  24  30.050546

4 ETW000  [     dev trc,00000]  ocica() -> SQL code 1403,1403                                             19  30.050565

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=1403, 24=DBSL_ERR_NOT_FOUND                        15  30.050580

4 ETW000  [     dev trc,00000]  rc=24=DBSL_ERR_NOT_FOUND, suppl=999, ft=1, cp=0, bulk=0, xcnt=1, da_p->xcnt=0, dbcount=0

4 ETW000                                                                                                  30  30.050610

4 ETW000  [     dev trc,00000]  row_i=0, row_pcnt=0, row_upto=4294967295, row_xcnt=1, row_max=1           17  30.050627

4 ETW000  [     dev trc,00000]     DbSlRead() --> 24=DBSL_ERR_NOT_FOUND                                   18  30.050645

4 ETW000  [    dbcrtab ,00000]  ***LOG BZ9=>table MTSETTINGS does not exist in nametab                    70  30.050715

4 ETW000  [     dev trc,00000]  } db_rtab( fcode = 'RT_READ_ONLY', retcode = 32 )                         16  30.050731

4 ETW000 Connected to DBMS = ORACLE --- dbs_ora_tnsname = 'MBW' --- SYSTEM = 'SAP'.

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=13=DBSL_CMD_VENDOR_NAME_GET)                     94  30.050825

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

4 ETW000                                                                                                 202  30.051027

4 ETW000  [     dev trc,00000]  -->DbSlRead(con=0, ssp=141C2B940, dap=141C2C260), sch=03B39318, scp=03B42940, tab='DDNTT'

4 ETW000                                                                                                  96  30.051123

4 ETW000  [     dev trc,00000]    stmt=SELECT VERSION, UUID, CRTIMESTMP, ABTIMESTMP, DYTIMESTMP, FLDCNT,   TABLENGTH, KEYCNT, KEYLENGTH, CLIENTPOS, TABTYPE, TABF

4 ETW000                                                                                                  27  30.051150

4 ETW000  [     dev trc,00000]         ORM, REFNAME,   HFLAG, BUFSTATE, BUFPARM, ALIGN, POINTERLG, UNICODELG, COMPCNT,   LEAFCNT FROM "DDNTT" WHERE TABNAME = :A0

4 ETW000                                                                                                  22  30.051172

4 ETW000  [     dev trc,00000]                                                                                                                                  

4 ETW000                                                                                                  30  30.051202

4 ETW000  [     dev trc,00000]  STID: ssp=141C2B940->stmtid_ptr=141C2A060->stid='10.DDNTT                                   .     11978.20131204125321'

4 ETW000                                                                                                  33  30.051235

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             20  30.051255

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               17  30.051272

4 ETW000  [     dev trc,00000]   Out  01: DBSL_DATA_SHORT_RAW         (len=16, max=16, dec=0)             14  30.051286

4 ETW000  [     dev trc,00000]   Out  02: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.051299

4 ETW000  [     dev trc,00000]   Out  03: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.051312

4 ETW000  [     dev trc,00000]   Out  04: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.051325

4 ETW000  [     dev trc,00000]   Out  05: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               12  30.051337

4 ETW000  [     dev trc,00000]   Out  06: DBSL_DATA_INTEGER_4         (len=4, max=4, dec=0)               13  30.051350

4 ETW000  [     dev trc,00000]   Out  07: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.051363

4 ETW000  [     dev trc,00000]   Out  08: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.051376

4 ETW000  [     dev trc,00000]   Out  09: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               12  30.051388

4 ETW000  [     dev trc,00000]   Out  10: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               18  30.051406

4 ETW000  [     dev trc,00000]   Out  11: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               15  30.051421

4 ETW000  [     dev trc,00000]   Out  12: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             13  30.051434

4 ETW000  [     dev trc,00000]   Out  13: DBSL_DATA_SHORT_RAW         (len=6, max=6, dec=0)               13  30.051447

4 ETW000  [     dev trc,00000]   Out  14: DBSL_DATA_SHORT_CHAR        (len=2, max=2, dec=0)               13  30.051460

4 ETW000  [     dev trc,00000]   Out  15: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.051473

4 ETW000  [     dev trc,00000]   Out  16: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.051486

4 ETW000  [     dev trc,00000]   Out  17: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               12  30.051498

4 ETW000  [     dev trc,00000]   Out  18: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.051511

4 ETW000  [     dev trc,00000]   Out  19: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.051524

4 ETW000  [     dev trc,00000]   Out  20: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               13  30.051537

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 1 input variables, col=1                          13  30.051550

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.051563

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 21 output variables                               14  30.051577

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.051590

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_RAW         , len=16, max=16, dec=0                   14  30.051604

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   65  30.051669

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   18  30.051687

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   13  30.051700

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.051713

4 ETW000  [     dev trc,00000]   6: DBSL_DATA_INTEGER_4         , len=4, max=4, dec=0                     20  30.051733

4 ETW000  [     dev trc,00000]   7: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.051746

4 ETW000  [     dev trc,00000]   8: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.051759

4 ETW000  [     dev trc,00000]   9: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.051772

4 ETW000  [     dev trc,00000]  10: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     12  30.051784

4 ETW000  [     dev trc,00000]  11: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     13  30.051797

4 ETW000  [     dev trc,00000]  12: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.051810

4 ETW000  [     dev trc,00000]  13: DBSL_DATA_SHORT_RAW         , len=6, max=6, dec=0                     13  30.051823

4 ETW000  [     dev trc,00000]  14: DBSL_DATA_SHORT_CHAR        , len=2, max=2, dec=0                     12  30.051835

4 ETW000  [     dev trc,00000]  15: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     24  30.051859

4 ETW000  [     dev trc,00000]  16: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     14  30.051873

4 ETW000  [     dev trc,00000]  17: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     17  30.051890

4 ETW000  [     dev trc,00000]  18: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     17  30.051907

4 ETW000  [     dev trc,00000]  19: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     17  30.051924

4 ETW000  [     dev trc,00000]  20: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     18  30.051942

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  32  30.051974

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             18  30.051992

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#12: con=0,stp=0FEB3228,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  30.052013

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.052033

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                              213  30.052246

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,000s]

4 ETW000                                                                                                  28  30.052274

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=0, 0=DBSL_ERR_OK                                   14  30.052288

4 ETW000  [     dev trc,00000]     DbSlRead() --> 0=DBSL_ERR_OK                                           15  30.052303

4 ETW000  [     dev trc,00000]  -->DbSlBegRead(#0: con=0, ssp=141C2C460, dap=141C2A360), In=1, Out=4, sch=03B39280, scp=03B42820, tab='DDNTF'

4 ETW000                                                                                                  32  30.052335

4 ETW000  [     dev trc,00000]    stmt=SELECT TABNAME, BLOCKNR, FIELDSLG, FIELDS FROM "DDNTF" WHERE TABNAME = :A0  ORDER BY TABNAME, BLOCKNR                    

4 ETW000                                                                                                  24  30.052359

4 ETW000  [     dev trc,00000]  STID: ssp=141C2C460->stmtid_ptr=141C34CA0->stid='10.DDNTF                                   .      1042.20131204125323'

4 ETW000                                                                                                  26  30.052385

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             14  30.052399

4 ETW000  [     dev trc,00000]   Out  00: DBSL_DATA_SHORT_CHAR        (len=60, max=60, dec=0)             14  30.052413

4 ETW000  [     dev trc,00000]   Out  01: DBSL_DATA_INTEGER_2         (len=2, max=2, dec=0)               12  30.052425

4 ETW000  [     dev trc,00000]   Out  02: DBSL_DATA_INTEGER_4         (len=4, max=4, dec=0)               13  30.052438

4 ETW000  [     dev trc,00000]   Out  03: DBSL_DATA_LONG_RAW          (len=32768, max=32768, dec=0)       13  30.052451

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 1 input variables, col=1                          13  30.052464

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.052477

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 4 output variables                                14  30.052491

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=60, max=60, dec=0                   13  30.052504

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_INTEGER_2         , len=2, max=2, dec=0                     13  30.052517

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_INTEGER_4         , len=4, max=4, dec=0                     12  30.052529

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_LONG_RAW          , len=32768, max=32768, dec=0             43  30.052572

4 ETW000  [     dev trc,00000]     DbSlBegRead(#0) -> 0=DBSL_ERR_OK                                       16  30.052588

4 ETW000  [     dev trc,00000]  -->DbSlExeRead(#1: con=0, ss_p=141C2C460, da_p=141C2A360)                 16  30.052604

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  34  30.052638

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             18  30.052656

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#13: con=0,stp=03B825F8,itr=3,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  30.052677

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.052697

4 ETW000  [     dev trc,00000]  OCIStmtExecute() completed, rc=100=OCI_NO_DATA, no further data          246  30.052943

4 ETW000  [     dev trc,00000]     -> rc=1403=OCI_ERR_NOT_FOUND                                           19  30.052962

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                               17  30.052979

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=1403, rows processed 1, iters-errors = 3-0) [dur. 0,000s]

4 ETW000                                                                                                  20  30.052999

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=1403, 0=DBSL_ERR_OK                                13  30.053012

4 ETW000  [     dev trc,00000]     DbSlExeRead(#1,con=0) -> 0=DBSL_ERR_OK, dbcount=1, lob_pw=0            19  30.053031

4 ETW000  [     dev trc,00000]  -->DbSlEndRead(#1: con=0, ss_p=141C2C460, da_p=141C2A360, sch=03B39280) -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  22  30.053053

4 ETW000  [     dev trc,00000]  -->DbSlModify(DBSL_INSERT, con=0, ssp=141819080, dap=141818298), sch=0000NULL

4 ETW000                                                                                                  48  30.053101

4 ETW000  [     dev trc,00000]  Wrong statement ID or statement not prepared.                             14  30.053115

4 ETW000  [     dev trc,00000]     DbSlModify(con=0) -> 13=DBSL_ERR_STMTID                                12  30.053127

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_INSERT, con=0, ss_p=141819080, da_p=141818298), stp=141817FF8, stid=141818220

4 ETW000                                                                                                  24  30.053151

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  67  30.053218

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              16  30.053234

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=71,stp=0FE289F8); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                  22  30.053256

4 ETW000  [     dev trc,00000]         INSERT INTO "PATCHHIST" VALUES(:A0 ,:A1 ,:A2 ,:A3 ,:A4 ,:A5 ,:A6 ,:A7 )                                                  

4 ETW000                                                                                                  21  30.053277

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=8, mrk=8,sch=03B39448, scp=03B42B80, tab='PATCHHIST') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  46  30.053323

4 ETW000  [     dev trc,00000]  -->DbSlModify(DBSL_INSERT, con=0, ssp=141819080, dap=141818298, sch=03B39448), tab='PATCHHIST'

4 ETW000                                                                                                  24  30.053347

4 ETW000  [     dev trc,00000]    stmt=INSERT INTO "PATCHHIST" VALUES(:A0 ,:A1 ,:A2 ,:A3 ,:A4 ,:A5 ,:A6 ,:A7 )                                                  

4 ETW000                                                                                                  30  30.053377

4 ETW000  [     dev trc,00000]  STID: ssp=141819080->stmtid_ptr=141818220->stid=' 3.PATCHHIST                               . 268435456.20110915183634'

4 ETW000                                                                                                  56  30.053433

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=40, max=40, dec=0)             19  30.053452

4 ETW000  [     dev trc,00000]   In   01: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             13  30.053465

4 ETW000  [     dev trc,00000]   In   02: DBSL_DATA_SHORT_CHAR        (len=44, max=44, dec=0)             13  30.053478

4 ETW000  [     dev trc,00000]   In   03: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             13  30.053491

4 ETW000  [     dev trc,00000]   In   04: DBSL_DATA_SHORT_CHAR        (len=6, max=6, dec=0)               13  30.053504

4 ETW000  [     dev trc,00000]   In   05: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.053517

4 ETW000  [     dev trc,00000]   In   06: DBSL_DATA_NUMC              (len=20, max=20, dec=0)             13  30.053530

4 ETW000  [     dev trc,00000]   In   07: DBSL_DATA_NUMC              (len=20, max=20, dec=0)             13  30.053543

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 8 input variables, col=8                          12  30.053555

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=40, max=40, dec=0                   14  30.053569

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   20  30.053589

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=44, max=44, dec=0                   16  30.053605

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   18  30.053623

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=6, max=6, dec=0                     17  30.053640

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   17  30.053657

4 ETW000  [     dev trc,00000]   6: DBSL_DATA_NUMC              , len=20, max=20, dec=0                   14  30.053671

4 ETW000  [     dev trc,00000]   7: DBSL_DATA_NUMC              , len=20, max=20, dec=0                   13  30.053684

4 ETW000  [     dev trc,00000]     bind_ind_and_values(IN,con=0,arr=565,col=8,rc=0,rl=8,flt=0,int=0,sh=0,uc=99)

4 ETW000                                                                                                  21  30.053705

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 0: SQLT_AFC , l= 40, con=0, stp=0FE289F8, pv=0FDF6EF0, in=0FDF2850,rl=0FDF4BA0,rc=0000NULL)

4 ETW000                                                                                                  25  30.053730

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 1: SQLT_AFC , l= 20, con=0, stp=0FE289F8, pv=0FDFC738, in=0FDF2CBA,rl=0FDF500A,rc=0000NULL)

4 ETW000                                                                                                  46  30.053776

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 2: SQLT_AFC , l= 44, con=0, stp=0FE289F8, pv=0FDFF35C, in=0FDF3124,rl=0FDF5474,rc=0000NULL)

4 ETW000                                                                                                  30  30.053806

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 3: SQLT_AFC , l= 20, con=0, stp=0FE289F8, pv=0FE05478, in=0FDF358E,rl=0FDF58DE,rc=0000NULL)

4 ETW000                                                                                                  28  30.053834

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 4: SQLT_AFC , l=  6, con=0, stp=0FE289F8, pv=0FE0809C, in=0FDF39F8,rl=0FDF5D48,rc=0000NULL)

4 ETW000                                                                                                  28  30.053862

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 5: SQLT_AFC , l= 28, con=0, stp=0FE289F8, pv=0FE08DDA, in=0FDF3E62,rl=0FDF61B2,rc=0000NULL)

4 ETW000                                                                                                  36  30.053898

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 6: SQLT_AFC , l= 20, con=0, stp=0FE289F8, pv=0FE0CBA6, in=0FDF42CC,rl=0FDF661C,rc=0000NULL)

4 ETW000                                                                                                  30  30.053928

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 7: SQLT_AFC , l= 20, con=0, stp=0FE289F8, pv=0FE0F7CA, in=0FDF4736,rl=0FDF6A86,rc=0000NULL)

4 ETW000                                                                                                  28  30.053956

4 ETW000  [     dev trc,00000]  -->exec_modify(): bulk=0, input_cnt=8, lit_cnt=0, fae_cnt=0               19  30.053975

4 ETW000  [     dev trc,00000]  stmt_execute?: row_ _max=1, _xcnt=0, _i=1, _pcnt=0, _upto=4294967295, _total=0

4 ETW000                                                                                                  98  30.054073

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  36  30.054109

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             18  30.054127

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#14: con=0,stp=0FE289F8,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  30.054148

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.054168

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                              464  30.054632

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,000s]

4 ETW000                                                                                                  28  30.054660

4 ETW000  [     dev trc,00000]  IN array size xcnt=1, IN->row_total=1                                     15  30.054675

4 ETW000  [     dev trc,00000]  orpc=1, DbCa.dbcount=1, DbSt.counter.dbrecords=1                          12  30.054687

4 ETW000  [     dev trc,00000]     stmt_execute() -> orc=0 (orc1=0), rc=0=DBSL_ERR_OK                     14  30.054701

4 ETW000  [     dev trc,00000]  <--exec_modify() -> 0=DBSL_ERR_OK, supply=999                             13  30.054714

4 ETW000  [     dev trc,00000]     DbSlModify(con=0) -> 0=DBSL_ERR_OK                                     13  30.054727

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

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

4 ETW000                          name=UNKNOWN, stmtid=NULL,

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

4 ETW000  [     dev trc,00000]  vv Ds_prep_open(head_p=000000000214EBF0, In=0)                            37  30.054828

4 ETW000  [     dev trc,00000]  vv cursor_open(head_p=000000000214EBF0, In=0, re=0)                       62  30.054890

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_SELECT, con=0, ss_p=7FEE28CC888, da_p=7FEE28CC7F0), stp=7FEE28CC868, stid=0000NULL

4 ETW000                                                                                                  32  30.054922

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  69  30.054991

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              19  30.055010

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=303,stp=0FE2C108); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                  22  30.055032

4 ETW000  [     dev trc,00000]         SELECT EXECUTABLE,SAPRELEASE,HOSTNAME,PLATFORM,DBVENDOR,TIMESTAMP,PATCHNO,DBSLPATCHN FROM PATCHHIST WHERE EXECUTABLE = 'R3

4 ETW000                                                                                                  21  30.055053

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

4 ETW000                                                                                                  21  30.055074

4 ETW000  [     dev trc,00000]         AME = 'SRVDC1127' AND PLATFORM = 'NT' AND DBVENDOR = 'ORA')                                                              

4 ETW000                                                                                                  34  30.055108

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=0, mrk=0,sch=03B394E0, scp=03B42CA0, tab=' ') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  69  30.055177

4 ETW000  [     dev trc,00000]  -->DbSlBegRead(#0: con=0, ssp=7FEE28CC888, dap=7FEE28CC7F0), In=0, Out=0, sch=03B394E0, scp=03B42CA0, tab=' '

4 ETW000                                                                                                  27  30.055204

4 ETW000  [     dev trc,00000]    stmt=SELECT EXECUTABLE,SAPRELEASE,HOSTNAME,PLATFORM,DBVENDOR,TIMESTAMP,PATCHNO,DBSLPATCHN FROM PATCHHIST WHERE EXECUTABLE = 'R3

4 ETW000                                                                                                  27  30.055231

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

4 ETW000                                                                                                  21  30.055252

4 ETW000  [     dev trc,00000]         AME = 'SRVDC1127' AND PLATFORM = 'NT' AND DBVENDOR = 'ORA')                                                              

4 ETW000                                                                                                  21  30.055273

4 ETW000  [     dev trc,00000]  STID: ssp=7FEE28CC888->stmtid_ptr=0000NULL                                15  30.055288

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 0 input variables, col=0                          13  30.055301

4 ETW000  [     dev trc,00000]     DbSlBegRead(#0) -> 0=DBSL_ERR_OK                                       12  30.055313

4 ETW000  [     dev trc,00000]  ^^ cursor_open(cid=0) -> OK                                               11  30.055324

4 ETW000  [     dev trc,00000]  ^^ Ds_prep_open(cid=0)                                                    12  30.055336

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

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

4 ETW000  [     dev trc,00000]  vv Ds_fetch(head_p=000000000214EBF0, cid=0, #out=8, rows=1)               14  30.055373

4 ETW000  [     dev trc,00000]  -->DbSlExeRead(#1: con=0, ss_p=7FEE28CC888, da_p=7FEE28CC7F0)             29  30.055402

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 8 output variables                                13  30.055415

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=40, max=40, dec=0                   14  30.055429

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   12  30.055441

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=44, max=44, dec=0                   17  30.055458

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   15  30.055473

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=6, max=6, dec=0                     13  30.055486

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   23  30.055509

4 ETW000  [     dev trc,00000]   6: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   77  30.055586

4 ETW000  [     dev trc,00000]   7: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   18  30.055604

4 ETW000  [     dev trc,00000]     bind_ind_and_values(OUT,con=0,arr=607,col=8,rc=0,rl=0,flt=0,int=0,sh=0,uc=99)

4 ETW000                                                                                                  22  30.055626

4 ETW000  [     dev trc,00000]  -  oci_define  ( 0: SQLT_CHR , l= 40, con=0, stp=0FE2C108, pv=0FDF4E40, in=0FDF2850,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  24  30.055650

4 ETW000  [     dev trc,00000]  -  oci_define  ( 1: SQLT_CHR , l= 20, con=0, stp=0FE2C108, pv=0FDFAD18, in=0FDF2D0E,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  40  30.055690

4 ETW000  [     dev trc,00000]  -  oci_define  ( 2: SQLT_CHR , l= 44, con=0, stp=0FE2C108, pv=0FDFDC84, in=0FDF31CC,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  35  30.055725

4 ETW000  [     dev trc,00000]  -  oci_define  ( 3: SQLT_CHR , l= 20, con=0, stp=0FE2C108, pv=0FE044D8, in=0FDF368A,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  37  30.055762

4 ETW000  [     dev trc,00000]  -  oci_define  ( 4: SQLT_CHR , l=  6, con=0, stp=0FE2C108, pv=0FE07444, in=0FDF3B48,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  30.055788

4 ETW000  [     dev trc,00000]  -  oci_define  ( 5: SQLT_CHR , l= 28, con=0, stp=0FE2C108, pv=0FE0827E, in=0FDF4006,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  26  30.055814

4 ETW000  [     dev trc,00000]  -  oci_define  ( 6: SQLT_CHR , l= 20, con=0, stp=0FE2C108, pv=0FE0C4E2, in=0FDF44C4,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  24  30.055838

4 ETW000  [     dev trc,00000]  -  oci_define  ( 7: SQLT_CHR , l= 20, con=0, stp=0FE2C108, pv=0FE0F44E, in=0FDF4982,rl=0000NULL,rc=0000NULL)

4 ETW000                                                                                                  25  30.055863

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  29  30.055892

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             17  30.055909

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#15: con=0,stp=0FE2C108,itr=607,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  30.055930

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.055950

4 ETW000  [     dev trc,00000]  OCIStmtExecute() completed, rc=100=OCI_NO_DATA, no further data         2813  30.058763

4 ETW000  [     dev trc,00000]     -> rc=1403=OCI_ERR_NOT_FOUND                                           55  30.058818

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,000s]                               22  30.058840

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=1403, rows processed 0, iters-errors = 607-0) [dur. 0,000s]

4 ETW000                                                                                                  21  30.058861

4 ETW000  [     dev trc,00000]  -->oci_get_errmsg (con=0, rc=1403)                                        14  30.058875

4 ETW000  [     dev trc,00000]     OCIErrorGet() -> SQL error code: 1403                                  64  30.058939

4 ETW000  [     dev trc,00000]  ORA-01403: no data found                                                                                                        

4 ETW000                                                                                                  21  30.058960

4 ETW000  [     dev trc,00000]  ocica() -> SQL code 1403,1403                                             13  30.058973

4 ETW000  [     dev trc,00000]     stmt_fetch() -> orc=1403, 24=DBSL_ERR_NOT_FOUND                        13  30.058986

4 ETW000  [     dev trc,00000]  rc=24=DBSL_ERR_NOT_FOUND, suppl=999, ft=1, cp=0, bulk=0, xcnt=1, da_p->xcnt=0, dbcount=0

4 ETW000                                                                                                  19  30.059005

4 ETW000  [     dev trc,00000]  row_i=0, row_pcnt=0, row_upto=4294967295, row_xcnt=607, row_max=607       15  30.059020

4 ETW000  [     dev trc,00000]     DbSlExeRead(#1,con=0) -> 24=DBSL_ERR_NOT_FOUND, dbcount=0, lob_pw=0

4 ETW000                                                                                                  18  30.059038

4 ETW000  [     dev trc,00000]  head_p=000000000214EBF0: dbsl rc=24=DBSL_ERR_NOT_FOUND -> dbds rc=64=DS_NOTFOUND, dbdsoci.c:1104

4 ETW000                                                                                                  21  30.059059

4 ETW000  [     dev trc,00000]  vv cursor_close(cid=0)                                                    12  30.059071

4 ETW000  [     dev trc,00000]  -->DbSlEndRead(#-1: con=0, ss_p=7FEE28CC888, da_p=7FEE28CC7F0, sch=0000NULL) -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  21  30.059092

4 ETW000  [     dev trc,00000]  ^^ cursor_close()                                                         11  30.059103

4 ETW000  [     dev trc,00000]  head_p->sqlcode=0, ->rec_cnt=0, ->mod_cnt=0                               13  30.059116

4 ETW000  [     dev trc,00000]  ^^ Ds_fetch() -> rc=64=DS_NOTFOUND                                        11  30.059127

4 ETW000  [     dev trc,00000]  } dsql_fetch()=64, rec_cnt=0, mod_cnt=0                                   11  30.059138

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

4 ETW000  [     dev trc,00000]  vv Ds_close(cid=0)                                                        12  30.059160

4 ETW000  [     dev trc,00000]  } dsql_close()=0                                                           9  30.059169

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

4 ETW000  [     dev trc,00000]  vv Ds_close(cid=0)                                                        42  30.059222

4 ETW000  [     dev trc,00000]  } dsql_close()=0                                                          14  30.059236

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

4 ETW000                                                                                                  23  30.059259

4 ETW000  [     dev trc,00000]  -->DbSlModify(DBSL_INSERT, con=0, ssp=141819080, dap=141818298, sch=03B39448), tab='PATCHHIST'

4 ETW000                                                                                                  28  30.059287

4 ETW000  [     dev trc,00000]    stmt=INSERT INTO "PATCHHIST" VALUES(:A0 ,:A1 ,:A2 ,:A3 ,:A4 ,:A5 ,:A6 ,:A7 )                                                  

4 ETW000                                                                                                  30  30.059317

4 ETW000  [     dev trc,00000]  STID: ssp=141819080->stmtid_ptr=141818220->stid=' 3.PATCHHIST                               . 268435456.20110915183634'

4 ETW000                                                                                                  26  30.059343

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=40, max=40, dec=0)             15  30.059358

4 ETW000  [     dev trc,00000]   In   01: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             13  30.059371

4 ETW000  [     dev trc,00000]   In   02: DBSL_DATA_SHORT_CHAR        (len=44, max=44, dec=0)             13  30.059384

4 ETW000  [     dev trc,00000]   In   03: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             13  30.059397

4 ETW000  [     dev trc,00000]   In   04: DBSL_DATA_SHORT_CHAR        (len=6, max=6, dec=0)               13  30.059410

4 ETW000  [     dev trc,00000]   In   05: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             13  30.059423

4 ETW000  [     dev trc,00000]   In   06: DBSL_DATA_NUMC              (len=20, max=20, dec=0)             23  30.059446

4 ETW000  [     dev trc,00000]   In   07: DBSL_DATA_NUMC              (len=20, max=20, dec=0)             15  30.059461

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 8 input variables, col=8                          18  30.059479

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=40, max=40, dec=0                   17  30.059496

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   14  30.059510

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=44, max=44, dec=0                   17  30.059527

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   14  30.059541

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=6, max=6, dec=0                     12  30.059553

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   13  30.059566

4 ETW000  [     dev trc,00000]   6: DBSL_DATA_NUMC              , len=20, max=20, dec=0                   13  30.059579

4 ETW000  [     dev trc,00000]   7: DBSL_DATA_NUMC              , len=20, max=20, dec=0                   12  30.059591

4 ETW000  [     dev trc,00000]  -->exec_modify(): bulk=0, input_cnt=8, lit_cnt=0, fae_cnt=0               15  30.059606

4 ETW000  [     dev trc,00000]  stmt_execute?: row_ _max=1, _xcnt=0, _i=1, _pcnt=0, _upto=4294967295, _total=0

4 ETW000                                                                                                  20  30.059626

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  29  30.059655

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             17  30.059672

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#16: con=0,stp=0FE289F8,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  30.059693

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.059713

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                              294  30.060007

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,000s]

4 ETW000                                                                                                  27  30.060034

4 ETW000  [     dev trc,00000]  IN array size xcnt=1, IN->row_total=1                                     18  30.060052

4 ETW000  [     dev trc,00000]  orpc=1, DbCa.dbcount=1, DbSt.counter.dbrecords=1                          15  30.060067

4 ETW000  [     dev trc,00000]     stmt_execute() -> orc=0 (orc1=0), rc=0=DBSL_ERR_OK                     13  30.060080

4 ETW000  [     dev trc,00000]  <--exec_modify() -> 0=DBSL_ERR_OK, supply=999                             13  30.060093

4 ETW000  [     dev trc,00000]     DbSlModify(con=0) -> 0=DBSL_ERR_OK                                     13  30.060106

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

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

4 ETW000                                                                                                  16  30.060134

4 ETW000  [     dev trc,00000]  -->DbSlModify(DBSL_DELETE, con=0, ssp=141819080, dap=141818298), sch=0000NULL

4 ETW000                                                                                                  24  30.060158

4 ETW000  [     dev trc,00000]  Wrong statement ID or statement not prepared.                             13  30.060171

4 ETW000  [     dev trc,00000]     DbSlModify(con=0) -> 13=DBSL_ERR_STMTID                                12  30.060183

4 ETW000  [     dev trc,00000]  -->DbSlPrepare(DBSL_DELETE, con=0, ss_p=141819080, da_p=141818298), stp=141817FF8, stid=141818220

4 ETW000                                                                                                  23  30.060206

4 ETW000  [     dev trc,00000]  AppInf(210)->1; mod='DBSL: con=0, no ABAP',act='0',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  71  30.060277

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected                              16  30.060293

4 ETW000  [     dev trc,00000]  -->oci_prepare_stmt(con=0,len=145,stp=0FE301C8); AI=DBSL: con=0, no ABAP.0.DBSL: con=0, no client_id.DBSL: con=0, no client_info

4 ETW000                                                                                                  23  30.060316

4 ETW000  [     dev trc,00000]         DELETE FROM "PATCHHIST" WHERE "EXECUTABLE"=:A0 AND "SAPRELEASE"=:A1 AND "HOSTNAME"=:A2 AND "PLATFORM"=:A3 AND "DBVENDOR"=:

4 ETW000                                                                                                  21  30.060337

4 ETW000  [     dev trc,00000]         A4 AND "TIMESTAMP"=:A5                                                                                                   

4 ETW000                                                                                                  43  30.060380

4 ETW000  [     dev trc,00000]     DbSlPrepare(lit=0, lob=0, fae=0, col=6, mrk=6,sch=03B39578, scp=03B42DC0, tab='PATCHHIST') -> 0=DBSL_ERR_OK

4 ETW000                                                                                                  51  30.060431

4 ETW000  [     dev trc,00000]  -->DbSlModify(DBSL_DELETE, con=0, ssp=141819080, dap=141818298, sch=03B39578), tab='PATCHHIST'

4 ETW000                                                                                                  24  30.060455

4 ETW000  [     dev trc,00000]    stmt=DELETE FROM "PATCHHIST" WHERE "EXECUTABLE"=:A0 AND "SAPRELEASE"=:A1 AND "HOSTNAME"=:A2 AND "PLATFORM"=:A3 AND "DBVENDOR"=:

4 ETW000                                                                                                  25  30.060480

4 ETW000  [     dev trc,00000]         A4 AND "TIMESTAMP"=:A5                                                                                                   

4 ETW000                                                                                                  21  30.060501

4 ETW000  [     dev trc,00000]  STID: ssp=141819080->stmtid_ptr=141818220->stid=' 3.PATCHHIST                               . 805306369.20110915183634'

4 ETW000                                                                                                  35  30.060536

4 ETW000  [     dev trc,00000]   In   00: DBSL_DATA_SHORT_CHAR        (len=40, max=40, dec=0)             18  30.060554

4 ETW000  [     dev trc,00000]   In   01: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             13  30.060567

4 ETW000  [     dev trc,00000]   In   02: DBSL_DATA_SHORT_CHAR        (len=44, max=44, dec=0)             13  30.060580

4 ETW000  [     dev trc,00000]   In   03: DBSL_DATA_SHORT_CHAR        (len=20, max=20, dec=0)             13  30.060593

4 ETW000  [     dev trc,00000]   In   04: DBSL_DATA_SHORT_CHAR        (len=6, max=6, dec=0)               13  30.060606

4 ETW000  [     dev trc,00000]   In   05: DBSL_DATA_SHORT_CHAR        (len=28, max=28, dec=0)             12  30.060618

4 ETW000  [     dev trc,00000]  bind_type_and_length(): 6 input variables, col=6                          13  30.060631

4 ETW000  [     dev trc,00000]   0: DBSL_DATA_SHORT_CHAR        , len=40, max=40, dec=0                   13  30.060644

4 ETW000  [     dev trc,00000]   1: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   13  30.060657

4 ETW000  [     dev trc,00000]   2: DBSL_DATA_SHORT_CHAR        , len=44, max=44, dec=0                   12  30.060669

4 ETW000  [     dev trc,00000]   3: DBSL_DATA_SHORT_CHAR        , len=20, max=20, dec=0                   13  30.060682

4 ETW000  [     dev trc,00000]   4: DBSL_DATA_SHORT_CHAR        , len=6, max=6, dec=0                     13  30.060695

4 ETW000  [     dev trc,00000]   5: DBSL_DATA_SHORT_CHAR        , len=28, max=28, dec=0                   12  30.060707

4 ETW000  [     dev trc,00000]     bind_ind_and_values(IN,con=0,arr=714,col=6,rc=0,rl=6,flt=0,int=0,sh=0,uc=79)

4 ETW000                                                                                                  21  30.060728

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 0: SQLT_AFC , l= 40, con=0, stp=0FE301C8, pv=0FDF6B40, in=0FDF2850,rl=0FDF49C8,rc=0000NULL)

4 ETW000                                                                                                  25  30.060753

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 1: SQLT_AFC , l= 20, con=0, stp=0FE301C8, pv=0FDFDAD0, in=0FDF2DE4,rl=0FDF4F5C,rc=0000NULL)

4 ETW000                                                                                                  61  30.060814

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 2: SQLT_AFC , l= 44, con=0, stp=0FE301C8, pv=0FE01298, in=0FDF3378,rl=0FDF54F0,rc=0000NULL)

4 ETW000                                                                                                  35  30.060849

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 3: SQLT_AFC , l= 20, con=0, stp=0FE301C8, pv=0FE08D50, in=0FDF390C,rl=0FDF5A84,rc=0000NULL)

4 ETW000                                                                                                  28  30.060877

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 4: SQLT_AFC , l=  6, con=0, stp=0FE301C8, pv=0FE0C518, in=0FDF3EA0,rl=0FDF6018,rc=0000NULL)

4 ETW000                                                                                                  28  30.060905

4 ETW000  [     dev trc,00000]  -  oci_bind    ( 5: SQLT_AFC , l= 28, con=0, stp=0FE301C8, pv=0FE0D5D4, in=0FDF4434,rl=0FDF65AC,rc=0000NULL)

4 ETW000                                                                                                  29  30.060934

4 ETW000  [     dev trc,00000]  -->exec_modify(): bulk=0, input_cnt=6, lit_cnt=0, fae_cnt=0               18  30.060952

4 ETW000  [     dev trc,00000]  stmt_execute?: row_ _max=1, _xcnt=0, _i=1, _pcnt=0, _upto=4294967295, _total=0

4 ETW000                                                                                                  26  30.060978

4 ETW000  [     dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''

4 ETW000                                                                                                  29  30.061007

4 ETW000  [     dev trc,00000]     time 2014-04-04 22:32:25, con=0 connected, new transaction             19  30.061026

4 ETW000  [     dev trc,00000]  -->oci_execute_stmt(#17: con=0,stp=0FE301C8,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'

4 ETW000                                                                                                  21  30.061047

4 ETW000  [     dev trc,00000]  before OCIStmtExecute(svchp=03B79038, errhp=03B78108): [dur. 0,000s]

4 ETW000                                                                                                  20  30.061067

4 ETW000  [     dev trc,00000]  after  OCIStmtExecute(rpc=1): [dur. 0,000s]                              338  30.061405

4 ETW000  [     dev trc,00000]  <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [dur. 0,000s]

4 ETW000                                                                                                  27  30.061432

4 ETW000  [     dev trc,00000]  IN array size xcnt=1, IN->row_total=1                                     14  30.061446

4 ETW000  [     dev trc,00000]  orpc=1, DbCa.dbcount=1, DbSt.counter.dbrecords=1                          12  30.061458

4 ETW000  [     dev trc,00000]     stmt_execute() -> orc=0 (orc1=0), rc=0=DBSL_ERR_OK                     13  30.061471

4 ETW000  [     dev trc,00000]  <--exec_modify() -> 0=DBSL_ERR_OK, supply=999                             13  30.061484

4 ETW000  [     dev trc,00000]     DbSlModify(con=0) -> 0=DBSL_ERR_OK                                     13  30.061497

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

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)           52  30.061560

4 ETW000  [     dev trc,00000]  dbsync[db_syflush]: to_be_committed = 1                                   38  30.061598

4 ETW000  [     dev trc,00000]     all LL freed, no memory used for LL storage                            26  30.061624

4 ETW000  [     dev trc,00000]  -->DbSlCommit(con=0,FORCE,DROP), 17 stmts executed, 0/0 open cur          54  30.061678

4 ETW000  [     dev trc,00000]     all LL freed, no memory used for LL storage                            15  30.061693

4 ETW000  [     dev trc,00000]  -->oci_commit(con=0), time: 2014-04-04 22:32:25                           18  30.061711

4 ETW000  [     dev trc,00000]     OCITransCommit(svchp=03B79038, errhp=03B78108, OCI_DEFAULT)            23  30.061734

4 ETW000  [     dev trc,00000]  <--oci_commit(), orc=0, time: 2014-04-04 22:32:25                       6175  30.067909

4 ETW000  [     dev trc,00000]  -->DbSlControl(con=0,cmd=12=DBSL_CMD_VENDOR_ID_GET)                       65  30.067974

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        : 04.04.2014 - 22:32:25

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:                                      226  0.000226

4 ETW000  [     dev trc,00000]  Disconnecting from connection 0 ...                                       21  0.000247

4 ETW000  [     dev trc,00000]  Closing user session (con=0, svc=03B79038, usr=03B887C0)                4130  0.004377

4 ETW000  [     dev trc,00000]  Server handle (con=0,svchp=03B79038,srvhp=03B798C0) detached           15084  0.019461

4 ETW000  [     dev trc,00000]  Disconnected (con=0) from ORACLE.                                         36  0.019497

4 ETW000  [     dev trc,00000]  Disconnected from connection 0                                            15  0.019512

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

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

4 ETW000 Disconnected from database.

4 ETW000 End of Transport (0000).

4 ETW000 date&time: 04.04.2014 - 22:32:25

Former Member
0 Kudos

HI

Now please attach this log files dev_w0, dev_ms, dev_disp and dev_rd.

Former Member
0 Kudos

Hi Michael,

On looking at the dev_disp file I see the below section repeating(looping) several times:

*********** DP LOOP ***********

NiSelISelectInt: 1 handles selected (0 buffered)

NiDgHdlRecvFrom: hdl 1 received 14 bytes from 127.0.0.1:50818

DpAppcBlksLow: appc_blks_in_queue/limit/max = 0/500/1000

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

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

DpRqIGetFromQ: read elem from queue NOWP, elems=0

-IN-- sender_id WORK_PROCESS      tid  1     wp_ca_blk   165     wp_id 1

-IN-- action    SEND_TO_DP        uid  2     appc_ca_blk -1      type  NOWP

-IN-- new_stat  WP_WAIT           mode 0     len         8       rq_id 45

DpListInsert: insert elem 1 into wpadm_free_dia_list (at begin)

DpListInsert: 1 elems in wpadm_free_dia_list

DpIWpSetNewStat: switched W1 from WP_RUN          to WP_WAIT       

DpIWpSetNewStat: info for DIA: new/wait/run/hold/restr/killed = 0/1/1/0/0/0 wps

DpRqNoWpHandle: new state of W1 is WP_WAIT       

DpHdlWpWait: W1 has req_info 80002000, thdp_adm_req_info 0

REL WP ca_blk 165

DpRqServiceQueue: 3 request(s) in DIA queue ===>>

DpWpFind: W1 is still in async phase

DpWpFind: found W1 in async phase

DpRqServiceQueue: W1 is free

DpRqIGetFromQ: read elem from queue DIA, elems=2

-IN-- sender_id DISPATCHER        tid  -1    wp_ca_blk   -1      wp_id -1

-IN-- action    SEND_TO_WP        uid  -1    appc_ca_blk -1      type  DIA 

-IN-- new_stat  NO_CHANGE         mode 255   len         0       rq_id 36

-IN-- req_info  THDP_ADM,SET_SYSTEM_USER

-IN-- thdp_adm_req_info  CALL_DELAYED 

DpCheckReqHooks: reset dp_calldelayed_in_queue

DpRqNormalHandle: set SYSTEM_USER T1 U2 M0

DpRqNormalHandle: DpRqCheck o.k.

DpContextIsActive: checking whether T1/U2/M0/V-1 is active

DpListRemove: remove elem 1 from wpadm_free_dia_list

DpListRemove: 0 elems in wpadm_free_dia_list

DpIWpSetNewStat: switched W1 from WP_WAIT         to WP_RUN        

DpIWpSetNewStat: info for DIA: new/wait/run/hold/restr/killed = 0/0/2/0/0/0 wps

DpRqNormalHandle: new state of W1 is WP_RUN

DpRqNormalHandle: new state of T1/M0 = 0x42

DpRqNormalHandle: act_wp of T1/M0 = W1

DpRqNormalHandle: give rq_id 36 to W1 ...

DpRqServiceQueue: all DIA wp's in use now ...

DpRqServiceQueue: 1 request(s) in UPD queue ===>>

DpRqServiceQueue: all UPD wp's in use now ...

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DpRqServiceQueue: 0 request(s) in UP2 queue ===>>

WP blk   606    WORK_PROCESS        5976

APPC blk 605    WORK_PROCESS        5976

DISPATCHER has 3 WP and 0 APPC blks

Same is the case with the dev_rd file:

********** GWRD LOOP **********

I'm in GwReceive

Fri Apr 04 23:29:39 2014

NiSelISelectInt: 0 handles selected (0 buffered)

********** GWRD LOOP **********

I'm in GwReceive

Fri Apr 04 23:29:59 2014

NiSelISelectInt: 0 handles selected (0 buffered)

GwGetCounter: 0 connections (0 %, max=500)

GwGetCounter: 2 clients (0 %, max=300)

GwGetCounter: 1 rem_gateways (1 %, max=100)

GwGetCounter: 0 comm_adm_entries (0 %, max=500)

GwGetCounter: 0 overflow_size (0 %, max=10000000)

GwCheckCounter: check counter

GwICheckConns: check connections (900 secs)

GwICheckWaitingRegPrgms: keepalive to waiting registered programs (300 secs)

GwIFreeGwConn: check inactive GW-GW connections (1800 secs)

GwIFreeGwConn: check inactive GW(CLIENT)-GW connections (1800 secs)

GwICheckAccept: check connections in state DO_ACCEPT (60 secs)

GwICheckFrag: check connections with fragmentation (120 secs)

GwICheckRegServer: check registered server in error state (60 secs)

GwIFreeNormalClient: check NORMAL_CLIENT connection (600 secs)

GwICheckRoutes: check routes (120 secs)

GwICheckConnPending: check pending connections (60 secs)

GwICheckConnPending: check pending client hdl (#0) (60 secs)

GwICheckHdlPending: check pending hdl(#0) (60 secs)

********** GWRD LOOP **********

I'm in GwReceive

Fri Apr 04 23:30:19 2014

NiSelISelectInt: 0 handles selected (0 buffered)

********** GWRD LOOP **********

I'm in GwReceive

Fri Apr 04 23:30:39 2014

NiSelISelectInt: 0 handles selected (0 buffered)

GwGetCounter: 0 connections (0 %, max=500)

GwGetCounter: 2 clients (0 %, max=300)

GwGetCounter: 1 rem_gateways (1 %, max=100)

GwGetCounter: 0 comm_adm_entries (0 %, max=500)

GwGetCounter: 0 overflow_size (0 %, max=10000000)

GwICheckConns: check connections (900 secs)

GwICheckWaitingRegPrgms: keepalive to waiting registered programs (300 secs)

GwIFreeGwConn: check inactive GW-GW connections (1800 secs)

GwIFreeGwConn: check inactive GW(CLIENT)-GW connections (1800 secs)

GwICheckAccept: check connections in state DO_ACCEPT (60 secs)

GwICheckFrag: check connections with fragmentation (120 secs)

GwICheckRegServer: check registered server in error state (60 secs)

GwIFreeNormalClient: check NORMAL_CLIENT connection (600 secs)

GwICheckRoutes: check routes (120 secs)

GwICheckConnPending: check pending connections (60 secs)

GwICheckConnPending: check pending client hdl (#0) (60 secs)

GwICheckHdlPending: check pending hdl(#0) (60 secs)

********** GWRD LOOP **********

I'm in GwReceive

Fri Apr 04 23:30:59 2014

NiSelISelectInt: 0 handles selected (0 buffered)

********** GWRD LOOP **********

I'm in GwReceive

I had read a different post somewhere and added the below line in my hosts file:

Could this be causing any issues like above?

Also, I ran the r3trans -d and found this one statement which might be the cause, but don't know what to make of it:

OCIErrorGet() -> SQL error code: 1403                              88  5.430257
4 ETW000  [ dev trc,00000]  ORA-01403: no data found 

4 ETW000  [ dev trc,00000]  AppInf(5)->1; mod='',act='',usr='DBSL: con=0, no client_id',ta='',mp=''
4 ETW000                                                                                              30  5.429826
4 ETW000  [ dev trc,00000] time 2014-04-04 23:42:38, con=0 connected, new transaction         18  5.429844
4 ETW000  [ dev trc,00000]  -->oci_execute_stmt(#11: con=0,stp=0FE93228,itr=1,off=0,bat=0); AI='..DBSL: con=0, no client_id.'
4 ETW000                                                                                              21  5.429865
4 ETW000  [ dev trc,00000]  before OCIStmtExecute(svchp=03BA9038, errhp=03BA8108): [dur. 0,000s]
4 ETW000                                                                                              21  5.429886
4 ETW000  [ dev trc,00000]  OCIStmtExecute() completed, rc=100=OCI_NO_DATA, no further data      216  5.430102
4 ETW000  [ dev trc,00000] -> rc=1403=OCI_ERR_NOT_FOUND                                       17  5.430119
4 ETW000  [ dev trc,00000]  after  OCIStmtExecute(rpc=0): [dur. 0,000s]                           17  5.430136
4 ETW000  [ dev trc,00000]  <--oci_execute_stmt(rc=1403, rows processed 0, iters-errors = 1-0) [dur. 0,000s]
4 ETW000                                                                                              19  5.430155
4 ETW000  [ dev trc,00000]  -->oci_get_errmsg (con=0, rc=1403)                                    14  5.430169
4 ETW000  [ dev trc,00000] OCIErrorGet() -> SQL error code: 1403                              88  5.430257
4 ETW000  [ dev trc,00000]  ORA-01403: no data found                                                                                                   
4 ETW000                                                                                              24  5.430281
4 ETW000  [ dev trc,00000]  ocica() -> SQL code 1403,1403                                         14  5.430295
4 ETW000  [ dev trc,00000] stmt_fetch() -> orc=1403, 24=DBSL_ERR_NOT_FOUND                    13  5.430308
4 ETW000  [ dev trc,00000]  rc=24=DBSL_ERR_NOT_FOUND, suppl=999, ft=1, cp=0, bulk=0, xcnt=1, da_p->xcnt=0, dbcount=0
4 ETW000                                                                                              20  5.430328
4 ETW000  [ dev trc,00000]  row_i=0, row_pcnt=0, row_upto=4294967295, row_xcnt=1, row_max=1       14  5.430342
4 ETW000  [ dev trc,00000] DbSlRead() --> 24=DBSL_ERR_NOT_FOUND                               13  5.430355
4 ETW000  [dbcrtab ,00000]  ***LOG BZ9=>table MTSETTINGS does not exist in nametab                59  5.430414
4 ETW000  [ dev trc,00000]  } db_rtab( fcode = 'RT_READ_ONLY', retcode = 32 )                     14  5.430428

4 ETW000 Connected to DBMS = ORACLE --- dbs_ora_tnsname = 'MBW' --- SYSTEM = 'SAP'.

4 ETW000  [ dev trc,00000]  -->DbSlControl(con=0,cmd=13=DBSL_CMD_VENDOR_NAME_GET)                103  5.430531
4 ETW000  [ dev trc,00000]  { db_rtab( fcode = 'RT_INSERT', tname = 'PATCHHIST' ) {saprel.c:1491}
4 ETW000                                                                                             195  5.430726

Look forward to hearing your thoughts.

Regards,

Manish

Former Member
0 Kudos

hi,

Can you check your /etc/hosts file. Do you have this entry?

127.0.0.1       localhost

Let me know

Former Member
0 Kudos

Yes. that entry is present.

Former Member
0 Kudos

hi

the best thing you can do is contact sap support because the logs you posted are not clear on the error. i think the problem is at operating system level.

regards

Michael

Former Member
0 Kudos

Hello

could you please paste dev_w0, dev_ms and dev_rd files from work directory. This will help us to assist you.

regards

Michael

Former Member
0 Kudos

Hi Michael,

Here is the dev_rd:

********** GWRD LOOP **********

I'm in GwReceive

Fri Apr 04 13:41:17 2014

NiSelISelectInt: 0 handles selected (0 buffered)

********** GWRD LOOP **********

I'm in GwReceive

Fri Apr 04 13:41:37 2014

NiSelISelectInt: 0 handles selected (0 buffered)

GwGetCounter: 0 connections (0 %, max=500)

GwGetCounter: 2 clients (0 %, max=300)

GwGetCounter: 1 rem_gateways (1 %, max=100)

GwGetCounter: 0 comm_adm_entries (0 %, max=500)

GwGetCounter: 0 overflow_size (0 %, max=10000000)

GwICheckConns: check connections (900 secs)

GwICheckWaitingRegPrgms: keepalive to waiting registered programs (300 secs)

GwIFreeGwConn: check inactive GW-GW connections (1800 secs)

GwIFreeGwConn: check inactive GW(CLIENT)-GW connections (1800 secs)

GwICheckAccept: check connections in state DO_ACCEPT (60 secs)

GwICheckFrag: check connections with fragmentation (120 secs)

GwICheckRegServer: check registered server in error state (60 secs)

GwIFreeNormalClient: check NORMAL_CLIENT connection (600 secs)

GwICheckRoutes: check routes (120 secs)

GwICheckConnPending: check pending connections (60 secs)

GwICheckConnPending: check pending client hdl (#0) (60 secs)

GwICheckHdlPending: check pending hdl(#0) (60 secs)

********** GWRD LOOP **********

I'm in GwReceive

There is no dev_ms.

And these are the last few lines from dev_w0:

M  resptime of act sub step: 3830 ms

M  resptime of all sub step: 3830 ms (old/new=0/3830)

M  SosIGetAnchor: get anchor for 12

M  SosICreateNewAnchorArray: created new abap anchor array for T0/M0 with 18 slots at 0x000007DFE554D9B0

M  PfGetRollAnchor: get roll memory and initialize it

M  PfAllocRoll: allocated EM_GLOBAL (000007DFE554DA80/16)

M  PfAllocRoll: allocated EM_GLOBAL (000007DFE554DAD0/1168)

M  PfStatWrite: set record dialog step to 1

M  set cpu stamp 3140625

M  new cpu time = 0 us

M  PfRecClose: close record (0)

M  PfCopyAllRec: write 1 PF_NORMAL_STAT recs (len=904)

M  PfCopyAllRec: hook >hook for normal statistical records< o.k.

M  PfStatIndUpd: New Index-Record, starttime= 1396608180

M  PfCopyAllRec: write 1 PF_DBCON_STAT recs (len=224)

M  PfCopyAllRec: hook >hook for normal statistical records< o.k.

M  PfCopyAllRec: copied 2 recs

M  PfStatWrite: copy rec (388 bytes) to shared buf

M  PfStatIndMergeInSharedInd: updated shared index:

M  from 04.04.2014 10:34:56(1396607696) to 04.04.2014 10:43:00(1396608180)

M  PfStatWrite: 15402 bytes (78 recs) in shared buf

M  PfStatIndInit: Initializing Index-Record

M  PfStatDisconnect: disconnect statistics

M  ThDealComm: T0/U1/M0 has no cpic conns

M  clean up all internal modes of T0/U1/M0

M  call ab_endmode (END_MODE_CLEAR_ALL)

M  ThGetEnqInfo: no enq info found

M  ThRmLocQueue: no async request found

M  ThPageFree: free (1,0,0, mode = 3)

M  ThRollFree: free (1,0,0, mode = 3)

M  ThRmFree:  cleanup resources for T0 M0

M  RmCleanUpResources3: hdr/tbl/ext_tbl/free/keep = 000000000FBBD600/0000000000000000/0/1/0

M  RmCleanUpResources3: no resources registered

M  ThRmFree: reset eg state of T0 M0

M  ThRmFree cleanup resources for T0

M  RmCleanUpResources3: hdr/tbl/ext_tbl/free/keep = 000000000FBC3B18/0000000000000000/1/1/0

M  RmCleanUpResources3: no resources registered

M  ThRmFree: reset eg state of T0

M  ThIEmFree: delete hyper context (hyperHdl=000007DFE5551260)

M  ThIEmFree: call EmContextDelete (em_hdl=0)

M  ThResFree: save spa pointer 000007E00001C1A0 and install dummy spa

M  ThISend: (T0/M0, user=SAPSYS      , type=0x41, info=0x0, mode_deleted=1)

M    type=TASK_SWITCH,DISP

M  ThCheckAbapDebugging: no valid user or ABAP not no stack, skip check

M  ThJCheckJavaDebugging: return 0

M  ThrtGetStrategy: abap strategy ROLL / O.K.

M  ThRqOutCheck: check output for T0/M0

M  ThRqOutCheck: o.k.

M  ThISend: allowed rq_type of T0/M0 = TH_ALL_RQ

M  ThNewWpStat (type=0x41, task_switch=1, inline_hold=0, hand_shake=0, debug=0, ..)

M  ThNewWpStat: new MODE_REC = MODE_REC_SYS

M  ThNewWpStat: new state of T0/M0 = MODE_WAIT,MODE_REC_SYS

M  ThNewWpStat: new wp stat: WP_WAIT

M  ThMkReq: detach wp_ca_blk 110

M  ThDtWpBlk: detach WP ca_blk 110

M  ThISend: complete stat record

M  ThCallHooks: call hook >TrThHookFunc< for event COMPLETE_STAT_RECORD

M  TrThHookFunc: called for complete stat rec

M  ThCallHooks: hook >TrThHookFunc< o.k.

M  ThISend: stat_state = TH_STAT_WRITE

M  ThISend: th_wp_waits_for_rq = TH_WP_WAITS_FOR_RQ

M  make DISP owner of wp_ca_blk 110

M  DpRqIPutIntoQ: put elem in queue NOWP, elems=1

M  DpRqPutIntoQueue: put request into queue (reqtype 0, prio LOW, rq_id 160)

M  -OUT- sender_id WORK_PROCESS      tid  0     wp_ca_blk   110     wp_id 0

M  -OUT- action    SEND_TO_DP        uid  1     appc_ca_blk -1      type  NOWP

M  -OUT- new_stat  WP_WAIT           mode 0     len         8       rq_id 160

M  ThCheckBlks: th_step = TH_WORK_NOSYNC

M  ThCheckBlks: release appc_ca_blk 110

M  REL APPC ca_blk 110

M  ThDtAppcBlk: detach APPC ca_blk 110

M  PfClearAllRec: clear statistic area

M  PfStatDisconnect: disconnect statistics

M  ThISend: set inPrivMode = 0 for T0/M0

M  ThResetActWp: reset act_wp for T0/M0

M  ThSysUsrCheck (1,1): check system user T0/U1/M0

M  ThIEmFree: em state of T0/M0 (em_hdl=-1) is DP_EM_NO_CONTEXT

M  ThSysUsrCheck: reset T0

M  DpTmSlotReset: reset slot T0

M  ThCallHooks: call hook >TrThHookFunc< for event BEFORE_TASK_SWITCH

M  TrThHookFunc: called for task switch

M  ThCallHooks: hook >TrThHookFunc< o.k.

M  ThCallHooks: call hook >ThPCleanZombiesCallback< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >ThPCleanZombiesCallback< o.k.

M  ThCallHooks: call hook >ImcResetPtr< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >ImcResetPtr< o.k.

M  ThCallHooks: call hook >ThIDbgCallbackBeforeTaskSwitch< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >ThIDbgCallbackBeforeTaskSwitch< o.k.

M  ThCallHooks: call hook >HttpResetPtr< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >HttpResetPtr< o.k.

M  saved task type/act rqtype ZTTADIA/1 of T0/M0

M  ThISend: jump back with rc TH_WAIT_FOR_REQUEST

M  ********* W0 LOOP (DIA) *********

M  TskhLoop: th_wp_waits_for_rq = TH_NO_WAIT_INFO

M  ThInvalidateActFields: invalidate act fields from T0/M0/U1/no VM

M  ThReceive: th_step = TH_WAIT_FOR_REQ

M  ThRqWaitFor: wait for next request (new time slice)

M  ThAlarm: reset alarm (600 secs left)

M  ThRqWaitFor: wait for request

Regards,

Manish

Reagan
Advisor
Advisor
0 Kudos

*** ERROR => CONNECT failed with sql error '3113'

[dbsloci.c    14533]

***LOG BY2=> sql error 3113   performing CON [dblink       540]

***LOG BY0=> ORA-03113: end-of-file on communication channel [dblink       540]

Is the database up and running ?

Check the status of the database and start the database if it is down.

Check alert log for errors.

Regards

RB

Former Member
0 Kudos

Hi Reagan,

The DB is indeed up and running. I restarted it just to be sure and now I get this:

C  Connecting as SAPSR3/<pwd>@MBW on connection 0 (nls 0) ... (dbsl 720 081211, UNICODE[2])

C  Attaching to database server MBW (con=0, svchp=14B5F818, srvhp=14B60100)

C Sat Mar 29 23:19:17 2014

C  Starting user session: OCISessionBegin(con=0, usr='SAPSR3', svc=14B5F818, srv=14B60100, usr=14B6F390)

C     OCISessionBegin(OCI_DEFAULT) failed with SQL error 1017:

C     ORA-01017: invalid username/password; logon denied                                                                       

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

[dbsloci.c    14533]

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

B  ***LOG BY0=> ORA-01017: invalid username/password; logon denied [dbsh         1246]

B  ***LOG BY2=> sql error 1017   performing CON [dblink       540]

B  ***LOG BY0=> ORA-01017: invalid username/password; logon denied [dblink       540]

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

M  in_ThErrHandle: 1

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

As mentioned in the original post, I would like to know where/what the original password is that is being used.

Thanks.

Manish

former_member188883
Active Contributor
0 Kudos

Hi Manish,

It looks to be kernel issue. Refer to solution in the thread

Hop this helps.

Regards,

Deepak Kori

Former Member
0 Kudos

Thanks for the reply Deepak.

Small question, as I have not done kernel upgrades during installation before, can you guide or point me to a instruction set on how I can use the new kernel? I have stopped the install now and downloaded the new 7.20 EXT kernel. But am not 100% sure on what I should be doing.

Thanks in advance for your help.

Regards,

Manish

former_member188883
Active Contributor
0 Kudos

Hi Manish,

Please extract the new kernel to a different folder

Take backup of existing kernel directory usr/sap/<SID>/SYS/exe/run

Copy the extracted kernel into kernel directory and select replace all.

Hope this helps.

Regards,

Deepak Kori

Reagan
Advisor
Advisor
0 Kudos

Hello Manish

Have you configured SSFS ?

If not setup SSFS and see if that helps.

Regards

RB

Former Member
0 Kudos

Hi Deepak,

Thank you so much for your help. I updated the kernel and then ran the

brconnect -u system/<password> -f chpass -o SAPSR3 -p <password>

command with the password that I had given at the time of installation and got the below.

This was very helpful, because now the password has been setup at the right location:

BR0280I BRCONNECT time stamp: 2014-03-30 21.35.43

BR1525I Setting password for database user SAPSR3 in secure storage \\srvdc1127\

sapmnt\MBW\SYS\global\security\rsecssfs\data\SSFS_MBW.DAT ...

BR0280I BRCONNECT time stamp: 2014-03-30 21.35.43

BR0256I Enter 'c[ont]' to continue, 's[top]' to cancel BRCONNECT:

c

BR0280I BRCONNECT time stamp: 2014-03-30 21.35.58

BR0257I Your reply: 'c'

BR0259I Program execution will be continued...

BR0280I BRCONNECT time stamp: 2014-03-30 21.35.59

BR1526I Password for database user SAPSR3 set successfully in secure storage \\s

rvdc1127\sapmnt\MBW\SYS\global\security\rsecssfs\data\SSFS_MBW.DAT

BR0280I BRCONNECT time stamp: 2014-03-30 21.35.59

BR0802I BRCONNECT completed successfully

After this I restarted the installation from where it was stuck and this time it went past the Create ICM userstore phase. Finally!!

BUT, it waits at the Start Instance phase and then just gives up after about 10 minutes. Any idea how this can be overcome? Which log files to check for issues?

Thanks again for all your help.

Regards,

Manish

Reagan
Advisor
Advisor
0 Kudos

Check whether the central services are running.

You need to check the work process trace files.

RB

Former Member
0 Kudos

Hi Reagan,

Thanks replying so quickly.

When I try to start SAP using CCMS it stays Yellow. Looking at the trace files can't see errors. Am a bit lost really.

Looking at a few other similar issues I have also added a line in the hosts file:

127.0.0.1    localhost.<servername.domainname.domain.net>    localhost

And then retryed to finish the installation. The Shutdown proceeds fine and then the restart begins, but again, it comes back to the same thing.

Any other ideas?

Manish

Reagan
Advisor
Advisor
0 Kudos

Manish,

Provide the dev_ms, dev_disp and dev_w0 trace files from the work directory.

Regards

RB

Former Member
0 Kudos

Hi Reagan,

The file dev_w0 is 76 kb and dev_disp is 27583 kb. Is there a particular section that I can copy and paste, that would be of use?

Manish

Reagan
Advisor
Advisor
0 Kudos

I can only say which section is relevant only if I see the trace file.

If possible attach the trace files in the replies.

Regards

RB

Former Member
0 Kudos

This is what I think might help.

Here is a small snippet of the dev_disp, last few lines:

*********** DP LOOP ***********

NiSelISelectInt: 1 handles selected (0 buffered)

NiDgHdlRecvFrom: hdl 1 received 14 bytes from 127.0.0.1:50556

DpAppcBlksLow: appc_blks_in_queue/limit/max = 0/500/1000

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

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

DpWpFind: found W5

DpRqServiceQueue: W5 is free

DpRqIGetFromQ: read elem from queue DIA, elems=0

-IN-- sender_id DISPATCHER        tid  -1    wp_ca_blk   -1      wp_id -1

-IN-- action    SEND_TO_WP        uid  -1    appc_ca_blk -1      type  DIA 

-IN-- new_stat  NO_CHANGE         mode 255   len         0       rq_id 10891

-IN-- req_info  THDP_ADM,SET_SYSTEM_USER

-IN-- thdp_adm_req_info  AUTOCCMS

DpCheckReqHooks: reset dp_autoccms_in_queue

DpRqNormalHandle: set SYSTEM_USER T5 U6 M0

DpRqNormalHandle: DpRqCheck o.k.

DpContextIsActive: checking whether T5/U6/M0/V-1 is active

DpListRemove: remove elem 5 from wpadm_free_dia_list

DpListRemove: 9 elems in wpadm_free_dia_list

DpIWpSetNewStat: switched W5 from WP_WAIT         to WP_RUN        

DpIWpSetNewStat: info for DIA: new/wait/run/hold/restr/killed = 0/9/1/0/0/0 wps

DpRqNormalHandle: new state of W5 is WP_RUN

DpRqNormalHandle: new state of T5/M0 = 0x42

DpRqNormalHandle: act_wp of T5/M0 = W5

DpRqNormalHandle: give rq_id 10891 to W5 ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 1 request(s) in SPO queue ===>>

DpWpFind: found W14

DpRqServiceQueue: W14 is free

DpRqIGetFromQ: read elem from queue SPO, elems=0

-IN-- sender_id DISPATCHER        tid  -1    wp_ca_blk   -1      wp_id -1

-IN-- action    SEND_TO_WP        uid  -1    appc_ca_blk -1      type  SPO 

-IN-- new_stat  NO_CHANGE         mode 255   len         0       rq_id 10892

-IN-- req_info  SET_SYSTEM_USER,DP_SPOOL_ALRM

DpCheckReqHooks: reset dp_spo_in_queue

DpRqNormalHandle: set SYSTEM_USER T14 U15 M0

DpRqNormalHandle: DpRqCheck o.k.

DpContextIsActive: checking whether T14/U15/M0/V-1 is active

DpListRemove: remove elem 14 from wpadm_free_spo_list

DpListRemove: 0 elems in wpadm_free_spo_list

DpIWpSetNewStat: switched W14 from WP_WAIT         to WP_RUN        

DpIWpSetNewStat: info for SPO: new/wait/run/hold/restr/killed = 0/0/1/0/0/0 wps

DpRqNormalHandle: new state of W14 is WP_RUN

DpRqNormalHandle: new state of T14/M0 = 0x42

DpRqNormalHandle: act_wp of T14/M0 = W14

DpRqNormalHandle: give rq_id 10892 to W14 ...

DpRqNormalHandle: set dp_spoolwp_with_token = W14

DpRqServiceQueue: 0 request(s) in UP2 queue ===>>

DpGetScheduleInfo: next_schedule at: 1396273479/227000 in 15/953000 sec/usec

DpTimeCheck: act sleep = 15953 msec

*********** DP LOOP ***********

NiSelISelectInt: 1 handles selected (0 buffered)

NiDgHdlRecvFrom: hdl 1 received 14 bytes from 127.0.0.1:62151

DpAppcBlksLow: appc_blks_in_queue/limit/max = 0/500/1000

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

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

DpRqIGetFromQ: read elem from queue NOWP, elems=0

-IN-- sender_id WORK_PROCESS      tid  5     wp_ca_blk   385     wp_id 5

-IN-- action    SEND_TO_DP        uid  6     appc_ca_blk -1      type  NOWP

-IN-- new_stat  WP_WAIT           mode 0     len         8       rq_id 10893

DpListInsert: insert elem 5 into wpadm_free_dia_list (at begin)

DpListInsert: 10 elems in wpadm_free_dia_list

DpIWpSetNewStat: switched W5 from WP_RUN          to WP_WAIT       

DpIWpSetNewStat: info for DIA: new/wait/run/hold/restr/killed = 0/10/0/0/0/0 wps

DpRqNoWpHandle: new state of W5 is WP_WAIT       

DpHdlWpWait: W5 has req_info 2010, thdp_adm_req_info 20

REL WP ca_blk 385

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DpRqServiceQueue: 0 request(s) in UP2 queue ===>>

APPC blk 385    WORK_PROCESS        4276

DpGetScheduleInfo: next_schedule at: 1396273479/227000 in 15/953000 sec/usec

DpTimeCheck: act sleep = 15953 msec

*********** DP LOOP ***********

NiSelISelectInt: 1 handles selected (0 buffered)

NiDgHdlRecvFrom: hdl 1 received 14 bytes from 127.0.0.1:62147

DpAppcBlksLow: appc_blks_in_queue/limit/max = 0/500/1000

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

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

DpRqIGetFromQ: read elem from queue NOWP, elems=0

-IN-- sender_id WORK_PROCESS      tid  14    wp_ca_blk   880     wp_id 14

-IN-- action    SEND_TO_DP        uid  15    appc_ca_blk -1      type  NOWP

-IN-- new_stat  NO_CHANGE         mode 0     len         8       rq_id 10894

DpRqNoWpHandle: set dp_spoolwp_with_token = NO_WP

REL WP ca_blk 880

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DpRqServiceQueue: 0 request(s) in UP2 queue ===>>

APPC blk 880    WORK_PROCESS        2196

DpGetScheduleInfo: next_schedule at: 1396273479/227000 in 15/953000 sec/usec

DpTimeCheck: act sleep = 15953 msec

*********** DP LOOP ***********

NiSelISelectInt: 1 handles selected (0 buffered)

NiDgHdlRecvFrom: hdl 1 received 14 bytes from 127.0.0.1:62147

DpAppcBlksLow: appc_blks_in_queue/limit/max = 0/500/1000

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

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

DpRqIGetFromQ: read elem from queue NOWP, elems=0

-IN-- sender_id WORK_PROCESS      tid  14    wp_ca_blk   880     wp_id 14

-IN-- action    SEND_TO_DP        uid  15    appc_ca_blk -1      type  NOWP

-IN-- new_stat  WP_WAIT           mode 0     len         8       rq_id 10895

DpListInsert: insert elem 14 into wpadm_free_spo_list (at begin)

DpListInsert: 1 elems in wpadm_free_spo_list

DpIWpSetNewStat: switched W14 from WP_RUN          to WP_WAIT       

DpIWpSetNewStat: info for SPO: new/wait/run/hold/restr/killed = 0/1/0/0/0/0 wps

DpRqNoWpHandle: new state of W14 is WP_WAIT       

DpHdlWpWait: W14 has req_info 42000, thdp_adm_req_info 0

REL WP ca_blk 880

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DpRqServiceQueue: 0 request(s) in UP2 queue ===>>

APPC blk 880    WORK_PROCESS        2196

DpGetScheduleInfo: next_schedule at: 1396273479/227000 in 15/953000 sec/usec

DpTimeCheck: act sleep = 15953 msec

*********** DP LOOP ***********

Mon Mar 31 16:44:39 2014

NiSelISelectInt: 0 handles selected (0 buffered)

DpAppcBlksLow: appc_blks_in_queue/limit/max = 0/500/1000

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

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DpRqServiceQueue: 0 request(s) in UP2 queue ===>>

DpGetScheduleInfo: next_schedule at: 1396273479/227000 in 0/0 sec/usec

DpTimeCheck: secs 1396273479, active req_info's 0x0/0x0/

DpGetSchedule: found slot 3 (type/tid/mode DPT_ENVCHECK/-1/255)

DpSetNextSchedule: next schedule for slot 3: 1396273499/227000 (20/0 sec/msec)

DpCalcNextSchedule: next_schedule: 1396273499/227000

DpTimeCheck: call DpEnvCheck

DpEnvCheck ()

DpEnvCheck: check idle message server connection

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

NiICheck: hdl 17 sent NI_PING to partner

NiICheck2: hdl 17 sent NI_PING to partner

DpEnvCheck: waiting for ms pong

DpWPCheck ()

[DpWpDied] W0 lives  (PID:5604  HANDLE:988)

[DpWpDied] W1 lives  (PID:2808  HANDLE:992)

[DpWpDied] W2 lives  (PID:5760  HANDLE:996)

[DpWpDied] W3 lives  (PID:1340  HANDLE:1000)

[DpWpDied] W4 lives  (PID:4484  HANDLE:1004)

[DpWpDied] W5 lives  (PID:4276  HANDLE:1008)

[DpWpDied] W6 lives  (PID:5552  HANDLE:1012)

[DpWpDied] W7 lives  (PID:4024  HANDLE:1016)

[DpWpDied] W8 lives  (PID:2512  HANDLE:1020)

[DpWpDied] W9 lives  (PID:3716  HANDLE:1028)

[DpWpDied] W10 lives  (PID:5536  HANDLE:1032)

[DpWpDied] W11 lives  (PID:5804  HANDLE:1036)

[DpWpDied] W12 lives  (PID:5812  HANDLE:1040)

[DpWpDied] W13 lives  (PID:5828  HANDLE:1044)

[DpWpDied] W14 lives  (PID:2196  HANDLE:1048)

[DpWpDied] W15 lives  (PID:3316  HANDLE:1052)

DpVmCheck: vmc not active

DpCheckTerminals ()

DpGwCheck: 0

[DpProcDied] Process lives  (PID:4020  HANDLE:976)

DpGwCheck: ok

[DpProcDied] Process lives  (PID:3412  HANDLE:984)

DpCheckTimeTab: check time table

DpCheckRfcCheck: dp_rfc_check_active is 3

DpCalcTimeSlice: slice[1][41] = 0.001513

DpCalcTimeSlice: slice[2][41] = 0.000000

DpCalcTimeSlice: slice[3][41] = 0.000000

DpCalcTimeSlice: slice[4][41] = 0.000000

DpCalcTimeSlice: slice[5][41] = 0.000000

DpCalcTimeSlice: slice[6][41] = 0.000000

DpEnvCheck o.k.

DpGetSchedule: next schedule 1396273499/227000

DpGetSchedule: no schedule found

DpGetScheduleInfo: next_schedule at: 1396273499/227000 in 20/0 sec/usec

DpTimeCheck: act sleep = 20000 msec

*********** DP LOOP ***********

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

NiBufIProcMsg: hdl 17 received NI_PONG: wait_count: 1

dev_w0:

M  ThPlgConnectToIcm: connect to icman (ni/socket = 1/1288) o.k.

M  connect to icman o.k.

M  SigIRegisterExitRoutine: exithandler installed (ThSigHandler)

M  SigIRegisterTerminateRoutine: terminatehandler installed (ThSigHandler)

M  TskhLoop: tell dispatcher: I'am ready for processing

M  DpLockWpBlkEx: lock wp ca_blk 110

M  DpLockAppcBlk: lock appc ca_blk 110

M  TskhLoop: th_step = TH_WORK_SYNC

M  ThIEndBg: end background processing of T-1 (type=0x41, logoff=1, freeResources=0)

M  ThSetThParam : rdisp/thsend_order=0

M  rdisp/thsend_order : -1 -> 0

M  ThISend: set thsend_order to 0

M  ThISend: (T-1/M255, user=           ?, type=0x41, info=0x0, mode_deleted=1)

M    type=TASK_SWITCH,DISP

M  ThrtGetStrategy: abap strategy ROLL / O.K.

M  ThRqOutCheck: check output for T-1/M255

M  ThRqOutCheck: o.k.

M  ThNewWpStat (type=0x41, task_switch=1, inline_hold=0, hand_shake=0, debug=0, ..)

M  ThNewWpStat: no TID/MODE but task switch -> return WP_WAIT

M  rdisp/wp_auto_restart = 0 sec

M  rdisp/wp_abap_restart = 0

M  ThSetThToolParam : restartable_wp[0] = FALSE

M  ThSetThToolParam : restartable_wp[1] = TRUE

M  ThSetThToolParam : restartable_wp[2] = TRUE

M  ThSetThToolParam : restartable_wp[3] = FALSE

M  ThSetThToolParam : restartable_wp[4] = TRUE

M  ThSetThToolParam : restartable_wp[5] = TRUE

M  ThSetThToolParam : restartable_wp[6] = TRUE

M  ThMkReq: detach wp_ca_blk 110

M  ThDtWpBlk: detach WP ca_blk 110

M  ThISend: clear stat record

M  PfClearAllRec: clear statistic area

M  ThISend: stat_state = TH_STAT_CLEAR

M  ThISend: th_wp_waits_for_rq = TH_WP_WAITS_FOR_RQ

M  make DISP owner of wp_ca_blk 110

M  DpRqIPutIntoQ: put elem in queue NOWP, elems=1

M  DpRqPutIntoQueue: put request into queue (reqtype 0, prio LOW, rq_id 63)

M  -OUT- sender_id WORK_PROCESS      tid  -1    wp_ca_blk   110     wp_id 0

M  -OUT- action    SEND_TO_DP        uid  -1    appc_ca_blk -1      type  NOWP

M  -OUT- new_stat  WP_WAIT           mode 0     len         8       rq_id 63

M  ThCheckBlks: th_step = TH_WORK_NOSYNC

M  ThCheckBlks: release appc_ca_blk 110

M  REL APPC ca_blk 110

M  ThDtAppcBlk: detach APPC ca_blk 110

M  ThCallHooks: call hook >TrThHookFunc< for event BEFORE_TASK_SWITCH

M  TrThHookFunc: called for task switch

M  ThCallHooks: hook >TrThHookFunc< o.k.

M  ThCallHooks: call hook >ThPCleanZombiesCallback< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >ThPCleanZombiesCallback< o.k.

M  ThCallHooks: call hook >ImcResetPtr< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >ImcResetPtr< o.k.

M  ThCallHooks: call hook >ThIDbgCallbackBeforeTaskSwitch< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >ThIDbgCallbackBeforeTaskSwitch< o.k.

M  ThCallHooks: call hook >HttpResetPtr< for event BEFORE_TASK_SWITCH

M  ThCallHooks: hook >HttpResetPtr< o.k.

M  ThISend: jump back with rc TH_WAIT_FOR_REQUEST

M  ********* W0 LOOP (DIA) *********

M  TskhLoop: save spa pointer 000007E00001C1A0 and install dummy spa

M  TskhLoop: th_wp_waits_for_rq = TH_NO_WAIT_INFO

M  ThReceive: th_step = TH_WAIT_FOR_REQ

M  ThRqWaitFor: wait for next request (new time slice)

M  event-handling: W0, key 50800, flag 50800

M  ThAlarm: no previous alarm

M  ThRqWaitFor: wait for request

Reagan
Advisor
Advisor
0 Kudos

I am sorry I don't see any errors in those traces.

Regards

RB