cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 

HDB indexserver not starting

former_member554632
Explorer

on ‎2016 May 06 1:33 PM

0 Kudos
2,689

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

Show replies
Show replies
Answer
View Entire Topic
Former Member
‎2016 May 06 3:25 PM
0 Kudos

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

Show replies
Show replies
former_member554632
Explorer
‎2016 May 06 3:32 PM
0 Kudos

Hello,

the upgrade of AFL were without problem - but the system doesn't want to start anymore.

It's a single container system.

Not distributed.

After issuing HDB start the console is waiting for HANA to start - as the timeout is set to 45 minutes it's giving error: Timeout.

The provided log is everything I have. Nothing more is written to the file.

For name server all I have is:

[11172]{-1}[-1/-1] 2016-05-06 11:04:49.955059 w Backup           BackupMonitor_TransferQueue.cpp(00048) : Master index server not available!

[11172]{-1}[-1/-1] 2016-05-06 11:04:49.955131 w Backup           BackupMonitor_TransferQueue.cpp(00048) : Master index server not available!

[11172]{-1}[-1/-1] 2016-05-06 11:04:49.955180 w Backup           BackupMonitor_TransferQueue.cpp(00048) : Master index server not available!

[11172]{-1}[-1/-1] 2016-05-06 11:04:49.955181 w Backup           BackupMonitor_TransferQueue.cpp(00332) : Indexserver not answering.

Best regards

Ask a Question