数据库日志文件丢失，怎么能读取数据

sl0325

请教各位高手：
     我的数据库不小心删除了一个日志设备，数据设备都在，目前建立了一个测试库，和以前的数据库设备名相同，大小一样，把数据设备文件覆盖到测试库里，想能读取一些有用的数据出来。启动服务器报错，请教各位高手如何处理，日志如下：



00:00000:00001:2011/12/22 16:05:23.84 server  Database 'sybsystemdb', checkpoint=(1028, 7), first=(17179870212, 7), last=(1028, 7).
00:00000:00001:2011/12/22 16:05:23.84 server  Completed estimating recovery log boundaries for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.84 server  Started ANALYSIS pass for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.84 server  Completed ANALYSIS pass for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.84 server  Log contains all committed transactions until 2011/12/21 16:47:07.84 for database sybsystemdb.
00:00000:00001:2011/12/22 16:05:23.84 server  Started REDO pass for database 'sybsystemdb'. The total number of log records to process is 1.
00:00000:00001:2011/12/22 16:05:23.84 server  Completed REDO pass for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.84 server  Recovery of database 'sybsystemdb' will undo incomplete nested top actions.
00:00000:00001:2011/12/22 16:05:23.84 server  Started recovery checkpoint for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.85 server  Completed recovery checkpoint for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.87 server  Started filling free space info for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.89 server  Completed filling free space info for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:23.90 server  Started cleaning up the default data cache for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:24.28 server  Completed cleaning up the default data cache for database 'sybsystemdb'.
00:00000:00001:2011/12/22 16:05:24.28 server  Boot Count: 8
00:00000:00001:2011/12/22 16:05:24.29 server  Checking external objects.
00:00000:00001:2011/12/22 16:05:24.31 server  The transaction log in the database 'sybsystemdb' will use I/O size of 2 Kb.
00:00000:00001:2011/12/22 16:05:24.69 server  Resident Node id: f7992c3e37c5
00:00000:00001:2011/12/22 16:05:24.69 server  Transaction coordinator initialized.
00:00000:00001:2011/12/22 16:05:24.69 server  Database 'sybsystemdb' is now online.
00:00000:00001:2011/12/22 16:05:24.72 server  Recovering database 'model'.
00:00000:00001:2011/12/22 16:05:24.73 server  Started estimating recovery log boundaries for database 'model'.
00:00000:00001:2011/12/22 16:05:24.75 server  Database 'model', checkpoint=(1015, 3), first=(17179870199, 3), last=(1015, 3).
00:00000:00001:2011/12/22 16:05:24.75 server  Completed estimating recovery log boundaries for database 'model'.
00:00000:00001:2011/12/22 16:05:24.75 server  Started ANALYSIS pass for database 'model'.
00:00000:00001:2011/12/22 16:05:24.75 server  Completed ANALYSIS pass for database 'model'.
00:00000:00001:2011/12/22 16:05:24.75 server  Log contains all committed transactions until 2011/12/21 16:47:07.84 for database model.
00:00000:00001:2011/12/22 16:05:24.75 server  Started REDO pass for database 'model'. The total number of log records to process is 1.
00:00000:00001:2011/12/22 16:05:24.75 server  Completed REDO pass for database 'model'.
00:00000:00001:2011/12/22 16:05:24.75 server  Recovery of database 'model' will undo incomplete nested top actions.
00:00000:00001:2011/12/22 16:05:24.75 server  Started recovery checkpoint for database 'model'.
00:00000:00001:2011/12/22 16:05:24.78 server  Completed recovery checkpoint for database 'model'.
00:00000:00001:2011/12/22 16:05:24.80 server  Started filling free space info for database 'model'.
00:00000:00001:2011/12/22 16:05:24.82 server  Completed filling free space info for database 'model'.
00:00000:00001:2011/12/22 16:05:24.83 server  Started cleaning up the default data cache for database 'model'.
00:00000:00001:2011/12/22 16:05:25.21 server  Completed cleaning up the default data cache for database 'model'.
00:00000:00001:2011/12/22 16:05:25.22 server  Checking external objects.
00:00000:00001:2011/12/22 16:05:25.26 server  The transaction log in the database 'model' will use I/O size of 2 Kb.
00:00000:00001:2011/12/22 16:05:25.65 server  Database 'model' is now online.
00:00000:00001:2011/12/22 16:05:25.65 server  The logical pagesize of the server is 2 Kb.
00:00000:00001:2011/12/22 16:05:25.65 server  0 dump conditions detected at boot time
00:00000:00001:2011/12/22 16:05:25.66 server  Clearing temp db
00:00000:00001:2011/12/22 16:05:32.41 server  Processed 3073 allocation unit(s) out of 30728 units (allocation page 786432). 10% completed.
00:00000:00001:2011/12/22 16:05:38.80 server  Processed 6146 allocation unit(s) out of 30728 units (allocation page 1573120). 20% completed.
00:00000:00001:2011/12/22 16:05:45.34 server  Processed 9219 allocation unit(s) out of 30728 units (allocation page 235980. 30% completed.
00:00000:00001:2011/12/22 16:05:51.82 server  Processed 12292 allocation unit(s) out of 30728 units (allocation page 3146496). 40% completed.
00:00000:00001:2011/12/22 16:05:58.43 server  Processed 15364 allocation unit(s) out of 30728 units (allocation page 393292. 50% completed.
00:00000:00001:2011/12/22 16:06:05.83 server  Processed 18437 allocation unit(s) out of 30728 units (allocation page 4719616). 60% completed.
00:00000:00001:2011/12/22 16:06:14.43 server  Processed 21510 allocation unit(s) out of 30728 units (allocation page 5506304). 70% completed.
00:00000:00001:2011/12/22 16:06:23.65 server  Processed 24583 allocation unit(s) out of 30728 units (allocation page 6292992). 80% completed.
00:00000:00001:2011/12/22 16:06:33.05 server  Processed 27656 allocation unit(s) out of 30728 units (allocation page 7079680). 90% completed.
00:00000:00001:2011/12/22 16:06:43.24 server  Processed 30728 allocation unit(s) out of 30728 units (allocation page 7866112). 100% completed.
00:00000:00001:2011/12/22 16:06:45.54 server  The transaction log in the database 'tempdb' will use I/O size of 2 Kb.
00:00000:00001:2011/12/22 16:06:45.94 server  Database 'tempdb' is now online.
00:00000:00001:2011/12/22 16:06:46.00 server  Recovering database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.01 server  Started estimating recovery log boundaries for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.02 server  Database 'sybsystemprocs', checkpoint=(111904, 20), first=(17179981088, 20), last=(111904, 20).
00:00000:00001:2011/12/22 16:06:46.02 server  Completed estimating recovery log boundaries for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.02 server  Started ANALYSIS pass for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.02 server  Completed ANALYSIS pass for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.02 server  Log contains all committed transactions until 2011/12/22 09:07:28.36 for database sybsystemprocs.
00:00000:00001:2011/12/22 16:06:46.02 server  Started REDO pass for database 'sybsystemprocs'. The total number of log records to process is 1.
00:00000:00001:2011/12/22 16:06:46.02 server  Completed REDO pass for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.02 server  Recovery of database 'sybsystemprocs' will undo incomplete nested top actions.
00:00000:00001:2011/12/22 16:06:46.02 server  Started recovery checkpoint for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.04 server  Completed recovery checkpoint for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.06 server  Started filling free space info for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.16 server  Completed filling free space info for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.17 server  Started cleaning up the default data cache for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.55 server  Completed cleaning up the default data cache for database 'sybsystemprocs'.
00:00000:00001:2011/12/22 16:06:46.56 server  Checking external objects.
00:00000:00001:2011/12/22 16:06:46.58 server  The transaction log in the database 'sybsystemprocs' will use I/O size of 2 Kb.
00:00000:00001:2011/12/22 16:06:46.96 server  Database 'sybsystemprocs' is now online.
00:00000:00011:2011/12/22 16:06:47.01 kernel  network name MZEVA, interface IPv4, address 192.168.1.6, type tcp, port 4100, filter NONE
01:00000:00000:2011/12/22 16:06:47.02 kernel  engine 1, os pid 3913  online
01:00000:00000:2011/12/22 16:06:47.03 kernel  Encryption provider initialization succeeded on engine 1.
02:00000:00000:2011/12/22 16:06:47.04 kernel  engine 2, os pid 3919  online
02:00000:00000:2011/12/22 16:06:47.05 kernel  Encryption provider initialization succeeded on engine 2.
03:00000:00000:2011/12/22 16:06:47.06 kernel  engine 3, os pid 3925  online
03:00000:00000:2011/12/22 16:06:47.06 kernel  Encryption provider initialization succeeded on engine 3.
04:00000:00000:2011/12/22 16:06:47.07 kernel  engine 4, os pid 3931  online
04:00000:00000:2011/12/22 16:06:47.08 kernel  Encryption provider initialization succeeded on engine 4.
05:00000:00000:2011/12/22 16:06:47.09 kernel  engine 5, os pid 3937  online
05:00000:00000:2011/12/22 16:06:47.09 kernel  Encryption provider initialization succeeded on engine 5.
06:00000:00000:2011/12/22 16:06:47.10 kernel  engine 6, os pid 3943  online
06:00000:00000:2011/12/22 16:06:47.11 kernel  Encryption provider initialization succeeded on engine 6.
07:00000:00000:2011/12/22 16:06:47.12 kernel  engine 7, os pid 3949  online
07:00000:00000:2011/12/22 16:06:47.13 kernel  Encryption provider initialization succeeded on engine 7.
08:00000:00000:2011/12/22 16:06:47.13 kernel  engine 8, os pid 3955  online
08:00000:00000:2011/12/22 16:06:47.14 kernel  Encryption provider initialization succeeded on engine 8.
09:00000:00000:2011/12/22 16:06:47.15 kernel  engine 9, os pid 3961  online
09:00000:00000:2011/12/22 16:06:47.16 kernel  Encryption provider initialization succeeded on engine 9.
10:00000:00000:2011/12/22 16:06:47.16 kernel  engine 10, os pid 3967  online
10:00000:00000:2011/12/22 16:06:47.17 kernel  Encryption provider initialization succeeded on engine 10.
11:00000:00000:2011/12/22 16:06:47.18 kernel  engine 11, os pid 3973  online
11:00000:00000:2011/12/22 16:06:47.19 kernel  Encryption provider initialization succeeded on engine 11.
12:00000:00000:2011/12/22 16:06:47.20 kernel  engine 12, os pid 3979  online
12:00000:00000:2011/12/22 16:06:47.20 kernel  Encryption provider initialization succeeded on engine 12.
13:00000:00000:2011/12/22 16:06:47.21 kernel  engine 13, os pid 3985  online
13:00000:00000:2011/12/22 16:06:47.22 kernel  Encryption provider initialization succeeded on engine 13.
14:00000:00000:2011/12/22 16:06:47.23 kernel  engine 14, os pid 3991  online
14:00000:00000:2011/12/22 16:06:47.23 kernel  Encryption provider initialization succeeded on engine 14.
15:00000:00000:2011/12/22 16:06:47.24 kernel  engine 15, os pid 3997  online
15:00000:00000:2011/12/22 16:06:47.25 kernel  Encryption provider initialization succeeded on engine 15.
16:00000:00000:2011/12/22 16:06:47.26 kernel  engine 16, os pid 4003  online
16:00000:00000:2011/12/22 16:06:47.27 kernel  Encryption provider initialization succeeded on engine 16.
17:00000:00000:2011/12/22 16:06:47.27 kernel  engine 17, os pid 4009  online
17:00000:00000:2011/12/22 16:06:47.28 kernel  Encryption provider initialization succeeded on engine 17.
18:00000:00000:2011/12/22 16:06:47.29 kernel  engine 18, os pid 4015  online
18:00000:00000:2011/12/22 16:06:47.30 kernel  Encryption provider initialization succeeded on engine 18.
19:00000:00000:2011/12/22 16:06:47.31 kernel  engine 19, os pid 4021  online
19:00000:00000:2011/12/22 16:06:47.31 kernel  Encryption provider initialization succeeded on engine 19.
00:00000:00001:2011/12/22 16:06:47.62 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.64 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.67 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.69 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.72 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.74 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.77 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.79 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.82 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.84 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.87 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.89 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.91 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.94 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.96 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:47.99 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.01 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.04 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.06 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.09 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.11 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.14 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.16 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.19 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.21 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.24 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.26 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.29 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.31 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.34 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.36 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.39 server  The wash size of the 2K buffer pool in cache default data cache has been changed from 52428 Kb to 31458 Kb due to a change in the size of the pool.
00:00000:00001:2011/12/22 16:06:48.43 server  Recovery has tuned the size of '16K' pool in 'default data cache' to benefit recovery performance. The original configuration will be restored at the end of recovery.
00:00000:00001:2011/12/22 16:06:48.43 server  Recovery has tuned the size of '2K' pool in 'default data cache' to benefit recovery performance. The original configuration will be restored at the end of recovery.
00:00000:00001:2011/12/22 16:06:48.43 server  Recovery has tuned the '16K' pool in 'default data cache' by changing its 'local async prefetch limit' from 10 to 80. The original configuration will be restored at the end of recovery.
00:00000:00001:2011/12/22 16:06:48.43 server  Recovery has tuned the '2K' pool in 'default data cache' by changing its 'local async prefetch limit' from 10 to 80. The original configuration will be restored at the end of recovery.
00:00000:00001:2011/12/22 16:06:48.43 server  The server will recover databases serially.
00:00000:00001:2011/12/22 16:06:48.53 server  Starting diagnostics for read failure:
00:00000:00001:2011/12/22 16:06:48.53 server  Device Information:

sybman

最后两行是关键，可是你没有把错误信息贴出来。
.
00:00000:00001:2011/12/22 16:06:48.53 server  Starting diagnostics for read failure:
00:00000:00001:2011/12/22 16:06:48.53 server  Device Information:

zhaopingzi

楼主可以尝试做下 rebuild log

hobbylu

你尝试过-32768模式启动数据库没有？

sl0325

00:00000:00001:2011/12/22 16:06:48.53 server  Starting diagnostics for read failure:
00:00000:00001:2011/12/22 16:06:48.53 server  Device Information:
                                 Device number = 11
                                 Phyname = /opt/hisdata/bsrun6.dat
00:00000:00001:2011/12/22 16:06:48.53 server  Buffer Information:
                                 Buf addr = 0x0x406f5c1a8, Mass addr = 0x0x406f5c1a8
                                 Buf pageno = 43233159, Mass pageno = 43233159, dbid = 4
00:00000:00001:2011/12/22 16:06:48.53 server  
                                 Buf virtpg = 43233159, Mass virtpg = 4833159
                                 Buf stat = 0x1, Mass stat = 0x1008
                                 Mass keep = 1, Mass awaited = 0
00:00000:00001:2011/12/22 16:06:48.53 server  Page Information from first read attempt: Page read from disk ppageno = 45793159, pptnid = 1409437064, pindid = 0 pnextpg = 45793151, pprevpg = 46005240 plevel = 0, pstat = 0x11 pts_hi = 3, pts_lo = 251859148
00:00000:00001:2011/12/22 16:06:48.53 server  Page Information from second read attempt: Page read from disk ppageno = 45793159, pptnid = 1409437064, pindid = 0 pnextpg = 45793151, pprevpg = 46005240 plevel = 0, pstat = 0x11 pts_hi = 3, pts_lo = 251859148
00:00000:00001:2011/12/22 16:06:48.53 server  SDES Information:
                                 dbid = 4, objid = 14, sptnid = 14 scur.pageid = 43233159
                                 sstat = 0x0, sstat2 = 0x0
                                 suid = 1, cacheid = 0
00:00000:00001:2011/12/22 16:06:48.53 server  PSS Information:
                                 pstat = 0x1000, pcurdb = 1, pspid = 1
                                 p2stat = 0x1110, p3stat = 0x904
                                 plasterror = 0, preverror = 0, pattention = 0
00:00000:00001:2011/12/22 16:06:48.53 server  End diagnostics for read failure:
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x00000000012fcd13 pcstkwalk+0x323()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x00000000012fc7c0 ucstkgentrace+0x170()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000001295fa9 ucbacktrace+0x69()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000971b1d wrongpage__print_diagnostic+0x2ed()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000971086 wrongpage+0x2c6()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x000000000096ddad getpage_with_validation+0xbed()
00:00000:00001:2011/12/22 16:06:48.56 kernel  [Handler pc: 0x0x0000000000785d60 hdl_backout installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x00000000008300de pg_fill_supergam+0x12e()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x00000000008074ae db_fill+0x27e()
00:00000:00001:2011/12/22 16:06:48.56 kernel  [Handler pc: 0x0x0000000000785da0 hdl_backout_msg installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x000000000080f48c dbt(float, int, long, long)+0x16c()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x000000000080d84b dbt_get+0x7fb()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000802fae dbcontext_change+0x2e()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000803565 usedb+0x35()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000a7c75a boot__recover_and_online+0x3a()
00:00000:00001:2011/12/22 16:06:48.56 kernel  [Handler pc: 0x0x0000000000a89940 rec__caller_hdlr installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000a7e909 rec__boot_recover_dbs+0xe9()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000a7abd3 rec_run_parallel_recovery+0x2d3()
00:00000:00001:2011/12/22 16:06:48.56 kernel  [Handler pc: 0x0x0000000000a89940 rec__caller_hdlr installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000a7e424 dorecover+0x174()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x0000000000727b2d ds__recoverdbs+0x52d()
00:00000:00001:2011/12/22 16:06:48.56 kernel  pc: 0x00000000007214d7 dsinit+0xad7()
00:00000:00001:2011/12/22 16:06:48.56 kernel  end of stack trace, spid 1, kpid 196611, suid 0
00:00000:00001:2011/12/22 16:06:48.57 server  Recovering database 'bsrun201112'.
00:00000:00001:2011/12/22 16:06:48.57 server  Started estimating recovery log boundaries for database 'bsrun201112'.
00:00000:00001:2011/12/22 16:06:48.60 server  Starting diagnostics for read failure:
00:00000:00001:2011/12/22 16:06:48.60 server  Device Information:
                                 Device number = 8
                                 Phyname = /opt/hisdata/bsrun4.dat
00:00000:00001:2011/12/22 16:06:48.60 server  Buffer Information:
                                 Buf addr = 0x0x40b629200, Mass addr = 0x0x40b629200
                                 Buf pageno = 25465968, Mass pageno = 25465968, dbid = 4
00:00000:00001:2011/12/22 16:06:48.60 server  
                                 Buf virtpg = 25465968, Mass virtpg = 2425968
                                 Buf stat = 0x1, Mass stat = 0x1008
                                 Mass keep = 1, Mass awaited = 0
00:00000:00001:2011/12/22 16:06:48.60 server  Page Information from first read attempt: Page read from disk ppageno = 28025968, pptnid = 1409437064, pindid = 0 pnextpg = 28025958, pprevpg = 27980487 plevel = 0, pstat = 0x11 pts_hi = 2, pts_lo = 621732403
00:00000:00001:2011/12/22 16:06:48.60 server  Page Information from second read attempt: Page read from disk ppageno = 28025968, pptnid = 1409437064, pindid = 0 pnextpg = 28025958, pprevpg = 27980487 plevel = 0, pstat = 0x11 pts_hi = 2, pts_lo = 621732403
00:00000:00001:2011/12/22 16:06:48.60 server  SDES Information:
                                 dbid = 4, objid = 8, sptnid = 8 scur.pageid = 25465968
                                 sstat = 0x4001, sstat2 = 0x4000000
                                 suid = 1, cacheid = 0
00:00000:00001:2011/12/22 16:06:48.60 server  PSS Information:
                                 pstat = 0x1000, pcurdb = 4, pspid = 1
                                 p2stat = 0x1110, p3stat = 0x10000804
                                 plasterror = 0, preverror = 0, pattention = 0
00:00000:00001:2011/12/22 16:06:48.60 server  End diagnostics for read failure:
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x00000000012fcd13 pcstkwalk+0x323()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x00000000012fc7c0 ucstkgentrace+0x170()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000001295fa9 ucbacktrace+0x69()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000971b1d wrongpage__print_diagnostic+0x2ed()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000971086 wrongpage+0x2c6()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x000000000096ddad getpage_with_validation+0xbed()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000972083 getrow+0x43()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x00000000009a2f1f apl__startscan+0x14ef()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x00000000009a12f1 startptnscan+0x381()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x00000000009b8b32 xsc__syslogs_setscan+0x1b2()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x00000000009b82f8 xls_startscan+0x178()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x000000000093c0e3 ckpt_get_record+0x53()
00:00000:00001:2011/12/22 16:06:48.60 kernel  [Handler pc: 0x0x0000000000a857f0 rec_handle installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000a81aaa rec_logbounds+0x2ba()
00:00000:00001:2011/12/22 16:06:48.60 kernel  [Handler pc: 0x0x0000000000a857f0 rec_handle installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000a7f995 recovery+0x4a5()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000a7c7ce boot__recover_and_online+0xae()
00:00000:00001:2011/12/22 16:06:48.60 kernel  [Handler pc: 0x0x0000000000a89940 rec__caller_hdlr installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000a7e909 rec__boot_recover_dbs+0xe9()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000a7abd3 rec_run_parallel_recovery+0x2d3()
00:00000:00001:2011/12/22 16:06:48.60 kernel  [Handler pc: 0x0x0000000000a89940 rec__caller_hdlr installed by the following function:-]
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000a7e424 dorecover+0x174()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x0000000000727b2d ds__recoverdbs+0x52d()
00:00000:00001:2011/12/22 16:06:48.60 kernel  pc: 0x00000000007214d7 dsinit+0xad7()
00:00000:00001:2011/12/22 16:06:48.60 kernel  end of stack trace, spid 1, kpid 196611, suid 0
00:00000:00001:2011/12/22 16:06:48.61 server  Error: 695, Severity: 21, State: 1
00:00000:00001:2011/12/22 16:06:48.61 server  An attempt was made to read logical page '25465968' for database 'bsrun201112' (4), object 'syslogs' (, index 'syslogs' (0), partition 'syslogs_8' ( from disk. Wrong logical page '28025968' was brought into cache 'default data cache'.
00:00000:00001:2011/12/22 16:06:48.62 server  Error: 3414, Severity: 21, State: 1
00:00000:00001:2011/12/22 16:06:48.62 server  Database 'bsrun201112' (dbid 4): Recovery failed. Check the ASE errorlog for further information as to the cause.
00:00000:00001:2011/12/22 16:06:48.62 server  Recovery has restored the value of 'local async prefetch limit' for '16K' pool in 'default data cache' from '80' to 'DEFAULT'.
00:00000:00001:2011/12/22 16:06:48.62 server  Recovery has restored the value of 'local async prefetch limit' for '2K' pool in 'default data cache' from '80' to 'DEFAULT'.
00:00000:00001:2011/12/22 16:06:49.17 server  Bufunhash: Buffer 0x40b629200 from database id 4 has page number 28025968
in the page header and page number 25465968 in the buffer header. Buffer will be unhashed,
no action is necessary. Printing headers for diagnostics:
00:00000:00001:2011/12/22 16:06:49.17 server  Mass: 0x0x40b629200 Buffer: 0x0x40b629200
-Mass pgno: 25465968 vdev: 8 virtpg: 2425968 dbid: 4 stat: 0x4001000 keep: 0 awaited: 0
tripsleft: 0, flushseq: 0 dirty seq: 0 filthyseq: 0
-Buf pgno: 25465968 vdev: 8 virtpg: 2425968 stat: 0x1 bpage: 0xbabbd000 linked: ( lru)
00:00000:00001:2011/12/22 16:06:49.17 server  Page: 0x3babbd000 pageno: 28025968 objid: 1409437064 indid: 0
- nextpg: 28025958 prevpg: 27980487 pstat: 0x11 ts: (2, 621732403) nextrno: 10
- freeoff: 1503 minlen: 96 level: 0
00:00000:00001:2011/12/22 16:06:49.20 server  Recovery has restored the original size for '16K' pool and '2K' pool in 'default data cache'.
00:00000:00001:2011/12/22 16:06:49.20 server  Recovery complete.
00:00000:00001:2011/12/22 16:06:49.20 server  ASE's default unicode sort order is 'binary'.
00:00000:00001:2011/12/22 16:06:49.20 server  ASE's default sort order is:
00:00000:00001:2011/12/22 16:06:49.20 server          'bin_iso_1' (ID = 50)
00:00000:00001:2011/12/22 16:06:49.20 server  on top of default character set:
00:00000:00001:2011/12/22 16:06:49.20 server          'iso_1' (ID = 1).
00:00000:00001:2011/12/22 16:06:49.20 server  Master device size: 300 megabytes, or 153600 virtual pages. (A virtual page is 2048 bytes.)
00:00000:00001:2011/12/22 16:06:49.21 kernel  Warning: Cannot set console to nonblocking mode, switching to blocking mode.
01:00000:00014:2011/12/22 16:09:53.29 kernel  Initializing virtual device 19, '/opt/hisdata/test1.dat' with dsync 'on'.
01:00000:00014:2011/12/22 16:09:53.29 kernel  Virtual device 19 started using asynchronous i/o.
01:00000:00014:2011/12/22 16:09:53.29 kernel  Initializing device /opt/hisdata/test1.dat from offset 0 with zeros.
01:00000:00014:2011/12/22 16:09:53.37 kernel  Finished initialization.
01:00000:00014:2011/12/22 16:10:10.93 kernel  Initializing virtual device 20, '/opt/hisdata/test_log1.dat' with dsync 'on'.
01:00000:00014:2011/12/22 16:10:10.93 kernel  Virtual device 20 started using asynchronous i/o.
01:00000:00014:2011/12/22 16:10:10.93 kernel  Initializing device /opt/hisdata/test_log1.dat from offset 0 with zeros.
01:00000:00014:2011/12/22 16:10:11.02 kernel  Finished initialization.

-32768模式启动数据库后，不能USE db
rebuild log也失败了。
andkylee的帖子 ：ASE15.0.3升级后启动不起来了中使用了修改设备文件的方法，但不知道如何去按那个方法操作解决我现在的故障。
请高手们给个建议！！

Eisen

昨天无聊，在自己机器上搞了个库，然后把log设备删了，成功重现你的错误，但是在采用log设备覆盖然后rebuild log后成功恢复。除了在途transaction丢失外没有丢失任何已commit数据。
接着无聊又再次配置了复制，并重玩一趟，一样成功。按说照大家给你的做法应该没错了，除非——你的新log设备大小或生成版本不对。

sl0325

按照大家的建议做rebuild_log时，kernel setting console to nonblocking mode.failed attempt to remove old log extents.
rebuild失败，接着该如何处理呢？

sl0325

启动日志报错如下：

00:00000:00001:2011/12/22 16:06:48.61 server  Error: 695, Severity: 21, State: 1
00:00000:00001:2011/12/22 16:06:48.61 server  An attempt was made to read logical page '25465968' for database 'bsrun201112' (4), object 'syslogs' (, index 'syslogs' (0), partition 'syslogs_8' ( from disk. Wrong logical page '28025968' was brought into cache 'default data cache'.
00:00000:00001:2011/12/22 16:06:48.62 server  Error: 3414, Severity: 21, State: 1
00:00000:00001:2011/12/22 16:06:48.62 server  Database 'bsrun201112' (dbid 4): Recovery failed. Check the ASE errorlog for further information as to the cause.
00:00000:00001:2011/12/22 16:06:48.62 server  Recovery has restored the value of 'local async prefetch limit' for '16K' pool in 'default data cache' from '80' to 'DEFAULT'.
00:00000:00001:2011/12/22 16:06:48.62 server  Recovery has restored the value of 'local async prefetch limit' for '2K' pool in 'default data cache' from '80' to 'DEFAULT'.
00:00000:00001:2011/12/22 16:06:49.17 server  Bufunhash: Buffer 0x40b629200 from database id 4 has page number 28025968
in the page header and page number 25465968 in the buffer header. Buffer will be unhashed,
no action is necessary. Printing headers for diagnostics:
00:00000:00001:2011/12/22 16:06:49.17 server  Mass: 0x0x40b629200 Buffer: 0x0x40b629200
-Mass pgno: 25465968 vdev: 8 virtpg: 2425968 dbid: 4 stat: 0x4001000 keep: 0 awaited: 0
tripsleft: 0, flushseq: 0 dirty seq: 0 filthyseq: 0
-Buf pgno: 25465968 vdev: 8 virtpg: 2425968 stat: 0x1 bpage: 0xbabbd000 linked: ( lru)
00:00000:00001:2011/12/22 16:06:49.17 server  Page: 0x3babbd000 pageno: 28025968 objid: 1409437064 indid: 0
- nextpg: 28025958 prevpg: 27980487 pstat: 0x11 ts: (2, 621732403) nextrno: 10
- freeoff: 1503 minlen: 96 level: 0

sybman

如果rebuild_log不行，建议你用 dbcc　save_rebuild_log()试试看。

andkylee

Eisen，采用log设备覆盖， 你这个log设备从哪里来的？