Solution Manager 7.1 SP8 on Sybase 15.7 ESD 4, Session Terminates & All process are occupied by sapsys user

[sridharreddy_guda]

Dear Experts,

We have installed and Upgraded the SAP Solution Manager 7.1 SP8 on sybase 15.7 ESD#1 on windows 2008 R2 SP1. After SP8 upgrade we have upgrade the sybase to 15.7 ESD4 & kernel is on 721 EXT 100 patch level.

Server details:

RAM: 20 GB Memory

CPU: 4 Cores

Page Memory: 50 GB

SAP Application is starting, but most of the processes are occupying by sapsys user and its running for long time. After restart of the SAP Application and Server, process are free but when we execute any transactions after some time sessions get terminates.

Tried by applying the 720 EXT UC latest kernel, but the result is same.

We have set the parameters instance profile parameters as part of Solution Manager setup, as per the recommendations.

We are not getting where it went wrong.

Pls find the dev_w* log file

---------------------------------------------------
trc file: "dev_w3", trc level: 1, release: "721"
---------------------------------------------------
*
*  ACTIVE TRACE LEVEL           1
*  ACTIVE TRACE COMPONENTS      all, MJ
*
M sysno      11
M sid        SM1
M systemid   562 (PC with Windows NT)
M relno      7210
M patchlevel 0
M patchno    100
M intno      20020600
M make       multithreaded, Unicode, 64 bit, optimized
M profile    \\<hostname>\sapmnt\SM1\SYS\profile\SM1_DVEBMGS11_<hostname>
M pid        5936
M
M  kernel runs with dp version 137000(ext=119000) (@(#) DPLIB-INT-VERSION-137000-UC)
M  length of sys_adm_ext is 588 bytes
M  ***LOG Q0Q=> tskh_init, WPStart (Workp. 3 5936) [dpxxdisp.c   1376]
I  MtxInit: 30000 0 0
M  DpSysAdmExtCreate: ABAP is active
M  DpSysAdmExtCreate: VMC (JAVA VM in WP) is not active
M  DpSysAdmExtCreate: J2EE Engine running with this server
M  DpIPCInit2: read dp-profile-values from sys_adm_ext
M  DpShMCreate: sizeof(wp_adm)  42864 (2256)
M  DpShMCreate: sizeof(tm_adm)  5517056 (27448)
M  DpShMCreate: sizeof(wp_ca_adm)  64000 (64)
M  DpShMCreate: sizeof(appc_ca_adm) 64000 (64)
M  DpCommTableSize: max/headSize/ftSize/tableSize=500/16/1384064/1384080
M  DpShMCreate: sizeof(comm_adm)  1384080 (2744)
M  DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0
M  DpShMCreate: sizeof(slock_adm)  0 (296)
M  DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0
M  DpShMCreate: sizeof(file_adm)  0 (80)
M  DpShMCreate: sizeof(vmc_adm)  0 (2160)
M  DpShMCreate: sizeof(wall_adm)  (41664/42896/64/192)
M  DpShMCreate: sizeof(gw_adm) 48
M  DpShMCreate: sizeof(j2ee_adm) 3952
M  DpShMCreate: SHM_DP_ADM_KEY  (addr: 000000000FB00050, size: 7174832)
M  DpShMCreate: allocated sys_adm at 000000000FB00060
M  DpShMCreate: allocated wp_adm_list at 000000000FB030B0
M  DpShMCreate: allocated wp_adm at 000000000FB032A0
M  DpShMCreate: allocated tm_adm_list at 000000000FB0DA20
M  DpShMCreate: allocated tm_adm at 000000000FB0DA70
M  DpShMCreate: allocated wp_ca_adm at 0000000010050980
M  DpShMCreate: allocated appc_ca_adm at 0000000010060390
M  DpShMCreate: allocated comm_adm at 000000001006FDA0
M  DpShMCreate: system runs without slock table
M  DpShMCreate: system runs without file table
M  DpShMCreate: allocated vmc_adm_list at 00000000101C1C40
M  DpShMCreate: system runs without vmc_adm
M  DpShMCreate: allocated gw_adm at 00000000101C1CF0
M  DpShMCreate: allocated j2ee_adm at 00000000101C1D30
M  DpShMCreate: allocated ca_info at 00000000101C2CB0
M  DpShMCreate: allocated wall_adm at 00000000101C2D40
M 
M Sat Mar 30 13:18:30 2013
M  DpCommAttachTable: attached comm table (header=000000001006FDA0/ft=000000001006FDB0)
M  DpRqQInit: use protect_queue / slots_per_queue 0 / 4001 from sys_adm
M  rdisp/queue_size_check_value :  -> on,50,30,40,500,50,500,80
X  EmInit: MmSetImplementation( 2 ).
X  MM global diagnostic options set: 0
X  <ES> client 3 initializing ....
X  <ES> EsILock: use spinlock for locking
X  Using implementation view
X  <EsNT> Using memory model view.
M  <EsNT> Memory Reset disabled as NT default
X  ES initialized.
X  mm.dump: set maximum dump mem to 96 MB
M  DpVmcSetActive: set vmc state DP_VMC_NOT_ACTIVE
M 
M Sat Mar 30 13:18:31 2013
M  ThStart: taskhandler started
M  ThInit: initializing DIA work process W3
M 
M Sat Mar 30 13:18:34 2013
M  ThInit: running on host <hostname>
M  calling db_connect ...
B  Loading DB library 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll' ...
B  Library 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll' loaded
B  Version of 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll' is "721.02", patchlevel (0.110)
C  trace file set
C  Callback functions for dynamic profile parameter registered
B  con_info_ext_support(): DBSL supports extended connect protocol
B    ==> connect info for default DB will be read from ssfs
C 
C Sat Mar 30 13:18:35 2013
C  database is local: connect_timeout: 3
C  Thread ID:6228
C  lib_dbsl 721 03/06/2013 13:02:00
C  721_EXT build
C  Using dynamic link library 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll'
C  dbsybslib.dll patch info
C    SAP patchlevel  0
C    SAP patchno  110
C    Last SYBASE DBSL patchlevel 0
C    Last SYBASE DBSL patchno         110
C    Last SYBASE DBSL patchcomment SYB: Set up SSL encryption for secondary SAP connections (1820202)
C  lpc:(local) connection used on <hostname>
C 
C Sat Mar 30 13:18:36 2013
C  lpc:(local) connection used on <hostname>
C  spid 153 comm rd ODBC
C  spid 170 unc rd ODBC
C  Driver: Adaptive Server Enterprise Driver release: 15.7.0.504
C  Driver full version: Sybase ASE ODBC Driver/15.7.0/ESD#5 N-OF/P/AMD64/Windows/drv157esd5/Build 504/64-bit/Normal/Thu Dec 20 UTC 21:52:52 2012/SQLLEN8
C  Driver uses SQLLEN8
C  GetServerInfo version: Adaptive Server Enterprise/15.7.0/EBF 21024 SMP ESD#04.1 /P/X64/Windows Server/ase157x/46/64-bit/OPT/Fri Jan 04 01:23:44 2013
C  GetServerInfo release: 15.7.0.041
C  GetServerInfo architecture: x86_64
C  GetServerInfo edition: P
C  GetServerInfo byte width: 64 bit
C  DbSlConnect hdl: 0
C  trace file set
B  Connection 0 opened (DBSL handle 0)
M  ThInit: db_connect o.k.
M  ICT: exclude compression: *.zip,*.rar,*.arj,*.z,*.gz,*.tar,*.lzh,*.cab,*.hqx,*.ace,*.jar,*.ear,*.war,*.css,*.pdf,*.gzip,*.uue,*.bz2,*.iso,*.sda,*.sar,*.gif,*.png,*.swc,*.swf
I 
I Sat Mar 30 13:18:39 2013
I  MtxInit: 3 0 0
M  SHM_PRES_BUF   (addr: 0000000016CC0050, size: 4400000)
M  SHM_ROLL_AREA  (addr: 000007FFD1730050, size: 268435456)
M  SHM_PAGING_AREA  (addr: 0000000017100050, size: 134217728)
M  SHM_ROLL_ADM   (addr: 000000001F110050, size: 2767448)
M  SHM_PAGING_ADM  (addr: 00000000075E0050, size: 787488)
M  ThCreateNoBuffer  allocated 548152 bytes for 1000 entries at 0000000009730050
M  ThCreateNoBuffer  index size: 3000 elems
M  ThCreateVBAdm  allocated 31056 bytes (50 server) at 0000000002980050
X  EmInit: MmSetImplementation( 2 ).
X  MM global diagnostic options set: 0
X  <ES> client 3 initializing ....
X  Using implementation view
X  ES initialized.
X  mm.dump: set maximum dump mem to 96 MB
B  db_con_shm_ini:  WP_ID = 3, WP_CNT = 19, CON_ID = -1
B  dbtbxbuf: Buffer TABL  (addr: 0000000026350160, size: 100000000, end: 000000002C2AE260)
B  dbtbxbuf: Buffer TABLP (addr: 000000002C2B0160, size: 61440000, end: 000000002FD48160)
B  dbsync[db_syinit]: successfully attached to shared memory, sync_adm_p = 0000000002990050
B  dbsync[db_syinit]: Buffer synchronisation started with
B    sync_concept      = SEQ_NR
B    sendon            = 1
B    bufreftime        = 120
B    max_gap_wait_time = 60
B    ddlog_del_time    = 60
B    last_counter      = -2147483648
B    oldest_gap        = (2147483647,00000000000000)
B    time_of_last_sync = 20130330131837
B    MySysId           = '<hostname>              11'
B  dbexpbuf[EXP_SHB]: buffer EIBUF installed with
B    semkey             = 35
B    shmkey             = 54
B    wp_n               = 19
B    sclass             = 0
B    block_length       = 256
B    max_objects        = 20000
B    max_obj_size       = 10919200
B    pref_obj_size      = 0
B    est_large_obj_size = 16384
B    free_vec_lg        = 65
B    hash_vec_size      = 40009
B    buffer_l           = 51200000
B    max_blocks         = 170615
B    free_blocks        = 170615
B    mutex_n            = 40009
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
B  dbexpbuf[EXP_SHM]: buffer ESM   installed with
B    semkey             = 56
B    shmkey             = 65
B    wp_n               = 19
B    sclass             = 0
B    block_length       = 256
B    max_objects        = 2000
B    max_obj_size       = 859872
B    pref_obj_size      = 0
B    est_large_obj_size = 16384
B    free_vec_lg        = 65
B    hash_vec_size      = 4001
B    buffer_l           = 4194304
B    max_blocks         = 13438
B    free_blocks        = 13438
B    mutex_n            = 4001
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
B  dbexpbuf[EXP_CUA]: buffer CUA   installed with
B    semkey             = 30
B    shmkey             = 47
B    wp_n               = 19
B    sclass             = 10
B    block_length       = 512
B    max_objects        = 5000
B    max_obj_size       = 2088928
B    pref_obj_size      = 0
B    est_large_obj_size = 98304
B    free_vec_lg        = 193
B    hash_vec_size      = 10007
B    buffer_l           = 10240000
B    max_blocks         = 16321
B    free_blocks        = 16321
B    mutex_n            = 10007
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
B  dbexpbuf[EXP_OTR]: buffer OTR   installed with
B    semkey             = 55
B    shmkey             = 64
B    wp_n               = 19
B    sclass             = 13
B    block_length       = 256
B    max_objects        = 2000
B    max_obj_size       = 859872
B    pref_obj_size      = 0
B    est_large_obj_size = 20480
B    free_vec_lg        = 81
B    hash_vec_size      = 4001
B    buffer_l           = 4194304
B    max_blocks         = 13438
B    free_blocks        = 13438
B    mutex_n            = 4001
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
I  MPI: dynamic quotas disabled.
I  MPI init: pipes=4000 buffers=1279 reserved=383 quota=10%
M  rdisp/thwpsf_critical_path : -1 -> 0
M  CCMS: SemInMgt: Semaphore Management initialized by AlAttachShm_Doublestack.
M  CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.
G  RelWritePermissionForShm( pLocation = 120, pEnforce = 0 )
G  GetWritePermissionForShm( pLocation =  99, pEnforce = 1 )
G  RelWritePermissionForShm( pLocation = 100, pEnforce = 1 )
S  *** init spool environment
S  TSPEVJOB updates outside critical section: event_update_nocsec = 1
S  initialize debug system
T  Stack direction is downwards.
T  debug control: prepare exclude for printer trace
T  new memory block 00000000168B2B30
S  spool kernel/ddic check: Ok
S  using table TSP02FX for frontend printing
S  1 spool work process(es) found
S  frontend print via spool service enabled
S  printer list size is 150
S  printer type list size is 50
S  queue size (profile)   = 300
S  hostspool list size = 3000
S  option list size is 30
S      found processing queue enabled
S  found spool memory service RSPO-RCLOCKS at 000000003D3100D0
S  doing lock recovery
S  setting server cache root
S  found spool memory service RSPO-SERVERCACHE at 000000003D3106A0
S    using messages for server info
S  size of spec char cache entry: 297032 bytes (timeout 100 sec)
S  size of open spool request entry: 2512 bytes
S  immediate print option for implicitely closed spool requests is disabled
A  ***GENER* Trace switched on ***
A 
A  ---PXA-------------------------------------------
A  PXA INITIALIZATION
A 
A Sat Mar 30 13:18:40 2013
A  PXA: Locked PXA-Semaphore.
A  System page size: 4kb, total admin_size: 37312kb, dir_size: 36520kb.
A  Attached to PXA (address 000007FFE1760050, size 500000K, 1 fragments of 462688K )
A  PXA allocated (address 000007FFE1760050, size 500000K)
A  abap/pxa = shared protect gen_remote
A  PXA: checking structure sizes: 752|272|16
A  PXA INITIALIZATION FINISHED
A  ---PXA-------------------------------------------
A 
A  ATRA: pfclock execution time = 0
A  abap/force_local_update_task=0
A  ABAP ShmAdm attached (addr=000007DFDA15F000 leng=104841216 end=000007DFE055B000)
A  >> Shm MMADM area (addr=000007DFDB929780 leng=1229120 end=000007DFDBA558C0)
A  >> Shm MMDAT area (addr=000007DFDBA56000 leng=78663680 end=000007DFE055B000)
A  RFC rfc/signon_error_log = -1
A  RFC rfc/dump_connection_info = 0
A  RFC rfc/dump_client_info = 0
A  RFC rfc/cp_convert/ignore_error = 1
A  RFC rfc/cp_convert/conversion_char = 23
A  RFC rfc/wan_compress/threshold = 251
A  RFC rfc/recorder_pcs not set, use defaule value: 2
A  RFC rfc/delta_trc_level not set, use default value: 0
A  RFC rfc/no_uuid_check not set, use default value: 0
H  HTTP> Parameter icf/ssocookie_mandatory set to 0
B  dbtran INFO (init_connection '<DEFAULT>' [SYBASE:721.02]):
B   max_blocking_factor       =  50,  min_blocking_factor         =   1,
B   max_in_blocking_factor    = 128,  min_in_blocking_factor      =   1,
B   max_union_blocking_factor =  50,  min_union_blocking_factor   =   1,
B   prefer_union_all          =   0,  prefer_join                 =   0,
B   prefer_fix_blocking       =   0,  prefer_in_itab_opt          =   1,
B   convert AVG               =   1,  alias table FUPD            =   0,
B   escape_as_literal         =   0,                                  
B   select *                  =0x0f,  character encoding          = STD / []:X,
B   use_hints                 = abap->1, dbif->0x1, upto->0
M  ThrCreateShObjects  allocated 39514 bytes at 00000000029C0050
Y  dyWpInit
Y    ztta/dynpro_ara 800000
Y    ztta/cua_ara    500000
Y    ztta/diag_ara   250000
N  SsfSapSecin: getenv(SECUDIR)=="E:\usr\sap\SM1\DVEBMGS11/sec"
N 
N  =================================================
N  === SSF INITIALIZATION:
N  ===...SSF Security Toolkit name SAPSECULIB .
N  ===...SSF library is E:\usr\sap\SM1\DVEBMGS11\exe\sapcrypto.dll .
N  ===...SSF default hash algorithm is SHA1 .
N  ===...SSF default symmetric encryption algorithm is DES-CBC .
N  ===...SECUDIR="E:\usr\sap\SM1\DVEBMGS11/sec"
N  ===...loading of Security Toolkit successfully completed.
N  ===   SAPCRYPTOLIB  5.5.5C pl32  (Apr  2 2011) MT-safe
N  =================================================
N 
N Sat Mar 30 13:18:47 2013
N  MskiInitLogonTicketCacheHandle: Logon Ticket cache pointer retrieved from shared memory.
N  MskiInitLogonTicketCacheHandle: Workprocess runs with Logon Ticket cache.
M  JrfcVmcRegisterNativesDriver o.k.
W  =================================================
W  === ipl_Init() called
W    ITS Plugin: Path dw_gui
W    ITS Plugin: Description ITS Plugin - ITS rendering DLL
W    ITS Plugin: sizeof(SAP_UC) 2
W    ITS Plugin: Release: 721, [7210.0.100.20020600]
W    ITS Plugin: Int.version, [34]
W    ITS Plugin: Feature set: [31]
W    ===... Calling itsp_Init in external dll ===>
W  === ipl_Init() returns 0, ITSPE_OK: OK
W  =================================================
N  SignInit: successfully obtained handle for Security Context cache
N  SignInit: Not attempted to load Kerberos Library (ABAP parts missing, note TODO)
N  VSI: WP init in ABAP VM completed with rc=0
E  EnqLockTableSizeCalculate: session quota = 100%
E  EnqLockTableAttach2: attach to lock table (size = 33554432)
E  EnqLockTableMapToLocalContext: enque/use_pfclock2 = FALSE
E  EnqId_Initialize: local EnqId initialization o.k.
M  rdisp/thsend_order : -1 -> 0
M  ThISend: set thsend_order to 0
A  PXA preload starting.
A  preloading up to 92537 KBytes.
A  File pxauserload not present. Skip first step.
A  *GENER* starting inline generation: RSICC000 (reason: PXA preload).
A  UpdateProfile (new settings): off,krn_impl,(no dyn check),(no logging),(no stat err),(local generation),(no commit)
A  UpdateProfile (new exceptions): (tmp_err),(home_err),(local_err)
A  UpdateProfile (new version): 0
A 
A Sat Mar 30 13:18:48 2013
A  ***GENER* Trace switched off ***
B  dbrda: Redirect database access is disabled via rsdb/rda profile parameter setting.
C  ERROR: -1 in function StartSelect (execute) [line 20166]
C  (30046) [08S01] [Sybase][ODBC Driver]Connection to Sybase server has been lost. ASE Terminated t
C  he connection. All active transactions have been rolled back.
C  syb_glob.dbsl_ca.errclass set to DBSL_EC_RESTART
C  ERROR: -1 in function StartSelect (execute) [line 20166]
C  (5702) [ZZZZZ] [Sybase][ODBC Driver][Adaptive Server Enterprise]ASE is terminating this process
C  .
C 
C  sql statement is ==> SELECT TOP 1 "SUBC" FROM "TRDIR" WHERE "NAME" = ?
C  /* R3:RSGENCOV:102 T:TRDIR M:
C  000 */
C 
B  ***LOG BYM=> severe DB error 30046     ; work process in reconnect status [dbsh         1174]
B  ***LOG BY4=> sql error 30046  performing SEL on table TRDIR                          [dbtran       7637]
B  ***LOG BY0=> [ASE Error SQL30046:08S01]Connection to Sybase server has been lost. ASE Terminated the connection. All active transactions have been rolled back.[ASE Error SQL5702]ASE is terminating this process.
[dbtran       7637]
B  dbtran ERROR LOG (hdl_dbsl_error): DbSl 'SEL'
B   RSLT: {dbsl=99, tran=1}
B   FHDR: {tab='TRDIR', fcode=17, mode=2, bpb=0, dbcnt=0, crsr=1,
B          hold=0, keep=1, xfer=0, pkg=0, upto=0, init:b=0,
B          init:p=0000000000000000, init:#=2, wa:p=0X000007DF000DD568, wa:#=2}
B  dbtran ERROR LOG (hdl_dbsl_error): DbSl 'SEL'
B   STMT: {stmt:#=1, bndfld:#=1, prop=0x21, distinct=0,
B          fld:#=1, alias:p=0000000000000000, fupd:#=0, tab:#=1, where:#=1,
B          groupby:#=0, having:#=0, order:#=0, primary=0, hint:#=0}
B   CRSR: {tab='', id=0, hold=0, prop=0, max.in@0=1, fae:blk=0,
B          con:id=0, con:vndr=6, val=2,
B          key:#=1, xfer=0, xin:#=0, row:#=0, upto=1, wa:p=0X000007DF000DD568}
C  statement id is ==> [6 REPOLOAD                                 1854 20130228110140]
C 
B  DBREPO: report table: 'REPOLOAD                                '
B  DBREPO: program: 'CX_SY_OPEN_SQL_DB=============CP        '
B  DBREPO: state: A, lang = *, mach = 562
B  ***LOG BY4=> sql error 0      performing SEL on table REPOLOAD   [dbrepo       2920]
B  ***LOG BY0=> <message text not available> [dbrepo       2920]
A  TH VERBOSE LEVEL FULL
A  ** RABAX: level LEV_RX_PXA_RELEASE_MTX entered.
A  ** RABAX: level LEV_RX_PXA_RELEASE_MTX completed.
A  ** RABAX: level LEV_RX_COVERAGE_ANALYSER entered.
A  ** RABAX: level LEV_RX_COVERAGE_ANALYSER completed.
A  ** RABAX: level LEV_RX_HOTSPOT_TRACE entered.
A  ** RABAX: level LEV_RX_HOTSPOT_TRACE completed.
A  ** RABAX: level LEV_RX_SAVE_SHMLOCKS entered.
A  ** RABAX: level LEV_RX_SAVE_SHMLOCKS completed.
A  ** RABAX: level LEV_RX_RESET_SHMLOCKS entered.
A  ** RABAX: level LEV_RX_RESET_SHMLOCKS completed.
A  ** RABAX: level LEV_RX_ROLLBACK entered.
C  ERROR: -1 in function DoPing (SQLAllocStmt failed) [line 21231]
C  (30102) [HY010] [Sybase][ODBC Driver]Function sequence error
C  Connection Test during Rollback failed
B  Reconnect state is entered by connection:
B  0: name = R/3, con_id = 000000000, state = ACTIVE      , tx = YES, bc = NO , hc = NO , perm = YES, reco = YES, frco = NO , timeout = 000, con_max = 255, con_opt = 255, occ = NO , prog = 
B  hdl_error_on_commit_rollback: COMMIT/ROLLBACK failed without RECONNECT error code
B  hdl_error_on_commit_rollback: marking CONNECTION as closed
B  hdl_error_on_commit_rollback: DB-ROLLBACK detected RECONNECT state
B  RECONNECT: rsdb/reco_trials: 3
B  RECONNECT: rsdb/reco_sleep_time: 5
B  RECONNECT: rsdb/reco_sync_all_server: OFF
B  db_con_reconnect: reconnecting connection 0:
B  0: name = R/3, con_id = 000000000, state = INACTIVE    , tx = NO , bc = NO , hc = NO , perm = YES, reco = YES, frco = NO , timeout = 000, con_max = 255, con_opt = 255, occ = NO , prog = 
B  disconnecting from connection 0 ...
C  DbSlDisconnect hdl: 0
A  ** RABAX:  level LEV_RX_ROLLBACK failed.
A  *** suicide ON
A  ** RABAX: level LEV_RX_DB_ALIVE entered.
A  ** RABAX: level LEV_RX_DB_ALIVE completed.
A  ** RABAX: level LEV_RX_HOOKS entered.
A  ** RABAX: level LEV_RX_HOOKS completed.
A  ** RABAX: level LEV_RX_STANDARD entered.
A  ** RABAX: level LEV_RX_STANDARD completed.
A  ** RABAX: level LEV_RX_STOR_VALUES entered.
A  ** RABAX: level LEV_RX_STOR_VALUES completed.
A  ** RABAX: level LEV_RX_C_STACK entered.
A 
A Sat Mar 30 13:18:51 2013
A  ** RABAX: level LEV_RX_C_STACK completed.
A  ** RABAX: level LEV_RX_MEMO_CHECK entered.
A  ** RABAX: level LEV_RX_MEMO_CHECK completed.
A  ** RABAX: level LEV_RX_AFTER_MEMO_CHECK entered.
A  ** RABAX: level LEV_RX_AFTER_MEMO_CHECK completed.
A  ** RABAX: level LEV_RX_INTERFACES entered.
A  ** RABAX: level LEV_RX_INTERFACES completed.
A  ** RABAX: level LEV_RX_GET_MESS entered.
A  ** RABAX: level LEV_RX_GET_MESS completed.
A  ** RABAX: level LEV_RX_INIT_SNAP entered.
A  ** RABAX: level LEV_RX_INIT_SNAP completed.
A  ** RABAX: level LEV_RX_WRITE_SYSLOG entered.
A  ** RABAX: level LEV_RX_WRITE_SYSLOG completed.
A  ** RABAX: level LEV_RX_WRITE_SNAP_BEG entered.
A  ** RABAX: level LEV_RX_WRITE_SNAP_BEG completed.
A  ** RABAX: level LEV_RX_WRITE_SNAP entered.
A  ** RABAX: level LEV_SN_END completed.
A  ** RABAX: level LEV_RX_WRITE_SNAP_END entered.
A  ** RABAX: level LEV_RX_WRITE_SNAP_END completed.
A  ** RABAX: level LEV_RX_SET_ALERT entered.
A  ** RABAX: level LEV_RX_SET_ALERT completed.
A  ** RABAX: level LEV_RX_COMMIT entered.
A  ** RABAX: level LEV_RX_COMMIT completed.
A  ** RABAX: level LEV_RX_SNAP_SYSLOG entered.
A  ** RABAX: level LEV_RX_SNAP_SYSLOG completed.
A  ** RABAX: level LEV_RX_RESET_PROGS entered.
A  ** RABAX: level LEV_RX_RESET_PROGS completed.
A  ** RABAX: level LEV_RX_STDERR entered.
A  Sat Mar 30 13:18:51 2013
A 
A  Error Code DBIF_REPO_SQL_ERROR.
A  Module  $Id: //bas/721_REL/src/krn/runt/abload5.c#1 $ SAP.
A  Function db_info Line 384.
A  ** RABAX: level LEV_RX_STDERR completed.
A  ** RABAX: level LEV_RX_RFC_ERROR entered.
A  ** RABAX: level LEV_RX_RFC_ERROR completed.
A  ** RABAX: level LEV_RX_RFC_CLOSE entered.
A  ** RABAX: level LEV_RX_RFC_CLOSE completed.
A  ** RABAX: level LEV_RX_IMC_ERROR entered.
A  ** RABAX: level LEV_RX_IMC_ERROR completed.
A  ** RABAX: level LEV_RX_DATASET_CLOSE entered.
A  ** RABAX: level LEV_RX_DATASET_CLOSE completed.
A  ** RABAX: level LEV_RX_ERROR_SAVE entered.
A  ** RABAX: level LEV_RX_ERROR_SAVE completed.
A  ** RABAX: level LEV_RX_ERROR_TPDA entered.
A  ** RABAX: level LEV_RX_ERROR_TPDA completed.
A  ** RABAX: level LEV_RX_PXA_RELEASE_RUDI entered.
A  ** RABAX: level LEV_RX_PXA_RELEASE_RUDI completed.
A  ** RABAX: level LEV_RX_LIVE_CACHE_CLEANUP entered.
A  ** RABAX: level LEV_RX_LIVE_CACHE_CLEANUP completed.
A  ** RABAX: level LEV_RX_END entered.
A  ** RABAX: level LEV_RX_END completed.
A  ** RABAX: end RX_SUICIDE
A  Error DBIF_REPO_SQL_ERROR occured.  P1=[REPO/*/43/LOAD/CX_SY_OPEN_SQL_DB=============CP        ] P2=CX_SY_OPEN_SQL_DB=============CP         P3=0 P4=??? P5=LOAD.
A 
A  *
A  * Non recoverable error. Aborting process...
A  *
M  ***LOG R63=> ThWpHalt, halt wp () [thxxhead.c   17739]
M  in_ThErrHandle: 1
M  *** ERROR => ThWpHalt: halt wp (step 4, th_errno 5, action 2, level 1) [thxxhead.c   11348]
M  {root-id=00155D0502241EE2A6A1C80F354A4EE8}_{conn-id=00000000000000000000000000000000}_0

M  DpVmcGetVmByTmAdm: no VM found for T3/M0/I1 (locked VMs ignored)
M  ThResetEmMagic: reset em magic for T3/M0/I1
M 
M  Info for wp 3
M 
M    pid = 5936
M    severity = 0
M    status = 0
M    stat = WP_RUN
M    waiting_for = NO_WAITING
M    reqtype = DP_RQ_DIAWP
M    act_reqtype = DP_RQ_DIAWP
M    req.req_info = THDP_ADM,SET_SYSTEM_USER
M    req.tid = 3
M    req.uid = 4
M    req.mode = 0
M    req.len = 0
M    req.rq_id = 37
M    req.rq_source = FROM_WP_CA
M    last_tid = 3
M    last_uid = 4
M    last_mode = 0
M    act_cs_count = 0
M    csTrack = 0
M    csTrackRwExcl = 0
M    csTrackRwShrd = 0
M    mode_cleaned_counter = 0
M    control_flag = 1000040
M    int_checked_resource(RFC) = 0
M    ext_checked_resource(RFC) = 0
M    int_checked_resource(HTTP) = 0
M    ext_checked_resource(HTTP) = 0
M    report = ><DELAYED FUNCTION CALL>                 <
M    action = 22
M    tab_name = >                              <
M    attachedVm = no VM
M 
M  Modeinfo for User T3/M0
M 
M    tm state = 2
M    uid = 4
M    term type = 0x2
M    display = 0x0
M    cpic_no = 0
M    cpic_idx = -1
M    usr = >SAPSYS      <
M    terminal = >                    <
M    client = >000<
M    conversation_ID = >        <
M    appc_tm_conv_idx = -1
M    its_plugin = NO
M    allowCreateMode = YES
M    blockSoftCanel = NO
M     Session Resources:
M  HEAD: adr/max_elems/act_elems/tbl = 000000000FB28590/0/0/0000000000000000
M    imode = 1
M    mode state = 0x42
M    task_type = ZTTADELAY
M    lastThFc = THFCDELAY
M    lastAction = TH_IACT_NO_ACTION
M    th_errno = Internal TSKH error, session terminated (5)
M    rollout_reason = 0
M    last_rollout_level = 7
M    async_receives = 0
M    cpic_receive = 0
M    em handle = 0
M    roll state = DP_ROLL_INITIALIZED
M    abap state = DP_ROLL_INITIALIZED
M    em state = DP_EM_ATTACHED
M    eg state = DP_EM_NO_CONTEXT
M    spa state = DP_SPA_INITIALIZED
M    enq state = 0
M    softcancel = 0
M    cancelInitiator = ?
M    clean_state = DP_DEFAULT_CLEANING
M    next hook = T-1/U-1/M255
M    master hook = T-1/U-1/M255
M    slave hook = T-1/U-1/M255
M    debug_tid = -1
M    debug_mode = 255
M    mode type = 0x1
M    debug = DP_DEBUGGING_OFF
M    msg_count = 1
M    tcode = >                    <
M    act_wp = 3
M    last_wp = 3
M    client conversation_ID = >        <
M    server conversation_ID = >        <
M    lock = 0
M    max enq infos = 0
M    act enq infos = 0
M    em_hyper_hdl = 000007DFE0821570
M    plugin_info = NULL
M    act_plugin_hdl = -1
M    act_plugin_no  = 0
M    max_plugin_no  = 0
M    Context Resources:
M  HEAD: adr/max_elems/act_elems/tbl = 000000000FB22078/0/0/0000000000000000
M 
M  ThPrintDataBlks: associated blocks: wp/appc=261/260, len=8/80
M 
M 
M  ThIErrHandle: current request:
M 
M  -IN-- sender_id DISPATCHER        tid  3     wp_ca_blk   261     wp_id -1
M  -IN-- action    SEND_TO_WP        uid  4     appc_ca_blk -1      type  DIA 
M  -IN-- new_stat  NO_CHANGE         mode 0     len         0       rq_id 37
M  -IN-- req_info  THDP_ADM,SET_SYSTEM_USER
M  -IN-- thdp_adm_req_info  CALL_DELAYED 
M 
M  *****************************************************************************
M  *
M  *  LOCATION    SAP-Server <hostname>_SM1_11 on host <hostname> (wp 3)
M  *  ERROR       ThWpHalt: halt wp
M  *
M  *  TIME        Sat Mar 30 13:18:51 2013
M  *  RELEASE     721
M  *  COMPONENT   Taskhandler
M  *  VERSION     1
M  *  RC          5
M  *  MODULE      thxxhead.c
M  *  LINE        11629
M  *  COUNTER     1
M  *
M  *****************************************************************************
M 
M  PfStatDisconnect: disconnect statistics
M  Entering TH_CALLHOOKS
M  ThCallHooks: call hook >TrThHookFunc< for event BEFORE_DUMP
M  TrThHookFunc: called for WP dump
M  ThCallHooks: hook >TrThHookFunc< o.k.
M  ThCallHooks: call hook >ThrSaveSPAFields< for event BEFORE_DUMP
M  ThrSaveSPAFields: save spa fields
M  ThrSaveSPAFields: not an update task, no update info saved
M  ThrSaveSPAFields: not a batch task, field zttabtckey not saved
M  ThCallHooks: hook >ThrSaveSPAFields< o.k.
M  ThCallHooks: call hook >ThrBtcCallLgCl< for event BEFORE_DUMP
M  ThCallHooks: hook >ThrBtcCallLgCl< o.k.
M  ThIErrHandle: entering ThSetStatError
M  ThIErrHandle: do a full roll out of T3/M0
M  ThRollOut: roll out T3/U4/M0/I1 (level=0x7, short_roll_out=0)
M    level=ROLLIN_0,ROLLIN_1,ROLLIN_2
M  ThRollOut: call ab_rollout
M  ThRollOut: call rrol_out
M  ThRollOut: act roll state = DP_ROLLED_OUT
M  ThRollOut: full roll out of U4 M0 I1 (Level 7) ok
M  ThIErrHandle: prv_action of W3: 0x8
C  -> SybBreak
C  SybBreak 0
B  db_sqlbreak() = 15
M  *** WARNING => ThCallDbBreak: db_sqlbreak failed (16388), caller=ThIErrHandle
M  ThIErrHandle: don't try rollback again
M  ThIErrHandle: do not call ThrCoreInfo (no_core_info=0, in_dynp_env=0)
M  ThIErrHandle: Entering ThReplyToMsg
M  ThIErrHandle: Entering ThErrHdlUser
M  ThErrHdlUser: set th_errno (5)
M  ThErrHdlUser: save context
M  ThEmContextDetach2: detach T3/M0 from em memory (em_hdl=0, force=0)
M  ThEmContextDetach2: reset local em info
M  call ThrShutDown ...
B  Disconnecting from ALL connections:
B  Wp  Hdl ConName          ConId     ConState     TX  BC  HC  PRM RCT FRC TIM MAX OPT Date     Time   DBHost           Program                                
B  003 000 R/3              000000000 ACTIVE       YES NO  NO  YES YES NO  000 255 255 20130330 131835 <hostname>                                                  
C  DbSlDisconnect hdl: 0
B  Disconnected from connection 0
B  statistics db_con_commit (com_total=2, com_tx=0)
B  statistics db_con_rollback (roll_total=1, roll_tx=1)
M  ***LOG Q02=> wp_halt, WPStop (Workp. 3 5936) [dpnttool.c   339]

---------------------------------------------------
trc file: "dev_w3", trc level: 1, release: "721"
---------------------------------------------------
*
*  ACTIVE TRACE LEVEL           1
*  ACTIVE TRACE COMPONENTS      all, MJ
*
M sysno      11
M sid        SM1
M systemid   562 (PC with Windows NT)
M relno      7210
M patchlevel 0
M patchno    100
M intno      20020600
M make       multithreaded, Unicode, 64 bit, optimized
M profile    \\<hostname>\sapmnt\SM1\SYS\profile\SM1_DVEBMGS11_<hostname>
M pid        3860
M
M  kernel runs with dp version 137000(ext=119000) (@(#) DPLIB-INT-VERSION-137000-UC)
M  length of sys_adm_ext is 588 bytes
M  ***LOG Q0Q=> tskh_init, WPStart (Workp. 3 3860) [dpxxdisp.c   1376]
I  MtxInit: 30000 0 0
M  DpSysAdmExtCreate: ABAP is active
M  DpSysAdmExtCreate: VMC (JAVA VM in WP) is not active
M  DpSysAdmExtCreate: J2EE Engine running with this server
M  DpIPCInit2: read dp-profile-values from sys_adm_ext
M  DpShMCreate: sizeof(wp_adm)  42864 (2256)
M  DpShMCreate: sizeof(tm_adm)  5517056 (27448)
M  DpShMCreate: sizeof(wp_ca_adm)  64000 (64)
M  DpShMCreate: sizeof(appc_ca_adm) 64000 (64)
M  DpCommTableSize: max/headSize/ftSize/tableSize=500/16/1384064/1384080
M  DpShMCreate: sizeof(comm_adm)  1384080 (2744)
M  DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0
M  DpShMCreate: sizeof(slock_adm)  0 (296)
M  DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0
M  DpShMCreate: sizeof(file_adm)  0 (80)
M  DpShMCreate: sizeof(vmc_adm)  0 (2160)
M  DpShMCreate: sizeof(wall_adm)  (41664/42896/64/192)
M  DpShMCreate: sizeof(gw_adm) 48
M  DpShMCreate: sizeof(j2ee_adm) 3952
M  DpShMCreate: SHM_DP_ADM_KEY  (addr: 000000000FC80050, size: 7174832)
M  DpShMCreate: allocated sys_adm at 000000000FC80060
M  DpShMCreate: allocated wp_adm_list at 000000000FC830B0
M  DpShMCreate: allocated wp_adm at 000000000FC832A0
M  DpShMCreate: allocated tm_adm_list at 000000000FC8DA20
M  DpShMCreate: allocated tm_adm at 000000000FC8DA70
M  DpShMCreate: allocated wp_ca_adm at 00000000101D0980
M  DpShMCreate: allocated appc_ca_adm at 00000000101E0390
M  DpShMCreate: allocated comm_adm at 00000000101EFDA0
M  DpShMCreate: system runs without slock table
M  DpShMCreate: system runs without file table
M  DpShMCreate: allocated vmc_adm_list at 0000000010341C40
M  DpShMCreate: system runs without vmc_adm
M  DpShMCreate: allocated gw_adm at 0000000010341CF0
M  DpShMCreate: allocated j2ee_adm at 0000000010341D30
M  DpShMCreate: allocated ca_info at 0000000010342CB0
M  DpShMCreate: allocated wall_adm at 0000000010342D40
M  DpCommAttachTable: attached comm table (header=00000000101EFDA0/ft=00000000101EFDB0)
M  DpRqQInit: use protect_queue / slots_per_queue 0 / 4001 from sys_adm
M  rdisp/queue_size_check_value :  -> on,50,30,40,500,50,500,80
X  EmInit: MmSetImplementation( 2 ).
X  MM global diagnostic options set: 0
X  <ES> client 3 initializing ....
X  <ES> EsILock: use spinlock for locking
X  Using implementation view
X  <EsNT> Using memory model view.
M  <EsNT> Memory Reset disabled as NT default
X  ES initialized.
X  mm.dump: set maximum dump mem to 96 MB
M  DpVmcSetActive: set vmc state DP_VMC_NOT_ACTIVE
M  ThStart: taskhandler started
M  ThStart: prv_action of W3 = 0x8
M  ThInit: initializing DIA work process W3
M  ThInit: found previous user T3/M0/U4
M 
M Sat Mar 30 13:19:10 2013
M  ThInit: running on host <hostname>
M 
M Sat Mar 30 13:19:11 2013
M  calling db_connect ...
B  Loading DB library 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll' ...
B  Library 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll' loaded
B  Version of 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll' is "721.02", patchlevel (0.110)
C  trace file set
C  Callback functions for dynamic profile parameter registered
B  con_info_ext_support(): DBSL supports extended connect protocol
B    ==> connect info for default DB will be read from ssfs
C  database is local: connect_timeout: 3
C  Thread ID:1284
C  lib_dbsl 721 03/06/2013 13:02:00
C  721_EXT build
C  Using dynamic link library 'E:\usr\sap\SM1\DVEBMGS11\exe\dbsybslib.dll'
C  dbsybslib.dll patch info
C    SAP patchlevel  0
C    SAP patchno  110
C    Last SYBASE DBSL patchlevel 0
C    Last SYBASE DBSL patchno         110
C    Last SYBASE DBSL patchcomment SYB: Set up SSL encryption for secondary SAP connections (1820202)
C  lpc:(local) connection used on <hostname>
C  lpc:(local) connection used on <hostname>
C  spid 174 comm rd ODBC
C  spid 175 unc rd ODBC
C  Driver: Adaptive Server Enterprise Driver release: 15.7.0.504
C  Driver full version: Sybase ASE ODBC Driver/15.7.0/ESD#5 N-OF/P/AMD64/Windows/drv157esd5/Build 504/64-bit/Normal/Thu Dec 20 UTC 21:52:52 2012/SQLLEN8
C  Driver uses SQLLEN8
C  GetServerInfo version: Adaptive Server Enterprise/15.7.0/EBF 21024 SMP ESD#04.1 /P/X64/Windows Server/ase157x/46/64-bit/OPT/Fri Jan 04 01:23:44 2013
C  GetServerInfo release: 15.7.0.041
C  GetServerInfo architecture: x86_64
C  GetServerInfo edition: P
C  GetServerInfo byte width: 64 bit
C  DbSlConnect hdl: 0
C  trace file set
B  Connection 0 opened (DBSL handle 0)
M  ThInit: db_connect o.k.
M  ICT: exclude compression: *.zip,*.rar,*.arj,*.z,*.gz,*.tar,*.lzh,*.cab,*.hqx,*.ace,*.jar,*.ear,*.war,*.css,*.pdf,*.gzip,*.uue,*.bz2,*.iso,*.sda,*.sar,*.gif,*.png,*.swc,*.swf
I  MtxInit: 3 0 0
M  SHM_PRES_BUF   (addr: 0000000016BE0050, size: 4400000)
M  SHM_ROLL_AREA  (addr: 000007FFD1730050, size: 268435456)
M  SHM_PAGING_AREA  (addr: 0000000017020050, size: 134217728)
M  SHM_ROLL_ADM   (addr: 000000001F030050, size: 2767448)
M  SHM_PAGING_ADM  (addr: 0000000003240050, size: 787488)
M  ThCreateNoBuffer  allocated 548152 bytes for 1000 entries at 00000000029C0050
M  ThCreateNoBuffer  index size: 3000 elems
M  ThCreateVBAdm  allocated 31056 bytes (50 server) at 0000000002900050
X  EmInit: MmSetImplementation( 2 ).
X  MM global diagnostic options set: 0
X  <ES> client 3 initializing ....
X  Using implementation view
X  ES initialized.
X  mm.dump: set maximum dump mem to 96 MB
B  db_con_shm_ini:  WP_ID = 3, WP_CNT = 19, CON_ID = -1
B  dbtbxbuf: Buffer TABL  (addr: 0000000026270160, size: 100000000, end: 000000002C1CE260)
B  dbtbxbuf: Buffer TABLP (addr: 000000002C1D0160, size: 61440000, end: 000000002FC68160)
B  dbsync[db_syinit]: successfully attached to shared memory, sync_adm_p = 0000000002910050
B  dbsync[db_syinit]: Buffer synchronisation started with
B    sync_concept      = SEQ_NR
B    sendon            = 1
B    bufreftime        = 120
B    max_gap_wait_time = 60
B    ddlog_del_time    = 60
B    last_counter      = -2147483648
B    oldest_gap        = (2147483647,00000000000000)
B    time_of_last_sync = 20130330131837
B    MySysId           = '<hostname>              11'
B  dbexpbuf[EXP_SHB]: buffer EIBUF installed with
B    semkey             = 35
B    shmkey             = 54
B    wp_n               = 19
B    sclass             = 0
B    block_length       = 256
B    max_objects        = 20000
B    max_obj_size       = 10919200
B    pref_obj_size      = 0
B    est_large_obj_size = 16384
B    free_vec_lg        = 65
B    hash_vec_size      = 40009
B    buffer_l           = 51200000
B    max_blocks         = 170615
B    free_blocks        = 170577
B    mutex_n            = 40009
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
B  dbexpbuf[EXP_SHM]: buffer ESM   installed with
B    semkey             = 56
B    shmkey             = 65
B    wp_n               = 19
B    sclass             = 0
B    block_length       = 256
B    max_objects        = 2000
B    max_obj_size       = 859872
B    pref_obj_size      = 0
B    est_large_obj_size = 16384
B    free_vec_lg        = 65
B    hash_vec_size      = 4001
B    buffer_l           = 4194304
B    max_blocks         = 13438
B    free_blocks        = 13438
B    mutex_n            = 4001
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
B  dbexpbuf[EXP_CUA]: buffer CUA   installed with
B    semkey             = 30
B    shmkey             = 47
B    wp_n               = 19
B    sclass             = 10
B    block_length       = 512
B    max_objects        = 5000
B    max_obj_size       = 2088928
B    pref_obj_size      = 0
B    est_large_obj_size = 98304
B    free_vec_lg        = 193
B    hash_vec_size      = 10007
B    buffer_l           = 10240000
B    max_blocks         = 16321
B    free_blocks        = 16311
B    mutex_n            = 10007
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
B  dbexpbuf[EXP_OTR]: buffer OTR   installed with
B    semkey             = 55
B    shmkey             = 64
B    wp_n               = 19
B    sclass             = 13
B    block_length       = 256
B    max_objects        = 2000
B    max_obj_size       = 859872
B    pref_obj_size      = 0
B    est_large_obj_size = 20480
B    free_vec_lg        = 81
B    hash_vec_size      = 4001
B    buffer_l           = 4194304
B    max_blocks         = 13438
B    free_blocks        = 13438
B    mutex_n            = 4001
B    max_mtx_wait_time  = 17000
B    recovery_delay     = 500000
B    tracing            = 0
B    force_checks       = 0
B    protect_shm        = 0
I  MPI: dynamic quotas disabled.
I  MPI init: pipes=4000 buffers=1279 reserved=383 quota=10%
M  rdisp/thwpsf_critical_path : -1 -> 0
M  CCMS: SemInMgt: Semaphore Management initialized by AlAttachShm_Doublestack.
M  CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.
G  RelWritePermissionForShm( pLocation = 120, pEnforce = 0 )
G  GetWritePermissionForShm( pLocation =  99, pEnforce = 1 )
G  RelWritePermissionForShm( pLocation = 100, pEnforce = 1 )
S  *** init spool environment
S  TSPEVJOB updates outside critical section: event_update_nocsec = 1
S  initialize debug system
T  Stack direction is downwards.
T  debug control: prepare exclude for printer trace
T  new memory block 00000000165D2B30
S  spool kernel/ddic check: Ok
S  using table TSP02FX for frontend printing
S  1 spool work process(es) found
S  frontend print via spool service enabled
S  printer list size is 150
S  printer type list size is 50
S  queue size (profile)   = 300
S  hostspool list size = 3000
S  option list size is 30
S      found processing queue enabled
S  found spool memory service RSPO-RCLOCKS at 000000003D2300D0
S  doing lock recovery
S  setting server cache root
S  found spool memory service RSPO-SERVERCACHE at 000000003D2306A0
S    using messages for server info
S  size of spec char cache entry: 297032 bytes (timeout 100 sec)
S  size of open spool request entry: 2512 bytes
S  immediate print option for implicitely closed spool requests is disabled
A  ***GENER* Trace switched on ***
A 
A  ---PXA-------------------------------------------
A  PXA INITIALIZATION
A  PXA: Locked PXA-Semaphore.
A  System page size: 4kb, total admin_size: 37312kb, dir_size: 36520kb.
A  Attached to PXA (address 000007FFE1760050, size 500000K, 1 fragments of 462688K )
A  PXA allocated (address 000007FFE1760050, size 500000K)
A  abap/pxa = shared protect gen_remote
A  PXA: checking structure sizes: 752|272|16
A  PXA INITIALIZATION FINISHED
A  ---PXA-------------------------------------------
A 
A  ATRA: pfclock execution time = 0
A  abap/force_local_update_task=0
A  ABAP ShmAdm attached (addr=000007DFDA15F000 leng=104841216 end=000007DFE055B000)
A  >> Shm MMADM area (addr=000007DFDB929780 leng=1229120 end=000007DFDBA558C0)
A  >> Shm MMDAT area (addr=000007DFDBA56000 leng=78663680 end=000007DFE055B000)
A  RFC rfc/signon_error_log = -1
A  RFC rfc/dump_connection_info = 0
A  RFC rfc/dump_client_info = 0
A  RFC rfc/cp_convert/ignore_error = 1
A  RFC rfc/cp_convert/conversion_char = 23
A  RFC rfc/wan_compress/threshold = 251
A  RFC rfc/recorder_pcs not set, use defaule value: 2
A  RFC rfc/delta_trc_level not set, use default value: 0
A  RFC rfc/no_uuid_check not set, use default value: 0
H  HTTP> Parameter icf/ssocookie_mandatory set to 0
B  dbtran INFO (init_connection '<DEFAULT>' [SYBASE:721.02]):
B   max_blocking_factor       =  50,  min_blocking_factor         =   1,
B   max_in_blocking_factor    = 128,  min_in_blocking_factor      =   1,
B   max_union_blocking_factor =  50,  min_union_blocking_factor   =   1,
B   prefer_union_all          =   0,  prefer_join                 =   0,
B   prefer_fix_blocking       =   0,  prefer_in_itab_opt          =   1,
B   convert AVG               =   1,  alias table FUPD            =   0,
B   escape_as_literal         =   0,                                  
B   select *                  =0x0f,  character encoding          = STD / []:X,
B   use_hints                 = abap->1, dbif->0x1, upto->0
M  ThrCreateShObjects  allocated 39514 bytes at 0000000002920050
Y  dyWpInit
Y    ztta/dynpro_ara 800000
Y    ztta/cua_ara    500000
Y    ztta/diag_ara   250000
A  *** ERROR => ab_newmode: EmSetMmxUsageStatRef() failed 7. [abinit.c     755]
A  {root-id=00155D0502241EE2A6A1C80F354A4EE8}_{conn-id=00000000000000000000000000000000}_0

N  SsfSapSecin: getenv(SECUDIR)=="E:\usr\sap\SM1\DVEBMGS11/sec"
N 
N  =================================================
N  === SSF INITIALIZATION:
N  ===...SSF Security Toolkit name SAPSECULIB .
N  ===...SSF library is E:\usr\sap\SM1\DVEBMGS11\exe\sapcrypto.dll .
N  ===...SSF default hash algorithm is SHA1 .
N  ===...SSF default symmetric encryption algorithm is DES-CBC .
N  ===...SECUDIR="E:\usr\sap\SM1\DVEBMGS11/sec"
N  ===...loading of Security Toolkit successfully completed.
N  ===   SAPCRYPTOLIB  5.5.5C pl32  (Apr  2 2011) MT-safe
N  =================================================
N 
N Sat Mar 30 13:19:13 2013
N  MskiInitLogonTicketCacheHandle: Logon Ticket cache pointer retrieved from shared memory.
N  MskiInitLogonTicketCacheHandle: Workprocess runs with Logon Ticket cache.
M  JrfcVmcRegisterNativesDriver o.k.
W  =================================================
W  === ipl_Init() called
W    ITS Plugin: Path dw_gui
W    ITS Plugin: Description ITS Plugin - ITS rendering DLL
W    ITS Plugin: sizeof(SAP_UC) 2
W    ITS Plugin: Release: 721, [7210.0.100.20020600]
W    ITS Plugin: Int.version, [34]
W    ITS Plugin: Feature set: [31]
W    ===... Calling itsp_Init in external dll ===>
W      PpioRecoverLocks, table: 000007DFE0820920
W      PpioRecoverLocks, number of file locks 256
W      PpioRecoverLocks: file lock set to: 0000000000000000
W      PpioRecoverLocks: directory lock set to: 0000000000000000
W      PpioRecoverLocks: global lock set to: 0000000000000000
W      PpioRecoverLocks() done
W      PprcRecoverLocks, table: 000007DFE0820E10
W      PprcRecoverLocks: directory lock set to: 0000000000000000
W      PprcRecoverLocks() done
W  === ipl_Init() returns 0, ITSPE_OK: OK
W  =================================================
N  SignInit: successfully obtained handle for Security Context cache
N  SignInit: Not attempted to load Kerberos Library (ABAP parts missing, note TODO)
N  VSI: WP init in ABAP VM completed with rc=0
E  EnqLockTableSizeCalculate: session quota = 100%
E  EnqLockTableAttach2: attach to lock table (size = 33554432)
E  EnqLockTableMapToLocalContext: enque/use_pfclock2 = FALSE
E  EnqId_Initialize: local EnqId initialization o.k.
M  ThCheckPrevUser: previous user was T3/M0, clean counter 0
M  ThCheckPrevUser: clean previous user T3/U4/M0/I1/no VM
M 
M  Modeinfo for User T3/M0
M 
M    tm state = 2
M    uid = 4
M    term type = 0x2
M    display = 0x0
M    cpic_no = 0
M    cpic_idx = -1
M    usr = >SAPSYS      <
M    terminal = >                    <
M    client = >000<
M    conversation_ID = >        <
M    appc_tm_conv_idx = -1
M    its_plugin = NO
M    allowCreateMode = YES
M    blockSoftCanel = NO
M     Session Resources:
M  HEAD: adr/max_elems/act_elems/tbl = 000000000FCA8590/0/0/0000000000000000
M    imode = 1
M    mode state = 0x42
M    task_type = ZTTADELAY
M    lastThFc = THFCDELAY
M    lastAction = TH_IACT_NO_ACTION
M    th_errno = Internal TSKH error, session terminated (5)
M    rollout_reason = 0
M    last_rollout_level = 7
M    async_receives = 0
M    cpic_receive = 0
M    em handle = 0
M    roll state = DP_ROLLED_OUT
M    abap state = DP_ROLLED_OUT
M    em state = DP_EM_DETACHED
M    eg state = DP_EM_NO_CONTEXT
M    spa state = DP_SPA_INITIALIZED
M    enq state = 0
M    softcancel = 0
M    cancelInitiator = ?
M    clean_state = DP_DEFAULT_CLEANING
M    next hook = T-1/U-1/M255
M    master hook = T-1/U-1/M255
M    slave hook = T-1/U-1/M255
M    debug_tid = -1
M    debug_mode = 255
M    mode type = 0x1
M    debug = DP_DEBUGGING_OFF
M    msg_count = 1
M    tcode = >                    <
M    act_wp = 3
M    last_wp = 3
M    client conversation_ID = >        <
M    server conversation_ID = >        <
M    lock = 0
M    max enq infos = 0
M    act enq infos = 0
M    em_hyper_hdl = 000007DFE0821570
M    plugin_info = NULL
M    act_plugin_hdl = -1
M    act_plugin_no  = 0
M    max_plugin_no  = 0
M    Context Resources:
M  HEAD: adr/max_elems/act_elems/tbl = 000000000FCA2078/0/0/0000000000000000
M 
M  ThCheckPrevUser: reset spa state for user T3/U4/M0
M  DpLockWpBlkEx: lock wp ca_blk 260
M  DpVmcGetVmByTmAdm: no VM found for T3/M0/I1 (locked VMs ignored)
M  DpLockAppcBlk: lock appc ca_blk 260
M  ThCheckPrevUser: th_step = TH_WORK_SYNC
M  set task type ZTTADIA
M  ThCleanPrevUser: clean U4 T3 M0 I1 no VM clean state DP_DEFAULT_CLEANING clean counter 1
M  ThCleanPrevUser: saved MODE_REC = MODE_REC_SYS
M  PfStatDisconnect: disconnect statistics
M  ThCleanPrevUser: prv_action of W3: 0x0
M  ThRollIn: roll in T3/U4/M0/I1 (level=0x7, abap_level=1, attach_em=1)
M    level=ROLLIN_0,ROLLIN_1,ROLLIN_2
M    abap level=TH_ABAP_ROLL_IN_STORE
M  ThCheckEmState: check ATTACH for em hdl 0
M  ThCheckEmState: call EmContextAttach (em_hdl=0)
M  ThRollIn: U4 M0 I1 (Level 7) ok
M  ThRollIn: roll in abap store
M  DpVmcGetVmByTmAdm: no VM found for T3/M0/I1
M  DpVmcGetVmByTmAdm: no VM found for T3/M0/I1 (locked VMs ignored)
M  ThIAMDel: delete tid/mode 3/0 (th_errno 23, release 1)
M  ThTSCancel: set T3/M0 in status MODE_CANCEL
M  ThIDeleteMode (3, 0, 3, ><, 0, 255, TRUE)
M  ThIDeleteMode: no modes found ..
M    no sub modes
M  ThSUsrDel: delete system user
M  RstrNotifyUserChange: user/client = (SAPSYS      /000)
M  ThResFree: free resources of U4 M0 I1 (normal mode, complete free) at level 3, errno=23, db_action=TH_DB_NO_ACTION
M  ThResFree: reset spa state for user T3/U4/M0
M  ThCallHooks: call hook >ITSP: EndOfSession< for event BEFORE_SESSION_CANCEL
M  ThCallHooks: hook >ITSP: EndOfSession< o.k.
M  ThCallHooks: call hook >abTpdaThHook_EModeEnded< for event BEFORE_SESSION_CANCEL
M  ThCallHooks: hook >abTpdaThHook_EModeEnded< o.k.
M  ThCallHooks: call hook >abRtmClearSession< for event BEFORE_SESSION_CANCEL
M  ThCallHooks: hook >abRtmClearSession< o.k.
M  ThCallHooks: call hook >HttpClearSession< for event BEFORE_SESSION_CANCEL
M  ThCallHooks: hook >HttpClearSession< o.k.
M  ThCallHooks: call hook >SpoolHandleHook< for event BEFORE_SESSION_CANCEL
M  ThCallHooks: hook >SpoolHandleHook< o.k.
M  PfStatDisconnect: disconnect statistics
M  ThDealComm: T3/U4/M0 has no cpic conns
M  clean up all internal modes of T3/U4/M0
M  ThVmcCleanImode (deleteOfMainMode=1, AoS=0)
M  DpVmcGetVmByTmAdm: no VM found for T3/M0/I1 (locked VMs ignored)
M  ThSesGetSessionType: no associated entry
M  ThSesIsJavaSessionId: no associated entry
M  ThVmcCleanImode o.k.
M  call ab_endImode
M  ThIEmFree: call EmContextPop
M  ThRollIn: roll in T3/U4/M0/I0 (level=0x7, abap_level=1, attach_em=0)
M    level=ROLLIN_0,ROLLIN_1,ROLLIN_2
M    abap level=TH_ABAP_ROLL_IN_STORE
M  ThRollIn: U4 M0 I0 (Level 7) ok
M  ThRollIn: switch spaterm/spauid 3/4 -> 3/4
M  ThRollIn: roll in abap store
M  ThCleanImodeForIdx: no VM
M  call ab_endmode (END_MODE_CLEAR_ALL)
M  RstrNotifyUserChange: user/client = (SAPSYS      /000)
M  ***LOG R47=> ThResFree, delete (001023) [thxxmode.c   1339]
M  ThGetTransactionId: no transaction id found
M  ThGetEnqInfo: no enq info found
M  ThVBIRollBack: initialize TH-hooks
M  SosRegisterHook: add hook >ThVBICmRbHook(000000013FB25410)< (condition/order=0xf/0) for event AFTER_COMMIT
M  SosRegisterHook: add hook >ThVBICmRbHook(000000013FB25410)< (condition/order=0xf/0) for event AFTER_ROLLBACK
M  ThRmLocQueue: no async request found
M  ThPageFree: free (4,0,1, mode = 3)
M  ThRollFree: free (4,0,1, mode = 3)
M  ThRmFree:  cleanup resources for T3 M0
M  RmCleanUpResources3: hdr/tbl/ext_tbl/free/keep = 000000000FCA2078/0000000000000000/0/1/0
M  RmCleanUpResources3: no resources registered
M  ThRmFree: reset eg state of T3 M0
M  ThRmFree cleanup resources for T3
M  RmCleanUpResources3: hdr/tbl/ext_tbl/free/keep = 000000000FCA8590/0000000000000000/1/1/0
M  RmCleanUpResources3: no resources registered
M  ThRmFree: reset eg state of T3
M  ThIEmFree: delete hyper context (hyperHdl=000007DFE0821570)
M  ThIEmFree: call EmContextDelete (em_hdl=0)
M  ThResFree: save spa pointer 000007E00001C1A0 and install dummy spa
M  ThCheckComOrRb (event=1, full_commit=0)
M  ThCallHooks: skip hook >ab_SosRfcBeforeCommit< for event BEFORE_COMMIT, (condition IF_ROLLED_IN not true)
M  ThCallHooks: skip hook >ASTAT-collect commit handling< for event BEFORE_COMMIT, (condition 0xf not true)
M  ThCallHooks: call hook >ASTAT-collect commit handling< for event BEFORE_COMMIT
M  ThCallHooks: hook >ASTAT-collect commit handling< o.k.
M  ThCallHooks: call hook >rstshpc_hook_prepare_for_commit< for event BEFORE_COMMIT
M  ThCallHooks: hook >rstshpc_hook_prepare_for_commit< o.k.
M  ThCheckComOrRb (event=3, full_commit=0)
M  ThCallHooks: call hook >ThVBICmRbHook< for event AFTER_COMMIT
M  ThVBICmRbHook: called for commit
M  ThCallHooks: hook >ThVBICmRbHook< o.k.
M  ThCallHooks: call hook >dyKeyTableRest< for event AFTER_COMMIT
M  ThCallHooks: hook >dyKeyTableRest< o.k.
M  ThCallHooks: call hook >rstshwc_hook_was_commit< for event AFTER_COMMIT
M  ThCallHooks: hook >rstshwc_hook_was_commit< o.k.
M  ThCallHooks: call hook >ThNoClearPrevErr< for event AFTER_COMMIT
M  ThNoClearPrevErr: clear prev no err
M  ThCallHooks: hook >ThNoClearPrevErr< o.k.
M  ThCallHooks: call hook >SpoolHandleHook< for event AFTER_COMMIT
M  ThCallHooks: hook >SpoolHandleHook< o.k.
M  ThSetThParam : rdisp/thsend_order=0
M  rdisp/thsend_order : -1 -> 0
M  ThISend: set thsend_order to 0
M  ThISend: (T3/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 T3/M0
M  ThRqOutCheck: o.k.
M  ThISend: allowed rq_type of T3/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 T3/M0 = MODE_WAIT,MODE_CANCEL,MODE_REC_SYS
M  ThNewWpStat: new wp stat: 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: send output to canceled mode
M  ThMkReq: detach wp_ca_blk 260
M  ThDtWpBlk: detach WP ca_blk 260
M  ThISend: complete stat record
M  ThCallHooks: call hook >TrThHookFunc< for event COMPLETE_STAT_RECORD
M  TrThHookFunc: called for complete stat rec
M  ThGetTransactionId: no transaction id found
M  TrIGetTransactionId: no transaction-ID received (3)
M  ThCallHooks: hook >TrThHookFunc< o.k.
M  ThISend: stat_state = TH_STAT_WRITE
M  ThSysUsrCheck (1,1): check system user T3/U4/M0
M  ThIEmFree: em state of T3/M0 (em_hdl=-1) is DP_EM_NO_CONTEXT
M  ThSysUsrCheck: clear T3
M  DpITmSlotClear: clear slot T3
M  ThISend: th_wp_waits_for_rq = TH_WP_WAITS_FOR_RQ
M  make DISP owner of wp_ca_blk 260
M  DpRqIPutIntoQ: put elem in queue NOWP, elems=1
M  DpRqPutIntoQueue: put request into queue (reqtype 0, prio LOW, rq_id 82)
M  -OUT- sender_id WORK_PROCESS      tid  3     wp_ca_blk   260     wp_id 3
M  -OUT- action    SEND_TO_DP        uid  4     appc_ca_blk -1      type  NOWP
M  -OUT- new_stat  WP_WAIT           mode 0     len         8       rq_id 82
M  -OUT- req_info  CANCELMODE
M  ThCheckBlks: th_step = TH_WORK_NOSYNC
M  ThCheckBlks: release appc_ca_blk 260
M  REL APPC ca_blk 260
M  ThDtAppcBlk: detach APPC ca_blk 260
M  PfClearAllRec: clear statistic area
M  PfStatDisconnect: disconnect statistics
M  ThISend: set inPrivMode = 0 for T3/M0
M  ThResetActWp: reset act_wp for T3/M0
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 T3/M0
M  ThISend: jump back with rc TH_WAIT_FOR_REQUEST
M  TskhLoop: switch back to normal tracel level 1
E 
E Sat Mar 30 13:19:30 2013
E  Enqueue Info: enque/use_pfclock2 = FALSE
E  Enqueue Info: row condense enabled
A 
A Sat Mar 30 13:19:35 2013
A  UpdateProfile (new settings): off,krn_impl,(no dyn check),(no logging),(no stat err),(local generation),(no commit)
A  UpdateProfile (new exceptions): (tmp_err),(home_err),(local_err)
A  UpdateProfile (new version): 0
A  ***GENER* Trace switched off ***
B  dbdynpdb2: no VERSION column found in table DYNPSOURCE
G  GetWritePermissionForShm( pLocation = 281, pEnforce = 0 )
G  RelWritePermissionForShm( pLocation = 277, pEnforce = 0 )
M  SecAudit(rsauinit): WP attached to existing shared memory.
M  SecAudit(RsauShmInit): addr of SHM for Audit.. = 0000000002A50050
M  SecAudit(RsauShmInit): addr of RSAUSHM........ = 0000000002A51050
M  SecAudit(RsauShmInit): addr of RSAUSLOTINFO... = 0000000002A51660
M  SecAudit(RsauShmInit): addr of RSAUSLOTS...... = 0000000002A51670
G  GetWritePermissionForShm( pLocation = 281, pEnforce = 0 )
G  RelWritePermissionForShm( pLocation = 277, pEnforce = 0 )
B 
B Sat Mar 30 13:19:36 2013
B  dbrda: Redirect database access is disabled via rsdb/rda profile parameter setting.
M  Deactivate ASTAT hyper index locking
S 
S Sat Mar 30 13:19:49 2013
S  found spool memory service RSPO-ACTIONS at 000000003D23A3E0

Thank You,

Sreedhar

[sap_mk · Accepted solution]

This message is from the log you posted:

[Adaptive Server Enterprise]ASE is terminating this process

When you see that message, there should be a stacktrace in the errorlog that shows more details about the error. The timeframe to check is Sat Mar 30 13:18:48 2013.

Regards,

Mark Kusma

[JanStallkamp]

Hi.

Please check the system log (SM21) and short dumps (ST22). There should be some entries there that might bring some more insight into this.

Regards,

Jan