cancel
Showing results for 
Search instead for 
Did you mean: 

EhP1 installer error (RFC of "SUBST_START_REPORT_IN_BATCH" failed)

Former Member
0 Kudos

Hi,

we are trying to upgrade our CRM 7.0 to the EhP1 and the EhP1 installer is blocked with the error:


Checks after phase MAIN_SHDRUN/ALTNTAB_FILL were negative!
Last error code set: RFC call to SUBST_START_REPORT_IN_BATCH failed with key RFC_ERROR_SYSTEM_FAILURE (SYSTEM_FAILURE): connection closed without message (CM_NO_DATA_RECEIVED)

Log files report the following errors:


(altntfi.sav)
#--------------------------------------------------------------
#---- MASKING file ALTNTFI.LOG from G:\usr\sap\CR7\EHPI\abap\log
#---- TIME: 20111125115607  PHASE: ALTNTAB_FILL
#--------------------------------------------------------------
1 ETQ201 Entering upgrade-phase "ALTNTAB_FILL" ("20111125111509")
4 ETQ399 Set environment for standard connect:
2 ETQ367 Connect variables are set for standard instance access
4 ETQ399 System-nr = '02', GwService = 'sapgw02'
4 ETQ399 Environment variables:
4 ETQ399   dbs_ora_schema=SAPSR4
4 ETQ399   auth_shadow_upgrade=0
1 ETQ200 Executing actual phase 'MAIN_SHDRUN/ALTNTAB_FILL'.
1 ETQ399 Phase arguments:
2 ETQ399 Arg[0] = '30'
2 ETQ399 Arg[1] = 'ALTNTFI.ELG'
4 ETQ399 Set environment for shadow connect:
4 ETQ399 Set RFC variables for shadow connect:
4 ETQ399 System-nr = '07', GwService = 'sapgw07'
4 ETQ380 computing toolpath for request "TP_SHADOW_CONNECT"
4 ETQ381 request "TP_SHADOW_CONNECT" means "tp needs to connect to shadow system"
4 ETQ382 translates to group "R3UP_TOOL_GROUP_NEW"
4 ETQ383 translates to path "exe"
4 ETQ383 translates to path "exe"
4 ETQ399 Set tool parameters for shadow connect:
4 ETQ399   default TPPARAM: SHADOW.TPP
4 ETQ010 Date & Time: 20111125111509  
4 ETQ265 Starting report "RDDNT2MT" with variant "SAP_INIT" in batch
4 ETQ359 RFC Login to: System="CR7", Nr="07", GwHost="SYSKCRMDEV1", GwService="sapgw07"
4 ETQ232 RFC Login succeeded
4 ETQ233 Calling function module "SUBST_START_REPORT_IN_BATCH" by RFC
2EETQ360 RFC of "SUBST_START_REPORT_IN_BATCH" failed:
2EETQ361 code/exception  : SYSTEM_FAILURE
2EETQ362 key             : RFC_ERROR_SYSTEM_FAILURE
2EETQ364 message         : 
2EETQ399 connection closed without message (CM_NO_DATA_RECEIVED)
4 ETQ359 RFC Login to: System="CR7", Nr="07", GwHost="SYSKCRMDEV1", GwService="sapgw07"
4 ETQ232 RFC Login succeeded
2EETG894 Exception "SYSTEM_FAILURE" raised, write variant failed with error code -1
2EETG890 Exception "SYSTEM_FAILURE" raised, job scheduling failed with error code -1
2EETQ261 Start of batchjob "RDDNT2MT" failed
4 ETQ010 Date & Time: 20111125111548  

We have logged through GUI to the shadow instance but we can't test the function module SUBST_START_REPORT_IN_BATCH: the shadow instance continuously dumps (in SE37, ST22, ST11, SU01... with dumps LOAD_PROGRAM_CLASS_MISMATCH, GETWA_WRONG_LENGTH, LOAD_TYPE_VERSION_MISMATCH...). When I don't have a dump (i.e. I try to activate the function module in SE37 without opening it), the GUI closes with the error: "Work process restarted, session terminated " and in the logfile dev_disp shows me:


Fri Nov 25 11:15:30 2011
*** ERROR => DpHdlDeadWp: W5 (pid 17996) died (severity=0, status=0) [dpxxwp.c     1522]

Any help would be greatly appreciated.

Thanks,

laura.

Accepted Solutions (0)

Answers (1)

Answers (1)

Former Member
0 Kudos

In the dev_w0 we have:


---------------------------------------------------
trc file: "dev_w0", trc level: 1, release: "720"
---------------------------------------------------
*
*  ACTIVE TRACE LEVEL           1
*  ACTIVE TRACE COMPONENTS      all, MJ
*
M sysno      07
M sid        CR7
M systemid   562 (PC with Windows NT)
M relno      7200
M patchlevel 0
M patchno    105
M intno      20020600
M make       multithreaded, Unicode, 64 bit, optimized
M profile    G:\usr\sap\CR7\EHPI\abap\CR7\SYS\profile\CR7_DVEBMGS07_SYSKCRMDEV1
M pid        20192
M 
M  kernel runs with dp version 131000(ext=118000) (@(#) DPLIB-INT-VERSION-131000-UC)
M  length of sys_adm_ext is 588 bytes
M  ***LOG Q0Q=> tskh_init, WPStart (Workp. 0 20192) [dpxxdisp.c   1349]
I  MtxInit: 30000 0 0
M  
M Fri Nov 25 11:53:24 2011
M  DpSysAdmExtCreate: ABAP is active
M  DpSysAdmExtCreate: VMC (JAVA VM in WP) is not active
M  DpIPCInit2: read dp-profile-values from sys_adm_ext
M  DpShMCreate: sizeof(wp_adm)		54336	(2264)
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/584064/584080
M  DpShMCreate: sizeof(comm_adm)		584080	(1144)
M  DpSlockTableSize: max/headSize/ftSize/fiSize/tableSize=0/0/0/0/0
M  DpShMCreate: sizeof(slock_adm)		0	(104)
M  DpFileTableSize: max/headSize/ftSize/tableSize=0/0/0/0
M  DpShMCreate: sizeof(file_adm)		0	(80)
M  DpShMCreate: sizeof(vmc_adm)		0	(2152)
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: 000000000D880050, size: 6386192)
M  DpShMCreate: allocated sys_adm at 000000000D880060
M  DpShMCreate: allocated wp_adm_list at 000000000D883040
M  DpShMCreate: allocated wp_adm at 000000000D883230
M  DpShMCreate: allocated tm_adm_list at 000000000D890680
M  DpShMCreate: allocated tm_adm at 000000000D8906D0
M  DpShMCreate: allocated wp_ca_adm at 000000000DDD35E0
M  DpShMCreate: allocated appc_ca_adm at 000000000DDE2FF0
M  DpShMCreate: allocated comm_adm at 000000000DDF2A00
M  DpShMCreate: system runs without slock table
M  DpShMCreate: system runs without file table
M  DpShMCreate: allocated vmc_adm_list at 000000000DE813A0
M  DpShMCreate: system runs without vmc_adm
M  DpShMCreate: allocated gw_adm at 000000000DE81450
M  DpShMCreate: allocated j2ee_adm at 000000000DE81490
M  DpShMCreate: allocated ca_info at 000000000DE82410
M  DpShMCreate: allocated wall_adm at 000000000DE824A0
M  DpCommAttachTable: attached comm table (header=000000000DDF2A00/ft=000000000DDF2A10)
M  DpRqQInit: use protect_queue / slots_per_queue 0 / 2001 from sys_adm
M  rdisp/queue_size_check_value :  -> on,50,30,40,500,50,500,80
X  EmInit: MmSetImplementation( 2 ).
X  MM global diagnostic options set: 0
X  <ES> client 0 initializing ....
X  <ES> EsILock: use spinlock for locking
X  Using implementation view
X  <EsNT> Using memory model view.
M  <EsNT> Memory Reset disabled as NT default
X  ES initialized.
X  mm.dump: set maximum dump mem to 96 MB
M  DpVmcSetActive: set vmc state DP_VMC_NOT_ACTIVE
M  ThStart: taskhandler started
M  ThInit: initializing DIA work process W0
M  


M Fri Nov 25 11:53:29 2011
M  ThInit: running on host SYSKCRMDEV1
M  
M Fri Nov 25 11:53:49 2011
M  calling db_connect ...
B  Loading DB library 'G:\usr\sap\CR7\EHPI\abap\exe\dboraslib.dll' ...
B  Library 'G:\usr\sap\CR7\EHPI\abap\exe\dboraslib.dll' loaded
B  Version of 'G:\usr\sap\CR7\EHPI\abap\exe\dboraslib.dll' is "720.00", patchlevel (0.102)
C  Prepending C:\oracle\product\10.2.0\db_1 to Path.
C  Register application info.
C  Oracle client version: 10.2.0.2.0, (dbsl 720 260811, UNICODE[2])
C  Detailed Oracle client (patch) info not available.
C  Client NLS setting (by OCINlsGetInfo(con=0)): 'AMERICAN_AMERICA.UTF16'
C  Logon as OPS$-user to get SAPSR4SHD's password
C  Connecting as /@CR0 on connection 0 (nls 0) ... (dbsl 720 260811, UNICODE[2])
C  Attaching to database server CR0 (con=0, svchp=02D50138, srvhp=1298C918)
C  Starting user session: OCISessionBegin(con=0, usr='/', svc=02D50138, srv=1298C918, usr=02D4F268)
C  Now '/@CR0' is connected: con=0, nls=0, session_id=267.
C  Got SAPSR4SHD's password from OPS$-user
C  Disconnecting from connection 0 ...
C  Closing user session (con=0, svc=02D50138, usr=02D4F268)
C  Disconnected (con=0) from ORACLE.
C  Connecting as SAPSR4SHD/<pwd>@CR0 on connection 0 (nls 0) ... (dbsl 720 260811, UNICODE[2])
C  Starting user session: OCISessionBegin(con=0, usr='SAPSR4SHD', svc=02D50138, srv=1298C918, usr=02D4F268)
C  Now 'SAPSR4SHD/<pwd>@CR0' is connected: con=0, nls=0, session_id=267.
C  con=0, V$NLS_PARAMETERS: NLS_LANG=AMERICAN_AMERICA.UTF8, NLS_NCHAR=UTF8
C  Nls CharacterSet                      NationalCharSet                   EnvHp            ErrHp            ErrBt           
C    0 UTF16                             AL16UTF16                         129848C0         02D4EA50         02D50278        
C  DB instance CR0 is running on SYSKCRMDEV1 with ORACLE version 10.2.0.4.0 since NOV 25, 2011, 11:50:59.
B  Connection 0 opened (DBSL handle 0)
M  


M Fri Nov 25 11:53:50 2011
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 Fri Nov 25 11:54:01 2011
I  MtxInit: 0 0 0
M  SHM_PRES_BUF			(addr: 0000000015580050, size: 4400000)
M  SHM_ROLL_AREA		(addr: 000007FFDDA80050, size: 268435456)
M  SHM_PAGING_AREA		(addr: 00000000159C0050, size: 134217728)
M  SHM_ROLL_ADM			(addr: 000000001D9D0050, size: 2767448)
M  SHM_PAGING_ADM		(addr: 000000001DC80050, size: 787488)
M  ThCreateNoBuffer		allocated 548152 bytes for 1000 entries at 000000001DD50050
M  ThCreateNoBuffer		index size: 3000 elems
M  ThCreateVBAdm		allocated 30976 bytes (50 server) at 000000001DDE0050
X  EmInit: MmSetImplementation( 2 ).
X  MM global diagnostic options set: 0
X  <ES> client 0 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 = 0, WP_CNT = 24, CON_ID = -1
B  dbtbxbuf: Buffer TABL  (addr: 0000000021F00160, size: 30000000, end: 0000000023B9C4E0)
B  dbtbxbuf: Buffer TABLP (addr: 0000000023BA0160, size: 10240000, end: 0000000024564160)
B  dbsync[db_syinit]: successfully attached to shared memory, sync_adm_p = 0000000024570050
B  dbsync[db_syinit]: Buffer synchronisation started with
B    sync_concept      = SEQ_NR
B    sendon            = 1
B    bufreftime        = 120
B    del_ddlog         = 1
B    last_counter      = -2147481170
B    time_of_last_sync = 20111125115353
B    MySysId           = 'SYSKCRMDEV1         07'
B  dbexpbuf: Buffer EIBUF (addr: 0000000024580170, size: 4194304, end: 0000000024980170)
B  dbexpbuf: Buffer ESM   (addr: 0000000024990170, size: 4194304, end: 0000000024D90170)
B  dbexpbuf: Buffer CUA   (addr: 0000000024DA0170, size: 3072000, end: 000000002508E170)
B  dbexpbuf: Buffer OTR   (addr: 0000000025090170, size: 4194304, end: 0000000025490170)
I  MPI: dynamic quotas disabled.
I  MPI init: pipes=4000 buffers=1279 reserved=383 quota=10% 
M  rdisp/thsend_mode : -1 -> 0
M  rdisp/setactfields_late : -1 -> 0
M  CCMS uses Shared Memory Key 73 for monitoring.
M  CCMS: SemInMgt: Semaphore Management initialized by AlAttachShm_Doublestack.
M  CCMS: SemInit: Semaphore 38 initialized by AlAttachShm_Doublestack.
M  CCMS: AlInitGlobals : alert/use_sema_lock = TRUE.
S  


S Fri Nov 25 11:54:04 2011
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 0000000012B398B0
S  
S Fri Nov 25 11:54:05 2011
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 000000002E8100D0
S  doing lock recovery
S  setting server cache root
S  found spool memory service RSPO-SERVERCACHE at 000000002E810800
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  System page size: 4kb, total admin_size: 22920kb, dir_size: 22092kb.
A  Attached to PXA (address 000007FFEDAB0050, size 300000K, 1 fragments of 277080K )
A  PXA allocated (address 000007FFEDAB0050, size 300000K)
A  *** WARNING: INTERNAL_KERNEL_VERSION of message server
A               unknown yet.
A  abap/pxa = shared protect gen_remote
A  PXA: checking structure sizes: 752|264|16
A  PXA INITIALIZATION FINISHED
A  ---PXA-------------------------------------------
A  
A  ATRA: pfclock execution time = 0
A  


A Fri Nov 25 11:54:07 2011
A  ABAP ShmAdm attached (addr=000007DFE85E9000 leng=20955136 end=000007DFE99E5000)
A  >> Shm MMADM area (addr=000007DFE8AE0C20 leng=242240 end=000007DFE8B1BE60)
A  >> Shm MMDAT area (addr=000007DFE8B1C000 leng=15503360 end=000007DFE99E5000)
A  RFC Destination> destination SYSKCRMDEV1_CR7_07 host SYSKCRMDEV1 system CR7 systnr 7 (SYSKCRMDEV1_CR7_07)
A  RFC Options> H=SYSKCRMDEV1,S=07,d=2,
A  RFC FRFC> fallback activ but this is not a central instance.
A    
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>' [ORACLE:720.00]):
B   max_blocking_factor       =   5,  min_blocking_factor         =   5,
B   max_in_blocking_factor    =   5,  min_in_blocking_factor      =   5,
B   max_union_blocking_factor =   5,  min_union_blocking_factor   =   5,
B   prefer_union_all          =   0,  prefer_join                 =   0,
B   prefer_fix_blocking       =   0,  prefer_in_itab_opt          =   1,
B   convert AVG               =   0,  alias table FUPD            =   0,
B   escape_as_literal         =   1,                              
B   select *                  =0x0f,  character encoding          = STD / <none>:-,
B   use_hints                 = abap->1, dbif->0x1, upto->4294967295
M  
M Fri Nov 25 11:54:10 2011
M  ThrCreateShObjects		allocated 49904 bytes at 000000002F1B0050
N  SsfSapSecin: getenv(SECUDIR)=="G:\usr\sap\CR7\SYS\exe\uc\NTAMD64\sec"
N  
N  =================================================
N  === SSF INITIALIZATION:
N  ===...SSF Security Toolkit name SAPSECULIB .
N  ===...SSF library is G:\usr\sap\CR7\EHPI\abap\exe\sapsecu.dll .
N  ===...SSF default hash algorithm is SHA1 .
N  ===...SSF default symmetric encryption algorithm is DES-CBC .
N  ===...SECUDIR="G:\usr\sap\CR7\SYS\exe\uc\NTAMD64\sec"
N  ===...loading of Security Toolkit completed with rc 5 (SSF_SUP_NOTALLFUNCTIONS).
N  ===   SAPSECULIB Version 5.4.28M-6
N  =================================================
N  


N Fri Nov 25 11:54:55 2011
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    ITSP ICMAN is not active: Disable Kernel Web GUI functionality
W  === ipl_Init() returns 2, ITSPE_DISABLED: Service is disabled (sapparam)
W  =================================================
N  SignInit: Security Session Management cannot be used (ABAP parts are missing, note 1477428)
N  VSI: WP init in ABAP VM completed with rc=0
E  Enqueue Info: enque/use_pfclock2 = FALSE
E  Enqueue Info: row condense enabled
E  Enqueue Info: enque/use_pfclock2 = FALSE
E  Replication is disabled
E  EnqCcInitialize: local lock table initialization o.k.
E  
E Fri Nov 25 11:54:56 2011
E  EnqId_SuppressIpc: local EnqId initialization o.k.
E  EnqCcInitialize: local enqueue client init o.k.

Thanks