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: 

MaxDB dbmcli db_online InconsistentLogInfoPage

Go to solution
Former Member

on ‎06-23-2008 2:42 PM

0 Kudos

hi All,

I am getting the following error when I try to start the MaxDB database.

bash-3.00# /sapdb/programs/bin/dbmcli -d BI1 -n sapbi1db -u control,vcs12345 db_admin

OK

bash-3.00# /sapdb/programs/bin/dbmcli -d BI1 -n sapbi1db -u control,vcs12345 db_online

ERR

-24988,ERR_SQL: Sql error

-9407,System error: unexpected error

3,Database state: OFFLINE

20002,Expected ioseq 193064, found 193087-193112 on off 131435

20022,LastKnown not found on log

20025,Initialization of log for 'restart' failed with 'InconsistentLogInfoPage'

Please let me know if you know the issue?

Any help will be appreciated!

Thanks & Regards,

Venkat

-

Here are logs from few files -

bash-3.00# tail /sapdb/data/wrk/BI1/dbm.prt

2008-06-23 19:05:16 0x000031c1 INF 283 DBMSrv command db_offline

2008-06-23 19:05:25 0x000031c1 INF 226 DBMSrv DBM Server client disconnect: PID 12735 on computer sapbi1db

2008-06-23 19:05:37 0x000031f8 INF 216 DBMSrv DBM Server client connection (established at 2008-06-23 19:05:37): PID 12790 on computer sapbi1db

2008-06-23 19:05:37 0x000031f8 INF 283 DBMSrv command db_online

2008-06-23 19:05:46 0x000031f8 ERR -24988 DBMSrv ERR_SQL: Sql error

0x000031f8 ERR -24778 DBMSrv 3,Database state: OFFLINE

0x000031f8 ERR -24778 DBMSrv 20002,Expected ioseq 193064, found 193087-193112 on off 131435

0x000031f8 ERR -24778 DBMSrv 20022,LastKnown not found on log

0x000031f8 ERR -24778 DBMSrv 20025,Initialization of log for 'restart' failed with 'InconsistentLogInfoPage'

2008-06-23 19:05:46 0x000031f8 INF 226 DBMSrv DBM Server client disconnect: PID 12790 on computer sapbi1db

bash-3.00# more /sapdb/data/wrk/BI1/dbm.utl

-

Date Time Message-Text

-

2008-06-04 13:08:35 4846467B0001 0000 IC1 CREATE INSTANCE SYSDBA SUPERDBA

2008-06-04 13:10:50 4846467B0001 0001 RET RETURNCODE 0

2008-06-04 14:22:02 484657B20004 0000 SHT SHUTDOWN

2008-06-04 14:22:18 484657C20001 0000 RST RESTART

2008-06-04 14:22:19 484657C20001 0001 RET RETURNCODE 0

2008-06-04 14:28:31 484659370002 0000 SHT SHUTDOWN

2008-06-04 14:28:40 484659400001 0000 REQ SET LOG WRITER OFF

2008-06-04 14:28:40 484659400001 0001 RET RETURNCODE 0

2008-06-04 14:28:42 484659420003 0000 RST RESTART

2008-06-04 14:28:43 484659420003 0001 RET RETURNCODE 0

2008-06-04 16:14:02 484671F20004 0000 SHT SHUTDOWN

2008-06-04 16:14:12 484671FC0001 0000 REQ SET LOG WRITER ON

2008-06-04 16:14:12 484671FC0001 0001 RET RETURNCODE 0

2008-06-04 16:14:14 484671FE0002 0000 RST RESTART

2008-06-04 16:14:17 484671FE0002 0001 RET RETURNCODE 0

2008-06-04 16:18:55 484673170003 0000 SHT SHUTDOWN

2008-06-04 16:19:13 484673290001 0000 REQ SET LOG WRITER OFF

2008-06-04 16:19:13 484673290001 0001 RET RETURNCODE 0

2008-06-04 16:19:15 4846732B0003 0000 RST RESTART

2008-06-04 16:19:18 4846732B0003 0001 RET RETURNCODE 0

2008-06-04 17:54:37 484689850004 0000 SHT SHUTDOWN

2008-06-04 17:57:09 48468A1D0001 0000 RST RESTART

2008-06-04 17:57:12 48468A1D0001 0001 RET RETURNCODE 0

2008-06-04 21:21:43 4846BA0F0002 0000 SHT SHUTDOWN

2008-06-04 21:21:57 4846BA1D0001 0000 REQ SET LOG WRITER ON

2008-06-04 21:21:57 4846BA1D0001 0001 RET RETURNCODE 0

2008-06-04 21:21:59 4846BA1F0002 0000 RST RESTART

2008-06-04 21:22:02 4846BA1F0002 0001 RET RETURNCODE 0

2008-06-23 17:52:32 485F95880001 0000 RST RESTART

2008-06-23 17:56:02 485F965A0001 0000 RST RESTART

2008-06-23 18:12:34 485F9A3A0001 0000 RST RESTART

2008-06-23 18:57:27 485FA4BF0001 0000 RST RESTART

2008-06-23 18:59:25 485FA5350001 0000 RST RESTART

2008-06-23 19:05:44 485FA6B00001 0000 RST RESTART

-

current write position -

bash-3.00# tail /sapdb/data/wrk/BI1/knldiag.err

2008-06-23 19:05:38 --- Starting GMT 2008-06-23 13:35:38 7.6.00 Build 018-123-119-055

2008-06-23 19:05:46 22 ERR 51080 SYSERROR -9407 unexpected error

2008-06-23 19:05:46 22 ERR 3 Admin Kernel_Administration.cpp:606

2008-06-23 19:05:46 22 ERR 3 Admin 2008-06-23 19:05:46 Admin Error 3

2008-06-23 19:05:46 22 ERR 3 Admin Database state: OFFLINE

2008-06-23 19:05:46 22 ERR 3 Admin + Log_Volume.cpp:1048

2008-06-23 19:05:46 22 ERR 3 Admin + 2008-06-23 19:05:46 Log Info 20002

2008-06-23 19:05:46 22 ERR 3 Admin - Expected ioseq 193064, found 193087-193112 on off 131435

2008-06-23 19:05:46 22 ERR 3 Admin + Log_Volume.cpp:747

2008-06-23 19:05:46 22 ERR 3 Admin + 2008-06-23 19:05:46 Log Error 20022

2008-06-23 19:05:46 22 ERR 3 Admin - LastKnown not found on log

2008-06-23 19:05:46 22 ERR 3 Admin + Kernel_Administration.cpp:762

2008-06-23 19:05:46 22 ERR 3 Admin + 2008-06-23 19:05:46 Admin Warning 20025

2008-06-23 19:05:46 22 ERR 3 Admin - Initialization of log for 'restart' failed with 'InconsistentLogInfoPage'

2008-06-23 19:05:54 0 ERR 12009 DBCRASH Kernel exited due to signal 0(Killed after timeout with state SERVER_KILL)

2008-06-23 19:05:54 ___ Stopping GMT 2008-06-23 13:35:54 7.6.00 Build 018-123-119-055

bash-3.00# more /sapdb/data/wrk/BI1/knldiag

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T121

2008-06-23 19:05:42 22 12929 TASKING Task T120 started

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T120

2008-06-23 19:05:42 22 12929 TASKING Task T119 started

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T119

2008-06-23 19:05:42 22 12929 TASKING Task T118 started

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T118

2008-06-23 19:05:42 22 12929 TASKING Task T117 started

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T117

2008-06-23 19:05:42 22 12929 TASKING Task T116 started

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T116

2008-06-23 19:05:42 22 12929 TASKING Task T115 started

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T115

2008-06-23 19:05:42 22 12929 TASKING Task T114 started

2008-06-23 19:05:42 22 11007 COMMUNIC wait for connection T114

2008-06-23 19:05:43 9 3 RunTime State changed from STARTING to ADMIN

2008-06-23 19:05:43 21 31 SrvTasks Permanently reserved 13 servertasks for 'Backup / Restore'.

2008-06-23 19:05:43 21 31 SrvTasks Permanently reserved 2 servertasks for 'Backup / Restore'.

======================================= end of startup part ====================

2008-06-23 19:05:43 9 11570 startup complete

2008-06-23 19:05:44 10 11561 COMMUNIC Connecting T113 local 12792

2008-06-23 19:05:44 22 11561 COMMUNIC Connected T113 local 12792

2008-06-23 19:05:44 27 12821 TASKING Thread 27 starting

2008-06-23 19:05:44 27 11597 IO Open '/sapdb/BI1/sapdata/DISKD0001' successfull, fd: 10

2008-06-23 19:05:44 27 11565 startup DEVi started

2008-06-23 19:05:44 28 12821 TASKING Thread 28 starting

2008-06-23 19:05:44 28 11597 IO Open '/sapdb/BI1/sapdata/DISKD0001' successfull, fd: 18

2008-06-23 19:05:44 28 11565 startup DEVi started

2008-06-23 19:05:44 22 11000 vattach '/sapdb/BI1/sapdata/DISKD0001' devno 1 T113 succeeded

2008-06-23 19:05:44 29 12821 TASKING Thread 29 starting

2008-06-23 19:05:44 29 11597 IO Open '/sapdb/BI1/sapdata/DISKD0002' successfull, fd: 20

2008-06-23 19:05:44 29 11565 startup DEVi started

2008-06-23 19:05:44 30 12821 TASKING Thread 30 starting

2008-06-23 19:05:44 30 11597 IO Open '/sapdb/BI1/sapdata/DISKD0002' successfull, fd: 21

2008-06-23 19:05:44 30 11565 startup DEVi started

2008-06-23 19:05:44 22 11000 vattach '/sapdb/BI1/sapdata/DISKD0002' devno 2 T113 succeeded

2008-06-23 19:05:44 31 12821 TASKING Thread 31 starting

2008-06-23 19:05:44 31 11597 IO Open '/sapdb/BI1/sapdata/DISKD0003' successfull, fd: 23

2008-06-23 19:05:44 31 11565 startup DEVi started

2008-06-23 19:05:44 32 12821 TASKING Thread 32 starting

2008-06-23 19:05:44 32 11597 IO Open '/sapdb/BI1/sapdata/DISKD0003' successfull, fd: 24

2008-06-23 19:05:44 32 11565 startup DEVi started

2008-06-23 19:05:44 22 11000 vattach '/sapdb/BI1/sapdata/DISKD0003' devno 3 T113 succeeded

2008-06-23 19:05:44 33 12821 TASKING Thread 33 starting

2008-06-23 19:05:44 33 11597 IO Open '/sapdb/BI1/sapdata/DISKD0004' successfull, fd: 26

2008-06-23 19:05:44 33 11565 startup DEVi started

2008-06-23 19:05:44 34 12821 TASKING Thread 34 starting

2008-06-23 19:05:44 34 11597 IO Open '/sapdb/BI1/sapdata/DISKD0004' successfull, fd: 27

2008-06-23 19:05:44 34 11565 startup DEVi started

2008-06-23 19:05:44 22 11000 vattach '/sapdb/BI1/sapdata/DISKD0004' devno 4 T113 succeeded

2008-06-23 19:05:44 35 12821 TASKING Thread 35 starting

2008-06-23 19:05:44 35 11597 IO Open '/sapdb/BI1/saplog/DISKL001' successfull, fd: 29

2008-06-23 19:05:44 35 11565 startup DEVi started

2008-06-23 19:05:44 36 12821 TASKING Thread 36 starting

2008-06-23 19:05:44 36 11597 IO Open '/sapdb/BI1/saplog/DISKL001' successfull, fd: 30

2008-06-23 19:05:44 36 11565 startup DEVi started

2008-06-23 19:05:44 22 11000 vattach '/sapdb/BI1/saplog/DISKL001' devno 5 T113 succeeded

2008-06-23 19:05:44 22 20017 Pager Start Read Converter

2008-06-23 19:05:45 21 20003 KernelDB 70 percent of data area occupied, 614398 pages free

2008-06-23 19:05:45 22 20018 Pager Stop Read Converter, Pages: 872 IO: 872

2008-06-23 19:05:45 22 53000 DATACACH Begin data cache restart

2008-06-23 19:05:45 22 54003 dynpool DATA_CACHE_REGIONS : 32

2008-06-23 19:05:45 22 54003 dynpool B20_DATACACHE_HEAD : 173296

2008-06-23 19:05:45 22 54003 dynpool DATACACHE head num item: 10831

2008-06-23 19:05:45 22 54003 dynpool DATACACHE head item siz: 16

2008-06-23 19:05:45 22 54003 dynpool DATACACHE task queue : 3912

2008-06-23 19:05:45 22 54003 dynpool US + SV + DW + 8 : 163

2008-06-23 19:05:45 22 54003 dynpool DATACACHE taskq elem : 24

2008-06-23 19:05:45 22 54003 dynpool B20_DATACACHE_TASKQ : 177208

2008-06-23 19:05:45 22 54003 dynDATA DATA_CACHE_PAGES : 2704

2008-06-23 19:05:45 22 54003 dynpool B20_DATACACHE_CB : 324480

2008-06-23 19:05:45 22 54003 dynpool DATACACHE num cblocks : 2704

2008-06-23 19:05:45 22 54003 dynpool DATACACHE cblock size : 120

2008-06-23 19:05:45 22 54003 dynpool DYNP_B20_DATACACHE : 16054016

2008-06-23 19:05:45 22 54003 dynDATA DYND_B20_DATACACHE : 86528

2008-06-23 19:05:45 22 53000 DATACACH Finish data cache restart

2008-06-23 19:05:45 22 53000 B*TREE Blob file directory restart successful

2008-06-23 19:05:45 22 7 FileDir File directory restarting

2008-06-23 19:05:46 22 8 FileDir File directory restart completed

2008-06-23 19:05:46 22 20061 Log History: 50 (50) files existing

2008-06-23 19:05:46 22 20064 Log History: all history files registered, GC is ready

2008-06-23 19:05:46 22 54003 dynpool DYNP_K51_LOCK_LIST : 82757136

2008-06-23 19:05:46 22 54003 dynpool LOCK maxlocks : 300000

2008-06-23 19:05:46 22 54003 dynpool LOCK items : 902400

2008-06-23 19:05:46 22 54003 dynpool LOCK Regions : 24

2008-06-23 19:05:46 22 54003 dynpool LOCK TransRegions : 8

2008-06-23 19:05:46 22 54003 dynpool LOCK RegionGlob+Space : 3840

2008-06-23 19:05:46 22 54003 dynpool LOCK TransGlob : 768

2008-06-23 19:05:46 22 54003 dynpool LOCK SupplyItemsPerRgn : 100

2008-06-23 19:05:46 22 54003 dynpool LOCK SupplySize : 211200

2008-06-23 19:05:46 22 54003 dynpool LOCK RowHash entries : 300000

2008-06-23 19:05:46 22 54003 dynpool LOCK TabHash entries : 60000

2008-06-23 19:05:46 22 54003 dynpool LOCK Row+Tab hash size :2880000

2008-06-23 19:05:46 22 54003 dynpool LOCK Trans entries : 304

2008-06-23 19:05:46 22 54003 dynpool LOCK trans_list size : 80256

2008-06-23 19:05:46 22 54003 dynpool LOCK TransPtrList : 2432

2008-06-23 19:05:46 22 54003 dynpool LOCK TransHash entries : 304

2008-06-23 19:05:46 22 54003 dynpool LOCK TransHash size : 2432

2008-06-23 19:05:46 22 54003 dynpool LOCK SupplyPoolSegments: 9000

2008-06-23 19:05:46 22 54003 dynpool LOCK SupplyPoolSize : 79416000

2008-06-23 19:05:46 22 54003 dynpool LOCK ObjColl_list size : 9728

2008-06-23 19:05:46 22 54003 dynpool LOCK ObjReq_list size : 6080

2008-06-23 19:05:46 22 11000 vdetach '/sapdb/BI1/saplog/DISKL001' devno 5 T113

2008-06-23 19:05:46 11 12822 TASKING Thread 35 joining

2008-06-23 19:05:46 35 11566 stop DEVi stopped

2008-06-23 19:05:46 11 12822 TASKING Thread 36 joining

2008-06-23 19:05:46 36 11566 stop DEVi stopped

2008-06-23 19:05:46 22 ERR 51080 SYSERROR -9407 unexpected error

2008-06-23 19:05:46 22 ERR 3 Admin Kernel_Administration.cpp:606

2008-06-23 19:05:46 22 ERR 3 Admin 2008-06-23 19:05:46 Admin Error 3

2008-06-23 19:05:46 22 ERR 3 Admin Database state: OFFLINE

2008-06-23 19:05:46 22 ERR 3 Admin + Log_Volume.cpp:1048

2008-06-23 19:05:46 22 ERR 3 Admin + 2008-06-23 19:05:46 Log Info 20002

2008-06-23 19:05:46 22 ERR 3 Admin - Expected ioseq 193064, found 193087-193112 on off 131435

2008-06-23 19:05:46 22 ERR 3 Admin + Log_Volume.cpp:747

2008-06-23 19:05:46 22 ERR 3 Admin + 2008-06-23 19:05:46 Log Error 20022

2008-06-23 19:05:46 22 ERR 3 Admin - LastKnown not found on log

2008-06-23 19:05:46 22 ERR 3 Admin + Kernel_Administration.cpp:762

2008-06-23 19:05:46 22 ERR 3 Admin + 2008-06-23 19:05:46 Admin Warning 20025

2008-06-23 19:05:46 22 ERR 3 Admin - Initialization of log for 'restart' failed with 'InconsistentLogInfoPage'

2008-06-23 19:05:46 22 11560 COMMUNIC Releasing T113

2008-06-23 19:05:46 22 12696 DBSTATE Change DbState to 'SHUTDOWN'(25)

2008-06-23 19:05:46 6 12696 DBSTATE Change DbState to 'KILL'(29)

2008-06-23 19:05:46 6 12697 DBSTATE Resuming tracewriter

2008-06-23 19:05:46 6 12696 DBSTATE Change DbState to 'TRACE_WRITER_WAIT'(29)

2008-06-23 19:05:46 16 12825 TASKING state 29 before shutkill(1)

2008-06-23 19:05:46 16 12825 TASKING state 29 before shutkill(1)

2008-06-23 19:05:46 16 12825 TASKING state 29 before shutkill(1)

2008-06-23 19:05:46 16 20000 Trace Start flush kernel trace

2008-06-23 19:05:48 21 12825 TASKING state 29 before shutkill(1)

2008-06-23 19:05:48 21 12768 UKT6 stopped

2008-06-23 19:05:53 16 20001 Trace Stop flush kernel trace

2008-06-23 19:05:53 16 20002 Trace Start flush kernel dump

2008-06-23 19:05:53 16 20003 Trace Stop flush kernel dump

2008-06-23 19:05:53 16 12619 TASKING Releasing tracewriter

+++++++++++++++++++++++++++++++++++++++ Kernel Exit ++++++++++++++++++++++++++++

2008-06-23 19:05:54 0 ERR 12009 DBCRASH Kernel exited due to signal 0(Killed after timeout with state SERVER_KILL)

2008-06-23 19:05:54 0 12890 DIAGHIST Backup of diagnostic files will be forced at next restart

2008-06-23 19:05:54 0 12808 DBSTATE Flushing knltrace pages

2008-06-23 19:05:54 0 12696 DBSTATE Change DbState to 'OFFLINE '(29)

-

current write position -

Show replies
Show replies
Answer

Accepted Solutions (1)

Accepted Solutions (1)

markus_doehr2
Active Contributor
‎06-23-2008 2:54 PM
0 Kudos

Did you run the database in log overwrite mode? What happened prior to this startup?

Markus

Show replies
Show replies
Former Member
‎06-23-2008 3:13 PM
0 Kudos

Hi Markus,

Thanks for your reply.

I didn't change any default settings for MaxDB. I guess the default is override.

I am not sure what happened during my last online. I onlined the database couple of weeks and today I checked my setup (this is a test setup) which I found in faulted state. When I tried to restart it it is giving the above error.

Thanks,

Venkat

markus_doehr2
Active Contributor
‎06-23-2008 3:18 PM
0 Kudos

Did you shut down the system before shutting down the database?

Markus

lbreddemann
Active Contributor
‎06-23-2008 3:29 PM
0 Kudos

Hi Venkat,

the default log mode is surely not the overwrite mode.

From the error messages you posted it looks like your log volume got corrupted.

I would propose you do the following:

1. Perform a complete data backup in ADMIN mode

2. Check if the last shutdown of the database was successfull

3. If it was than you could delete the log volume without loosing any committed data from the database (I'll explain further down how to do this)

4. If it wasn't and you're a SAP customer - open a support message for it.

To clear the log area you've to do this:


dbmcli -d <DBSID> -u <dbm>,<dbm-pw> 
db_admin
util_connect
util_execute clear log
db_online

Afterwards make sure to enable the correct log mode (not overwrite), perform a full data backup and setup the log backups (e.g. automatic logbackup).

BE AWARE that you're deleting all log information with this procedure.

So if there had been any changes after the last savepoint - these will be lost.

This may (and likely will) cause application inconsistencies.

KR Lars

Former Member
‎06-26-2008 7:17 AM
0 Kudos

Thanks KR. Your solution solved my problem. I assigned you reward points:)

Regards,

Venkat

Former Member
‎04-12-2013 11:16 AM
0 Kudos

Hi Lars Breddemann,

I am Performing db refresh from prod to qty  i am following the SAP Note 129352 - Homogeneous system copy with MaxDB (SAP DB).Restore with initialization was taking long time still in running state and ecovery size shown in DBMGUI  was more than 1TB now . In file system the sapdata was not showing 1Tb filled space and it is normal FYI i have inserted prod backup tape which was close to 150 GB only.below i am pasting the dbm.prt error logs . please reply me what can be the issue.i have seen your posts on MAXDB i think you can help me out.


0x0007c036 ERR     -24580 DBMSrv   ERR_COMMAND_FAILED: Command 'util_execute' has ended and failed with return code -24988.

                    0x0007c036 ERR     -24988 DBMSrv   ERR_SQL: SQL error

                    0x0007c036 ERR     -24778 DBMSrv   -104,DBM command impossible at this time

0x0007b0d0 ERR     -24580 DBMSrv   ERR_COMMAND_FAILED: Command 'db_restartinfo' has ended and failed with return code -24988.

                    0x0007b0d0 ERR     -24988 DBMSrv   ERR_SQL: SQL error

                    0x0007b0d0 ERR     -24778 DBMSrv   -104,DBM command impossible at this time

Thanks in advance,

Krishna

Answers (0)

Ask a Question