HDB indexserver not starting

former_member554632 · ‎05-06-2016

Hello,

yesterday we wanted to install AFL plugin to our HANA database. The installation was succesfull, but afterwards our HANA database doesn't want to start. We can't find anything helpfull in the log files (attached all traces).

HANA DB Version: 1.00.85.01.399276

After issuing HDB start command, it takes around 45 minutes and then throwing Timeout error.

We tryied to restart the whole server, but it didn't help.

Could you please help to solve this issue?

Bellow is the indexserver log:

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089018 i Basis TraceStream.cpp(00396) : ==== Starting hdbindexserver, version 1.00.85.01.399276 (NewDB100_REL), build linuxx86_64 not set 2015-01-14 17:43:42 ld7272.wdf.sap.corp gcc (SAP release 20140516, based on SUSE gcc47-4.7.2_20130108-0.15.45) 4.7.2 20130108 [gcc-4_7-branch revision 195014]

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089267 i Basis TraceStream.cpp(00401) : MaxOpenFiles: 1048576

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089301 i Memory MallocProxy.cpp(01181) : Installed malloc hooks

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089304 i Basis ProcessorInfo.cpp(00746) : Using GDT segment limit to determine current CPU ID

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089305 i Basis Timer.cpp(00629) : Using RDTSC for HR timer

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089305 i Memory AllocatorImpl.cpp(01219) : Allocators activated

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089306 i Memory AllocatorImpl.cpp(01235) : Using big block segment size 67108864

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089308 w Environment Environment.cpp(00286) : Changing environment set IMSLERRPATH=/usr/sap/HAN/HDB00/exe//

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.089309 w Environment Environment.cpp(00286) : Changing environment set IMSLSERRPATH=/usr/sap/HAN/HDB00/exe//

[37346]{-1}[-1/-1] 2016-05-06 09:15:04.096804 w Environment Environment.cpp(00286) : Changing environment set SSL_WITH_OPENSSL=0

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.762076 i assign TREXIndexServer.cpp(00623) : assign to volume 2 started

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.762833 i FileIO FileStatistics.cpp(00287) : FileFactoryConfiguration::initial(path="/usr/sap/HAN/SYS/global/hdb/backint/", AsyncWriteSubmitActive=auto,AsyncWriteSubmitBlocks=new,AsynReadSubmit=off,#SubmitQueues=1,#CompletionQueues=1)

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.764371 i FileIO FileStatistics.cpp(00287) : FileFactoryConfiguration::initial(path="/usr/sap/HAN/SYS/global/hdb/backint/", AsyncWriteSubmitActive=auto,AsyncWriteSubmitBlocks=new,AsynReadSubmit=off,#SubmitQueues=1,#CompletionQueues=1)

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.765992 i FileIO FileStatistics.cpp(00287) : FileFactoryConfiguration::initial(path="/HANA/log/mnt00001/hdb00002/", AsyncWriteSubmitActive=auto,AsyncWriteSubmitBlocks=new,AsynReadSubmit=off,#SubmitQueues=1,#CompletionQueues=1)

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.767501 i FileIO FileStatistics.cpp(00287) : FileFactoryConfiguration::initial(path="/HANA/data/mnt00001/hdb00002/", AsyncWriteSubmitActive=auto,AsyncWriteSubmitBlocks=new,AsynReadSubmit=off,#SubmitQueues=1,#CompletionQueues=1)

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.769510 i HistoryManager HistoryManager.cpp(00492) : Maximum garbage collection parallelity set to 144

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.769691 i PhysicalPageAcce DataVolumeImpl.cpp(00543) : Open volume file(s) in "/HANA/data/mnt00001/hdb00002/" (readOnly= 0, diagMode= 0)

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.769711 i PhysicalPageAcce DataVolumeImpl.cpp(00556) : --confMaxFileSize= 0MB, fsysMaxFilesSize= 2048000MB --> 2048000MB

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.769888 i PhysicalPageAcce DataVolumeImpl.cpp(00601) : --opened volume file "datavolume_0000.dat"

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.781315 i Crypto RootKeyStore.cpp(00157) : Empty SSFS cache: reading from SSFS (ID=PERSISTENCE/ROOTKEY)

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.781381 i Crypto RootKeyStore.cpp(00104) : RSecSSFs: Entering function "RSecSSFsGetRecord" [/HDB/IMP/NewDB100_PATCH_REL/src/sys/src/spine/src/krn/rsec/rsecssfs.c 850]

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.781392 i Crypto RootKeyStore.cpp(00104) : RSecSSFs: Using explicitly set configuration data [/HDB/IMP/NewDB100_PATCH_REL/src/sys/src/spine/src/krn/rsec/rsecssfs.c 3907]

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.781423 i Crypto RootKeyStore.cpp(00104) : RSecSSFs: Data file "/usr/sap/HAN/SYS/global/hdb/security/ssfs//SSFS_HAN.DAT" opened for read [/HDB/IMP/NewDB100_PATCH_REL/src/sys/src/spine/src/krn/rsec/rsecssfs.c 2351]

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.781439 i Crypto RootKeyStore.cpp(00104) : RSecSSFs: Key file "/usr/sap/HAN/SYS/global/hdb/security/ssfs/SSFS_HAN.KEY" not found, using default key [/HDB/IMP/NewDB100_PATCH_REL/src/sys/src/spine/src/krn/rsec/rsecssfs.c 1271]

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.781876 i Crypto RootKeyStore.cpp(00104) : RSecSSFs: Exiting function "RSecSSFsGetRecord" with return code 0 (message: <No message available>) [/HDB/IMP/NewDB100_PATCH_REL/src/sys/src/spine/src/krn/rsec/rsecssfs.c 911]

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782145 i PersistenceManag PersistenceManagerImpl.cpp(04014) : Restart page version 1 loaded (96bytes)

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782173 i PersistenceManag PersistenceManagerImpl.cpp(04039) : Initial maximum known TID after restart: 460382606

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782175 i Logger PersistenceManagerImpl.cpp(04047) : Newest known master commit position: 0xd8c56f082

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782179 i Logger PersistenceManagerImpl.cpp(04057) : Known last prepare commit position on volume 1: 0x22126e88

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782192 i Logger PersistenceManagerImpl.cpp(04057) : Known last prepare commit position on volume 3: 0x132c855ac3

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782193 i Logger PersistenceManagerImpl.cpp(04057) : Known last prepare commit position on volume 4: 0xc6d706d83

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782194 i Logger PersistenceManagerImpl.cpp(04057) : Known last prepare commit position on volume 5: 0x71c883

[37615]{-1}[-1/-1] 2016-05-06 09:15:04.782200 i PersistenceManag PersistenceManagerImpl.cpp(04111) : Known DTX volume set [1,3,4,5,1024], 0 RTT entries

[37615]{-1}[-1/-1] 2016-05-06 09:15:12.236865 i PersistenceManag PersistenceSessionRegistry.cpp(00254) : Start loading open sessions and history cleanup files

[37615]{-1}[-1/-1] 2016-05-06 09:15:12.593347 i Logger PersistenceSessionRegistry.cpp(01062) : Open session count at restart: 1/1, max known TID at restart: 460382606

[37615]{-1}[-1/-1] 2016-05-06 09:15:12.593367 i PersistenceManag PersistenceSessionRegistry.cpp(00261) : Loading 1 open session(s) and 1 history cleanup file(s) finished in 0.712954 seconds;

[37615]{-1}[-1/-1] 2016-05-06 09:15:12.864095 i Service_Startup ContMgr.cc(00078) : Initializing system catalog.

[37615]{-1}[-1/-1] 2016-05-06 09:15:13.064796 i Service_Startup ContMgr.cc(00200) : Initializing system catalog done.

[37731]{-1}[-1/-1] 2016-05-06 09:15:14.117828 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01092) : LoadMultiplePageBlocksAtStartup

[37731]{-1}[-1/-1] 2016-05-06 09:15:14.117887 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01110) : allocate 347 segments requested to load and collect information about superblocks to read...

[37731]{-1}[-1/-1] 2016-05-06 09:15:18.616832 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01260) : collecting information done in 4498msec.

[37731]{-1}[-1/-1] 2016-05-06 09:15:18.617153 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01291) : SuperblockPrefetchCalculation:

allocationLimit=482491495213

inUseSize=3883578777

prefetchSize=382886333148

prefetchCount=5705

[37731]{-1}[-1/-1] 2016-05-06 09:15:18.617172 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01684) : set SuperblockPrefetchCount=5705 (max: 32767)

[37731]{-1}[-1/-1] 2016-05-06 09:15:18.617174 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01297) : trigger load of superblocks...

[37628]{-1}[-1/-1] 2016-05-06 09:15:18.744715 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01601) : load process: 0% (1 of 356) : overall 501MB/s.

[37731]{-1}[-1/-1] 2016-05-06 09:15:37.533549 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01342) : trigger load of superblocks done after 18916msec.

[37628]{-1}[-1/-1] 2016-05-06 09:15:37.562763 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01620) : i/o finished: read 356 superblock(s) within 18945ms = 1202MB/s overall.

[37731]{-1}[-1/-1] 2016-05-06 09:15:37.562841 i RowStorePageAcce AbsolutePageAccessImpl.cpp(01350) : finished loading all 347 segments by reading 356 superblocks after 23444msec.

[37731]{-1}[-1/-1] 2016-05-06 09:15:37.622566 i Service_Startup CheckpointMgr.cc(00703) : 347 RS segments loaded from Persistency in 23.5 sec (945.02127659574467MB/s)

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.627666 i Service_Startup MemSystem.cc(00143) : total memory size (MB) = 516796

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.627807 i Logger PersistenceManagerImpl.cpp(02273) : Preparing log recovery handler, start position 0xd8c56f0c6

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.627980 i Logger LoggerImpl.cpp(00444) : Setting log segment backup timeout to 86400s, original value was 0s

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.663639 w PersistenceManag PersistenceManagerImpl.cpp(03513) : NOTE: BACKUP DATA needed to ensure recoverability of the database

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.663662 i Logger PersistenceManagerImpl.cpp(03708) : Deactivating REDO logging for log replay

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.663664 i Logger PersistenceManagerImpl.cpp(03719) : Start termination of rollback(s) open in restart/backup savepoint

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.665658 i Logger PersistenceManagerImpl.cpp(03727) : Termination of rollback(s) open in restart/backup savepoint finished in 0.0001 seconds;

[37615]{-1}[-1/-1] 2016-05-06 09:15:37.665678 i Logger PersistenceManagerImpl.cpp(03732) : Start garbage collection of history files

[37551]{-1}[-1/-1] 2016-05-06 09:15:47.665775 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:15:57.665912 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:16:07.666016 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:16:17.666149 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:16:27.666243 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:16:37.666344 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:16:47.666436 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:16:57.666549 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:17:07.666645 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:17:17.666755 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:17:27.666908 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37551]{-1}[-1/-1] 2016-05-06 09:17:37.667020 i PersistenceManag PersistenceManagerImpl.cpp(01282) : Garbage collection of history files running: 0 cleanup files of 1 done

[37615]{-1}[-1/-1] 2016-05-06 09:17:47.177448 i Logger PersistenceManagerImpl.cpp(03743) : Garbage collection of history files finished: 1 cleanup files in 129.511 seconds;

[37615]{-1}[-1/-1] 2016-05-06 09:17:47.177487 i Logger PersistenceManagerImpl.cpp(03759) : Expecting savepoint 222299 log record as first log record at 0xd8c56f0c6

[37615]{-1}[-1/-1] 2016-05-06 09:17:47.177685 i Logger RecoveryHandlerImpl.cpp(00936) : RecoveryQueueCount used: 64

[37622]{-1}[-1/-1] 2016-05-06 09:17:47.927329 i Logger RecoveryHandlerImpl.cpp(00374) : Stopping parsing of open segment LogSegment[0:0xddef47ac0/Writing][GUID=3620CC37-146B-20160505-220549-1FE0000001/PrevGUID=3620CC37-146B-20160505-212730-3920000001/PersGUID=3620CC37-146B-20160413-091157-09D0000001/RestoreGUID=54A09A46-442E-20131025-055130-0120000001] at file position 4096, unused rest 1073737728B, termination reason: Invalid buffer header found at position 0x1000 in segment 0xddef47ac0

[37615]{-1}[-1/-1] 2016-05-06 09:17:47.955063 i Logger PersistenceManagerImpl.cpp(03827) : Starting log replay at position 0xd8c56f0c6

[37615]{-1}[-1/-1] 2016-05-06 09:17:47.955073 i Logger RecoveryHandlerImpl.cpp(01472) : Triggering recovery of remaining log

[38874]{-1}[-1/-1] 2016-05-06 09:17:49.268008 i Logger PersistenceManagerImpl.cpp(03041) : Found savepoint 222299 log record

[37551]{-1}[-1/-1] 2016-05-06 09:17:57.955542 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58230265920 and time: <none> (2%)

[37551]{-1}[-1/-1] 2016-05-06 09:20:57.957031 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58263811904 and time: 2016-04-15 17:28:56.452446+01:00 (5%)

[37551]{-1}[-1/-1] 2016-05-06 09:21:47.957542 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58297362944 and time: <none> (7%)

[37551]{-1}[-1/-1] 2016-05-06 09:26:37.960083 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58330904576 and time: 2016-04-15 17:29:30.283030+01:00 (10%)

[37551]{-1}[-1/-1] 2016-05-06 09:26:47.960205 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58364445504 and time: 2016-04-15 17:29:46.116009+01:00 (12%)

[37551]{-1}[-1/-1] 2016-05-06 09:31:57.963045 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58397984960 and time: 2016-04-15 17:30:03.709249+01:00 (15%)

[37551]{-1}[-1/-1] 2016-05-06 09:32:27.963377 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58448281600 and time: <none> (18%)

[37551]{-1}[-1/-1] 2016-05-06 09:32:57.963636 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58532135168 and time: <none> (24%)

[37551]{-1}[-1/-1] 2016-05-06 09:33:07.963730 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58666277824 and time: <none> (34%)

[37551]{-1}[-1/-1] 2016-05-06 09:33:17.963822 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58733368512 and time: <none> (39%)

[37551]{-1}[-1/-1] 2016-05-06 09:33:27.963953 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58783692672 and time: 2016-04-15 17:35:56.967064+01:00 (42%)

[37551]{-1}[-1/-1] 2016-05-06 09:33:38.820403 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58834013056 and time: 2016-04-15 17:36:54.529099+01:00 (46%)

[37551]{-1}[-1/-1] 2016-05-06 09:33:48.820447 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 58884339072 and time: 2016-04-15 17:38:22.929161+01:00 (50%)

[37551]{-1}[-1/-1] 2016-05-06 09:33:58.820550 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59001750912 and time: 2016-04-15 17:43:12.286839+01:00 (58%)

[37551]{-1}[-1/-1] 2016-05-06 09:34:48.821137 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59035285504 and time: <none> (61%)

[37551]{-1}[-1/-1] 2016-05-06 09:36:38.822096 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59204025024 and time: 2016-04-15 17:45:20.215160+01:00 (73%)

[37551]{-1}[-1/-1] 2016-05-06 09:36:48.822185 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59303638528 and time: 2016-04-15 17:57:56.870844+01:00 (80%)

[37551]{-1}[-1/-1] 2016-05-06 09:36:58.822274 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59392411072 and time: 2016-04-15 18:10:00.324347+01:00 (86%)

[37551]{-1}[-1/-1] 2016-05-06 09:37:08.844271 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59456303040 and time: 2016-04-15 18:29:00.734755+01:00 (91%)

[37551]{-1}[-1/-1] 2016-05-06 09:37:18.844362 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59499546496 and time: 2016-04-19 00:56:47.289513+01:00 (94%)

[37551]{-1}[-1/-1] 2016-05-06 09:37:28.844455 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59531929728 and time: 2016-04-26 04:56:24.324397+01:00 (96%)

[37551]{-1}[-1/-1] 2016-05-06 09:37:38.844559 i Logger RecoveryHandlerImpl.cpp(00663) : Redo done up to position: 59568842880 and time: 2016-05-04 11:09:52.077684+01:00 (99%)

[38874]{-1}[-1/-1] 2016-05-06 09:37:40.604635 i Logger RecoveryHandlerImpl.cpp(01866) : Log recovery cannot expect any more data at position 0xddef47b00, load states:

- LogPartition[/HANA/log/mnt00001/hdb00002/:0]: #segments 103/104 processed, 104/104 loaded, 104 total, last loaded LogSegment[0:0xddef47ac0/Writing][GUID=3620CC37-146B-20160505-220549-1FE0000001/PrevGUID=3620CC37-146B-20160505-212730-3920000001/PersGUID=3620CC37-146B-20160413-091157-09D0000001/RestoreGUID=54A09A46-442E-20131025-055130-0120000001]

[38874]{-1}[-1/-1] 2016-05-06 09:37:40.604696 i Logger RecoveryHandlerImpl.cpp(02166) : Recovery finished at log position 0xddef47b00

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.604713 i Logger RecoveryHandlerImpl.cpp(01520) : Finishing log recovery, waiting for in-process and in-load segments

[38850]{-1}[-1/-1] 2016-05-06 09:37:40.625582 i Logger RecoveryHandlerImpl.cpp(03341) : Signaling finish wait barrier

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.627890 i Logger LogSegment.cpp(00593) : Closing open log segments after log recovery completed at position 0xddef47b00

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.628042 i Logger LogSegment.cpp(00676) : Closing open on-disk segment LogSegment[0/103:0xddef47ac0/GUID=3620CC37-146B-20160505-220549-1FE0000001/PrevGUID=3620CC37-146B-20160505-212730-3920000001,TS=2016-05-05 23:05:49.510644/Writing/0x0]@0x00007ff034124440 after log recovery completed at position 0xddef47b00

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.629115 i Logger LoggerImpl.cpp(00933) : Replayed 88707600000B (84598MB) of log in 1193.45 seconds; 70.8852MB/s; max known TID=462402851

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.629222 i Logger LoggerImpl.cpp(00723) : Starting logger with 8 log buffers per partition, 1024KB each, segment size 1024MB, log mode normal

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.646217 i Logger RecoveryHandlerImpl.cpp(01767) : Termination of 0 indoubt transactions was executed in 0.008134 seconds;

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.648902 i Logger PersistenceManagerImpl.cpp(03940) : Activating REDO logging after log replay

[37615]{-1}[-1/-1] 2016-05-06 09:37:40.686650 i LogReplay RowStoreTransactionCallback.cc(00222) : starting master-slave DTX consistency check

[37615]{-1}[-1/-1] 2016-05-06 09:37:41.690414 i PersistenceManag PersistenceManagerImpl.cpp(04842) : DTX slave 5 reports rollback at restart completion; pre-restart RTT still awaits restart of volumes [1,3,4,1024]

[37615]{-1}[-1/-1] 2016-05-06 09:37:41.690432 i PersistenceManag PersistenceManagerImpl.cpp(04842) : DTX slave 3 reports rollback at restart completion; pre-restart RTT still awaits restart of volumes [1,4,1024]

[37615]{-1}[-1/-1] 2016-05-06 09:37:41.690437 i PersistenceManag PersistenceManagerImpl.cpp(04842) : DTX slave 4 reports rollback at restart completion; pre-restart RTT still awaits restart of volumes [1,1024]

[37615]{-1}[-1/-1] 2016-05-06 09:37:41.690457 i LogReplay RowStoreTransactionCallback.cc(00310) : finished master-slave DTX consistency check

[37615]{-1}[-1/-1] 2016-05-06 09:37:42.732183 i ContainerDirecto ContainerNameDirectoryImpl.cpp(01124) : Load ContainerNameDirectory...

[38066]{-1}[-1/-1] 2016-05-06 09:37:42.732254 i PersistenceManag VirtualFileStatsProxy.cpp(00130) : Initialize VirtualFile statistics...

[37874]{-1}[-1/-1] 2016-05-06 09:37:42.732254 i PersistenceManag VirtualFileStatsProxy.cpp(00186) : Initialize VirtualFile LOB statistics...

[37615]{-1}[-1/-1] 2016-05-06 09:37:46.847969 i ContainerDirecto ContainerNameDirectoryImpl.cpp(01185) : ContainerNameDirectory loaded 978484/978484 in 4.12 sec

[37874]{-1}[-1/-1] 2016-05-06 09:37:49.319309 i PersistenceManag VirtualFileStatsProxy.cpp(00220) : VirtualFile LOB statistics initialized: 3507009 CD entries in 6.59 sec

[38066]{-1}[-1/-1] 2016-05-06 09:37:49.974404 i PersistenceManag VirtualFileStatsProxy.cpp(00165) : VirtualFile statistics initialized: 978484/978484 CD entries in 7.24 sec

Former Member · ‎05-06-2016

Hi Moho,

Looking at the logs it does not explain the cause of the Database startup issue

The logs here are only between 09:15 AM to 09:37 AM, do you have more entries in the log after that? As you mentioned the process timed out after 45 mins above?

As it is a SPS08 system I believe this a Single container system, is it a distributed system? Can you share more details on the Architecture

Also during the AFL upgrade did the HANA Database have a planned stop and start or did it undergo a crash at some point?

More information would help and I would strongly suggest you to open a case with SAP Support meanwhile to make sure you have enough support

Sunil

Former Member · ‎05-06-2016

Dear Moho,

the following is shown in the log file.

20131025-055130-0120000001] at file position 4096, unused rest 1073737728B, termination reason: Invalid buffer header found at position 0x1000 in segment 0xddef47ac0

May something is corrupt. Please check the sap notes for a solution.

Best regards

Martin

HDB indexserver not starting

Accepted Solutions (0)

Answers (2)

Answers (2)

Struggling with Filters on Select - Fiori App

Re: How can I save Requirement in Cloud ALM

Re: How can I save Requirement in Cloud ALM

Re: fuzzy search with multiple name fields

SAC: Find drill level in hierarchy based on string