突然停电导致SYBASE无法启动

文章出th

因为突然停电,当时正在做业务,导致SYBASE无法启动.平台为WIDNOS 2000 SERVER,数据库为SYBASE12.5.3.报错日志为:


/14 09:23:11.25 kernel  Use license file c:\sybase\SYSAM-1_0\licenses\license.dat.
00:00000:00000:2006/09/14 09:23:11.54 kernel  Checked out license ASE_SERVER
00:00000:00000:2006/09/14 09:23:11.59 kernel  Adaptive Server Enterprise Edition
00:00000:00000:2006/09/14 09:23:14.09 kernel  Using config area from primary master device.
00:00000:00000:2006/09/14 09:23:14.71 kernel  Warning: Using default file 'c:\sybase\TIANKE.cfg' since a configuration file was not specified. Specify a configuration file name in the RUNSERVER file to avoid this message.
00:00000:00000:2006/09/14 09:23:24.10 kernel  Using 100000 file descriptors.
00:00000:00000:2006/09/14 09:23:27.73 kernel  Adaptive Server Enterprise/12.5.0.2/EBF 10730 IR ONE-OFF/P/NT (IX86)/OS 4.0/rel12502/1851/32-bit/OPT/Mon Nov 04 23:01:14 2002
00:00000:00000:2006/09/14 09:23:27.76 kernel  Confidential property of Sybase, Inc.
00:00000:00000:2006/09/14 09:23:27.76 kernel  Copyright 1987, 2002
00:00000:00000:2006/09/14 09:23:27.76 kernel  Sybase, Inc.  All rights reserved.
00:00000:00000:2006/09/14 09:23:27.76 kernel  Unpublished rights reserved under U.S. copyright laws.
00:00000:00000:2006/09/14 09:23:27.76 kernel  
00:00000:00000:2006/09/14 09:23:27.76 kernel  This software contains confidential and trade secret information of Sybase,
00:00000:00000:2006/09/14 09:23:27.76 kernel  Inc.   Use,  duplication or disclosure of the software and documentation by
00:00000:00000:2006/09/14 09:23:27.76 kernel  the  U.S.  Government  is  subject  to  restrictions set forth in a license
00:00000:00000:2006/09/14 09:23:27.76 kernel  agreement  between  the  Government  and  Sybase,  Inc.  or  other  written
00:00000:00000:2006/09/14 09:23:27.76 kernel  agreement  specifying  the  Government's rights to use the software and any
00:00000:00000:2006/09/14 09:23:27.76 kernel  applicable FAR provisions, for example, FAR 52.227-19.
00:00000:00000:2006/09/14 09:23:27.76 kernel  Sybase, Inc. One Sybase Drive, Dublin, CA 94568, USA
00:00000:00000:2006/09/14 09:23:27.76 kernel  Using 'c:\sybase\TIANKE.cfg' for configuration information.
00:00000:00000:2006/09/14 09:23:27.82 kernel  Logging ASE messages in file 'c:\sybase\ASE-12_5\install\errorlog'.
00:00000:00000:2006/09/14 09:23:32.71 kernel  Initializing MSDTC Connection
00:00000:00000:2006/09/14 09:23:53.40 kernel  MSDTC Initialization succeeded
00:00000:00000:2006/09/14 09:23:53.71 kernel  kdcl_sess_open: connectivity library supports master/query syntax.
00:00000:00000:2006/09/14 09:23:53.73 kernel  Directory Control Layer (DCL) using directory driver: InterfacesDriver
00:00000:00000:2006/09/14 09:23:53.76 kernel  *** WARNING ******************
00:00000:00000:2006/09/14 09:23:53.76 kernel  Operating system may favor the system cache when memory is at a premium.
To disable this warning, set the registry parameter 'LargeSystemCache' to 0 in HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Control\Session Manager\Memory Management.
00:00000:00000:2006/09/14 09:23:53.78 kernel  SQL Server NT process id is 0x338.
00:00000:00000:2006/09/14 09:23:53.79 kernel  SQL Server was started as a Service.
00:00000:00000:2006/09/14 09:23:53.84 kernel  SSL Plus v3.0.9 security modules loaded successfully.
00:00000:00000:2006/09/14 09:23:53.87 kernel  Network and device connection limit is 99992.
00:00000:00000:2006/09/14 09:23:54.42 server  Number of blocks left for proc headers: 51152.
00:00000:00000:2006/09/14 09:23:54.50 server  Proc header memory allocated 25576 pages for each per engine cache
00:00000:00000:2006/09/14 09:23:54.75 server  Memory allocated for the default data cache cachelet 1: 8192 Kb
00:00000:00000:2006/09/14 09:23:54.76 server  Size of the 2K memory pool: 8192 Kb
00:00000:00000:2006/09/14 09:23:54.76 server  Memory allocated for the cache1 cachelet 1: 139668 Kb
00:00000:00000:2006/09/14 09:23:54.76 server  Size of the 2K memory pool: 139668 Kb
00:00000:00000:2006/09/14 09:23:54.78 kernel  Initializing virtual device 0, 'c:\sybase\data\master.dat' with dsync 'on'.
00:00000:00000:2006/09/14 09:23:54.79 kernel  Virtual device 0 started using asynchronous i/o.
00:00000:00000:2006/09/14 09:23:54.79 kernel  Worker Thread Manager is not enabled for use in SQL Server.
00:00000:00000:2006/09/14 09:23:54.81 kernel  Either the config parameter 'use security services' is set to 0, or ASE does not support use of external security mechanisms on this platform. The Security Control Layer will not be initialized. No external security mechanisms will be supported.
00:00000:00000:2006/09/14 09:23:54.84 kernel  engine 0, os pid 224  online
00:00000:00000:2006/09/14 09:23:54.85 server  No active traceflags
00:00000:00001:2006/09/14 09:23:55.03 kernel  Component Integration Services loaded; version 'Component Integration Services/12.5.0.2/EBF 10730 IR ONE-OFF/P/NT (IX86)/OS 4.0/rel12502/1851/32-bit/OPT/Mon Nov 04 23:01:14 2002'.
00:00000:00001:2006/09/14 09:23:55.03 kernel  Component Integration Services: using 'Sybase Client-Library/12.5/P-EBF10419/PC Intel/BUILD125-015/OPT/Wed Jul 03 10:38:06 2002'
00:00000:00001:2006/09/14 09:23:55.03 server  Opening Master Database ...
00:00000:00001:2006/09/14 09:23:55.28 server  Loading SQL Server's default sort order and character set
00:00000:00001:2006/09/14 09:23:55.29 server  Loaded default Unilib conversion handle.
00:00000:00001:2006/09/14 09:23:55.35 server  Recovering database 'master'.
00:00000:00001:2006/09/14 09:23:55.48 server  Redo pass of recovery has processed 2 committed and 0 aborted transactions.
00:00000:00001:2006/09/14 09:23:55.65 server  Checking external objects.
00:00000:00001:2006/09/14 09:23:55.76 server  Database 'master' is now online.
00:00000:00001:2006/09/14 09:23:55.76 server  The transaction log in the database 'master' will use I/O size of 2 Kb.
00:00000:00001:2006/09/14 09:23:55.78 server  Warning: ASE_HA has no valid license and therefore is not initialized.
00:00000:00001:2006/09/14 09:23:55.79 server  server is unnamed
00:00000:00001:2006/09/14 09:23:55.81 server  Activating disk 'dev01' of size 5120000 bytes.
00:00000:00001:2006/09/14 09:23:55.81 kernel  Initializing virtual device 2, 'D:\sybasedata\dev01.dat' with dsync 'on'.
00:00000:00001:2006/09/14 09:23:55.81 kernel  Virtual device 2 started using asynchronous i/o.
00:00000:00001:2006/09/14 09:23:55.81 server  Activating disk 'log01' of size 1024000 bytes.
00:00000:00001:2006/09/14 09:23:55.81 kernel  Initializing virtual device 3, 'D:\sybasedata\log01.dat' with dsync 'on'.
00:00000:00001:2006/09/14 09:23:55.81 kernel  Virtual device 3 started using asynchronous i/o.
00:00000:00001:2006/09/14 09:23:55.82 server  Activating disk 'sysprocsdev' of size 102400 bytes.
00:00000:00001:2006/09/14 09:23:55.82 kernel  Initializing virtual device 1, 'c:\sybase\data\sybprocs.dat' with dsync 'on'.
00:00000:00001:2006/09/14 09:23:55.84 kernel  Virtual device 1 started using asynchronous i/o.
00:00000:00001:2006/09/14 09:23:55.84 server  Activating disk 'tempdb' of size 1024000 bytes.
00:00000:00001:2006/09/14 09:23:55.84 kernel  Initializing virtual device 4, 'D:\sybasedata\tempdb.dat' with dsync 'on'.
00:00000:00001:2006/09/14 09:23:55.84 kernel  Virtual device 4 started using asynchronous i/o.
00:00000:00001:2006/09/14 09:23:56.01 server  Recovering database 'sybsystemdb'.
00:00000:00001:2006/09/14 09:23:56.01 server  Redo pass of recovery has processed 0 committed and 1 aborted transactions.
00:00000:00001:2006/09/14 09:23:56.03 server  Boot Count: 783
00:00000:00001:2006/09/14 09:23:57.09 server  Checking external objects.
00:00000:00001:2006/09/14 09:23:57.10 server  The transaction log in the database 'sybsystemdb' will use I/O size of 2 Kb.
00:00000:00001:2006/09/14 09:23:57.10 server  Database 'sybsystemdb' is now online.
00:00000:00001:2006/09/14 09:23:57.14 server  Recovering database 'model'.
00:00000:00001:2006/09/14 09:23:57.15 server  Checking external objects.
00:00000:00001:2006/09/14 09:23:57.15 server  The transaction log in the database 'model' will use I/O size of 2 Kb.
00:00000:00001:2006/09/14 09:23:57.17 server  Database 'model' is now online.
00:00000:00001:2006/09/14 09:23:57.17 server  The logical pagesize of the server is 2 Kb.
00:00000:00001:2006/09/14 09:23:57.17 server  0 dump conditions detected at boot time
00:00000:00001:2006/09/14 09:23:57.18 server  Resident Node id: ee1c40df3b2b
00:00000:00001:2006/09/14 09:23:58.18 server  Transaction coordinator initialized.
00:00000:00001:2006/09/14 09:23:58.20 server  Clearing temp db
00:00000:00001:2006/09/14 09:24:11.00 server  The transaction log in the database 'tempdb' will use I/O size of 2 Kb.
00:00000:00001:2006/09/14 09:24:11.21 server  Database 'tempdb' is now online.
00:00000:00001:2006/09/14 09:24:11.76 server  Recovering database 'sybsystemprocs'.
00:00000:00001:2006/09/14 09:24:12.26 server  Checking external objects.
00:00000:00001:2006/09/14 09:24:12.48 server  The transaction log in the database 'sybsystemprocs' will use I/O size of 2 Kb.
00:00000:00001:2006/09/14 09:24:12.87 server  Database 'sybsystemprocs' is now online.
00:00000:00002:2006/09/14 09:24:31.46 kernel  network name 192.168.150.10, type ether, port 5000, filter NONE
00:00000:00001:2006/09/14 09:24:35.37 server  Recovering database 'market50'.
00:00000:00001:2006/09/14 09:24:39.75 server  Redo pass: 6000 records done (6%); 92015 records left.
00:00000:00001:2006/09/14 09:24:40.01 server  Redo pass: 12000 records done (12%); 86015 records left.
00:00000:00001:2006/09/14 09:24:40.21 server  Redo pass: 18000 records done (18%); 80015 records left.
00:00000:00001:2006/09/14 09:24:40.35 server  Redo pass: 24000 records done (24%); 74015 records left.
00:00000:00001:2006/09/14 09:24:40.64 server  Redo pass: 30000 records done (30%); 68015 records left.
00:00000:00001:2006/09/14 09:24:40.89 server  Redo pass: 36000 records done (36%); 62015 records left.
00:00000:00001:2006/09/14 09:24:41.04 server  Redo pass: 42000 records done (42%); 56015 records left.
00:00000:00001:2006/09/14 09:24:41.28 server  Redo pass: 48000 records done (48%); 50015 records left.
00:00000:00001:2006/09/14 09:24:41.54 server  Redo pass: 54000 records done (55%); 44015 records left.
00:00000:00001:2006/09/14 09:24:43.03 server  Redo pass: 60000 records done (61%); 38015 records left.
00:00000:00001:2006/09/14 09:24:44.20 server  Redo pass: 66000 records done (67%); 32015 records left.
00:00000:00001:2006/09/14 09:24:44.79 server  Redo pass: 72000 records done (73%); 26015 records left.
00:00000:00001:2006/09/14 09:24:45.03 server  Redo pass: 78000 records done (79%); 20015 records left.
00:00000:00001:2006/09/14 09:24:45.39 server  Redo pass: 84000 records done (85%); 14015 records left.
00:00000:00001:2006/09/14 09:24:46.04 server  Redo pass: 90000 records done (91%); 8015 records left.
00:00000:00001:2006/09/14 09:24:46.35 server  Redo pass: 96000 records done (97%); 2015 records left.
00:00000:00001:2006/09/14 09:24:46.50 server  Error: 3474, Severity: 21, State: 1
00:00000:00001:2006/09/14 09:24:46.51 server  During redo the page timestamp value is less than old timestamp from log. Page #=960369, object id = 210148813, page timestamp=0000 43c1f50c. Log old timestamp=0000 43cc8373.
00:00000:00001:2006/09/14 09:24:46.53 server  Error: 3414, Severity: 21, State: 1
00:00000:00001:2006/09/14 09:24:46.53 server  Database 'market50' (dbid 4): Recovery failed. Check the SQL Server errorlog for further information as to the cause.
00:00000:00001:2006/09/14 09:24:46.53 server  Recovery complete.
00:00000:00001:2006/09/14 09:24:46.53 server  SQL Server's default unicode sort order is 'binary'.
00:00000:00001:2006/09/14 09:24:46.54 server  SQL Server's default sort order is:
00:00000:00001:2006/09/14 09:24:46.54 server          'bin_iso_1' (ID = 50)
00:00000:00001:2006/09/14 09:24:46.54 server  on top of default character set:
00:00000:00001:2006/09/14 09:24:46.54 server          'iso_1' (ID = 1).
00:00000:00001:2006/09/14 09:24:46.54 server  Master device size: 30 megabytes, or 15360 virtual pages. (A virtual page is 2048 bytes.)
00:00000:00013:2006/09/14 09:25:07.37 server  Error: 7214, Severity: 11, State: 1
00:00000:00013:2006/09/14 09:25:07.39 server  No site name received in server login packet.  Server login rejected.
00:00000:00012:2006/09/14 09:25:07.42 server  Error: 7221, Severity: 14, State: 1
00:00000:00012:2006/09/14 09:25:07.42 server  Login to site 'TIANKE_XP' failed.
 
 
解决办法:
 
重建数据库日志,方法如下:
　(1)赋予sa用户sybase_ts_role的角色
isql -Usa -P
1>sp_role "grant","sybase_ts_role",sa
2>go
1>quit


(2)将数据库置为"bypass recovery"状态
isql -Usa -P
1>sp_configure "allow updates",1
2>go
1>use master
2>go
1>update sysdatabases set status=-32768
2>where name="database_name"
3>go
1>shutdown with nowait
2>go


(3)rebuild数据库日志
重启Server
isql -Usa -P
1>use master
2>go
1>dbcc rebuild_log(database_name,1,1)
2>go
1>shutdown with nowait
2>go


(4)重启server
1>use master
2>go
1>update sysdatabases set status=0 where name="database_name"
2>go
1>sp_configure "allow updates",0
2>go
1>shutdown with nowait
2>go


(5)在重启server之后,如果数据库恢复正常，rebuild log工作将会成功完成，否则要恢复数据库备份，使用dump database或bcp命令。