Подвисла база : Oracle

Сегодня ночью подвисла база и наругалась:
Тип события: Уведомление
Источник события: Oracle.dbcentr
Категория события: Отсутствует
Код события: 34
Дата: 24.02.2009
Время: 2:06:06
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
Audit trail: ACTION : 'CONNECT' DATABASE USER: '/' PRIVILEGE : SYSDBA CLIENT USER: SYSTEM CLIENT TERMINAL: CENTER7 STATUS: 0 .
Тип события: Ошибка
Источник события: Supermag Server
Категория события: Сервер
Код события: 2058
Дата: 24.02.2009
Время: 2:06:06
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
Фатальная ошибка в управляющем потоке базы данных dbcentr. Идентификационная строка: 'IsSMControllerStarted'. Дополнительную информацию см. в следующем сообщении.

Тип события: Ошибка
Источник события: Supermag Server
Категория события: Сервер
Код события: 2052
Дата: 24.02.2009
Время: 2:06:06
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
В работе сервера произошел сбой. Сообщения об ошибках см. ниже.
Запись 1. Код=80004005h (1089) [Microsoft OLE DB Provider for Oracle]:
ORA-01089: immediate shutdown in progress - no operations are permitted

Запись 2. Код=80004005h (0) [SmLibaryBase trace]:
{ call Supermag.core.NextMessage(?, ?, ?, ?, ?) }
Params:
{0} (null)[0](0,0): vt=0 value=<not printable>
{1} (null)[1000](0,0): vt=0 value=<not printable>
{2} (null)[0](0,0): vt=3 value=-20000
{3} (null)[1000](0,0): vt=8 value=116763515230615DFA0FAA2D9E5A5F109EA54F14FCF33DBCENTR*23EbdWfx5_+Q%21422052936856HEXCH
{4} (null)[1000](0,0): vt=8 value=SM2000Ctrl000F000E0001
%3 %4 %5

Тип события: Ошибка
Источник события: Supermag Server
Категория события: Сервер
Код события: 2060
Дата: 24.02.2009
Время: 2:06:06
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
Ошибка при обработке запроса к сессии монитора. Код запроса 100, параметры '_', адрес ответа ''. Дополнительную информацию см. в следующих сообщениях.

Тип события: Ошибка
Источник события: Supermag Server
Категория события: Сервер
Код события: 2052
Дата: 24.02.2009
Время: 2:06:06
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
В работе сервера произошел сбой. Сообщения об ошибках см. ниже.
Запись 1. Код=80004005h (1089) [Microsoft OLE DB Provider for Oracle]:
ORA-01089: immediate shutdown in progress - no operations are permitted

Запись 2. Код=80004005h (0) [Microsoft OLE DB Provider for Oracle]:
Неопознанная ошибка

Запись 3. Код=80004005h (0) [SmLibaryBase trace]:
begin Supermag.Core.StopSMController; end;
%4 %5
Тип события: Ошибка
Источник события: Supermag Server
Категория события: Сервер
Код события: 2055
Дата: 24.02.2009
Время: 2:06:07
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
Ошибка при завершении служебных сессий Супермага в базе данных dbcentr. Дополнительную информацию см. в следующем сообщении.
Тип события: Ошибка
Источник события: Supermag Server
Категория события: Сервер
Код события: 2052
Дата: 24.02.2009
Время: 2:06:07
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
В работе сервера произошел сбой. Сообщения об ошибках см. ниже.
Запись 1. Код=80004005h (1089) [Microsoft OLE DB Provider for Oracle]:
ORA-01089: immediate shutdown in progress - no operations are permitted

Запись 2. Код=80004005h (0) [SmLibaryBase trace]:
{ call Supermag.core.NextMessage(?, ?, ?, ?, ?) }
Params:
{0} (null)[0](0,0): vt=0 value=<not printable>
{1} (null)[1000](0,0): vt=0 value=<not printable>
{2} (null)[0](0,0): vt=3 value=-20000
{3} (null)[1000](0,0): vt=8 value=116763515230615DFA0FAA2D9E5A5F109EA54F14FCF33DBCENTR*23EbdWfx5_+Q%21422052936856HEXCH
{4} (null)[1000](0,0): vt=8 value=SM2000Ctrl000F000E0001
%3 %4 %5
Тип события: Ошибка
Источник события: CashServer Library
Категория события: Сервер
Код события: 2057
Дата: 24.02.2009
Время: 2:06:07
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
Внезапно потеряно соединение с базой данных dbcentr. База данных деактивизирована.
Тип события: Ошибка
Источник события: CashServer Library
Категория события: Сервер
Код события: 2057
Дата: 24.02.2009
Время: 2:06:07
Пользователь: Н/Д
Компьютер: CENTER7
Описание:
Внезапно потеряно соединение с базой данных %1. База данных деактивизирована.

в это время запучкался бекапкр но он отработал вот лог:

24-02-09 02:05.05 --- Запущен...
24-02-09 02:05.05 Выполняю команду "net send * Запускается архивирование базы. Выйдите из Супермага."
24-02-09 02:05.05 Пауза на 1 мин.
24-02-09 02:05.57 Попытка соединения с базой
24-02-09 02:06.05 Приступаю к анализу базы
24-02-09 02:06.05 Состояние базы : OPEN
24-02-09 02:06.06 Закрытие базы DBCENTR
24-02-09 02:06.06 Остановка сервиса OracleServiceDBCENTR
24-02-09 02:06.18 Приступаю к копированию
24-02-09 02:06.18 Копирование файла INDX01.DBF
24-02-09 02:07.22 Копирование файла INDX02.DBF
24-02-09 02:08.24 Копирование файла INDX03.DBF
24-02-09 02:09.28 Копирование файла INDX04.DBF
24-02-09 02:10.31 Копирование файла INDX05.DBF
24-02-09 02:11.34 Копирование файла INDX06.DBF
24-02-09 02:12.38 Копирование файла INDX07.DBF
24-02-09 02:13.40 Копирование файла INDX08.DBF
24-02-09 02:14.43 Копирование файла INDX09.DBF
24-02-09 02:15.45 Копирование файла INDX10.DBF
24-02-09 02:16.48 Копирование файла INDX11.DBF
24-02-09 02:17.51 Копирование файла SYSTEM01.DBF
24-02-09 02:18.07 Копирование файла TEMP01.DBF
24-02-09 02:19.09 Копирование файла TOOLS01.DBF
24-02-09 02:19.09 Копирование файла UNDOTBS01.DBF
24-02-09 02:20.17 Копирование файла USERS01.DBF
24-02-09 02:21.40 Копирование файла USERS02.DBF
24-02-09 02:23.08 Копирование файла USERS03.DBF
24-02-09 02:24.39 Копирование файла USERS04.DBF
24-02-09 02:26.18 Копирование файла USERS05.DBF
24-02-09 02:28.04 Копирование файла USERS06.DBF
24-02-09 02:29.57 Копирование файла USERS07.DBF
24-02-09 02:32.07 Копирование файла USERS08.DBF
24-02-09 02:34.26 Копирование файла USERS09.DBF
24-02-09 02:36.49 Копирование файла CONTROL01.CTL
24-02-09 02:36.49 Копирование файла CONTROL02.CTL
24-02-09 02:36.49 Копирование файла CONTROL03.CTL
24-02-09 02:36.49 Копирование файла REDO01.LOG
24-02-09 02:36.55 Копирование файла REDO02.LOG
24-02-09 02:37.03 Копирование файла REDO03.LOG
24-02-09 02:37.11 Копирование файла REDO04.LOG
24-02-09 02:37.18 Копирование файла REDO05.LOG
24-02-09 02:37.24 Старт сервиса OracleServiceDBCENTR
24-02-09 02:37.26 Выполняю команду "net send * Можно продолжать работать с Супермагом."
24-02-09 02:37.26 --- Закончено...

Когда говоришь о базе - приводи alert.log, а не виндовые журналы...

Tue Feb 24 00:51:09 2009
Thread 1 advanced to log sequence 3030
Current log# 5 seq# 3030 mem# 0: D:\ORACLE\ORADATA\DBCENTR\REDO05.LOG
Tue Feb 24 00:51:09 2009
ARC0: Evaluating archive log 4 thread 1 sequence 3029
ARC0: Beginning to archive log 4 thread 1 sequence 3029
Creating archive destination LOG_ARCHIVE_DEST_1: 'D:\ARH\ARC03029.001.LOG'
ARC0: Completed archiving log 4 thread 1 sequence 3029
Tue Feb 24 01:18:46 2009
Starting control autobackup
Control autobackup written to DISK device
handle 'D:\ARH\C-3113616410-20090224-00'
Tue Feb 24 02:06:06 2009
Shutting down instance: further logons disabled
Shutting down instance (immediate)
License high water mark = 15
Tue Feb 24 02:06:10 2009
alter database close normal
Tue Feb 24 02:06:11 2009
SMON: disabling tx recovery
SMON: disabling cache recovery
Tue Feb 24 02:06:11 2009
Shutting down archive processes
Archiving is disabled
Tue Feb 24 02:06:11 2009
ARCH shutting down
Tue Feb 24 02:06:11 2009
ARC0: Archival stopped
Tue Feb 24 02:06:11 2009
Thread 1 closed at log sequence 3030
Successful close of redo thread 1
Tue Feb 24 02:06:11 2009
Completed: alter database close normal
Tue Feb 24 02:06:11 2009
alter database dismount
Tue Feb 24 02:06:12 2009
ARCH shutting down
Tue Feb 24 02:06:12 2009
ARC1: Archival stopped
Tue Feb 24 02:06:12 2009
Completed: alter database dismount
ARCH: Archiving is disabled
Shutting down archive processes
Archiving is disabled
Archive process shutdown avoided: 0 active
ARCH: Archiving is disabled
Shutting down archive processes
Archiving is disabled
Archive process shutdown avoided: 0 active
Dump file d:\oracle\admin\dbcentr\bdump\alert_dbcentr.log
Tue Feb 24 02:37:28 2009
ORACLE V9.2.0.8.0 - Production vsnsta=0
vsnsql=12 vsnxtr=3
Windows 2000 Version 5.2 Service Pack 2, CPU type 586
Tue Feb 24 02:37:28 2009
Starting ORACLE instance (normal)
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
SCN scheme 2
Using log_archive_dest parameter default value
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Starting up ORACLE RDBMS Version: 9.2.0.8.0.
System parameters with non-default values:
processes = 150
timed_statistics = TRUE
shared_pool_size = 159383552
sga_max_size = 1328097332
large_pool_size = 8388608
java_pool_size = 67108864
control_files = D:\oracle\oradata\dbcentr\control01.ctl, D:\oracle\oradata\dbcentr\control02.ctl, D:\oracle\oradata\dbcentr\control03.ctl
db_block_checksum = FALSE
db_block_size = 8192
db_cache_size = 947912704
compatible = 9.2.0.0.0
log_archive_start = TRUE
log_archive_dest_1 = LOCATION=D:\arh MANDATORY REOPEN
log_archive_format = ARC%S.%T.LOG
log_buffer = 10485760
db_file_multiblock_read_count= 32
fast_start_mttr_target = 300
transactions_per_rollback_segment= 1
max_rollback_segments = 99
undo_management = AUTO
undo_tablespace = UNDOTBS1
undo_retention = 10800
O7_DICTIONARY_ACCESSIBILITY= TRUE
remote_login_passwordfile= EXCLUSIVE
db_domain =
global_names = TRUE
instance_name = dbcentr
session_cached_cursors = 30
job_queue_processes = 2
cursor_sharing = SIMILAR
hash_join_enabled = TRUE
hash_area_size = 16384000
background_dump_dest = D:\oracle\admin\dbcentr\bdump
user_dump_dest = D:\oracle\admin\dbcentr\udump
core_dump_dest = D:\oracle\admin\dbcentr\cdump
sort_area_size = 8192000
db_name = dbcentr
open_cursors = 300
star_transformation_enabled= FALSE
parallel_automatic_tuning= TRUE
optimizer_index_cost_adj = 30
optimizer_index_caching = 90
query_rewrite_enabled = TRUE
pga_aggregate_target = 125829120
PMON started with pid=2, OS id=4024
DBW0 started with pid=3, OS id=4328
LGWR started with pid=4, OS id=3860
CKPT started with pid=5, OS id=2320
SMON started with pid=6, OS id=952
RECO started with pid=7, OS id=5284
CJQ0 started with pid=8, OS id=5268
Tue Feb 24 02:37:31 2009
ARCH: STARTING ARCH PROCESSES
ARC0 started with pid=9, OS id=5824
ARC0: Archival started
ARC1 started with pid=10, OS id=4612
Tue Feb 24 02:37:32 2009
ARCH: STARTING ARCH PROCESSES COMPLETE
Tue Feb 24 02:37:32 2009
ARC0: Becoming the 'no FAL' ARCH
ARC0: Becoming the 'no FAL' ARCHARC0: Thread not mounted
Tue Feb 24 02:37:32 2009
alter database mount exclusive
Tue Feb 24 02:37:33 2009
ARC1: Archival started
Tue Feb 24 02:37:33 2009
ARC1: Becoming the heartbeat ARCH
ARC1: Becoming the heartbeat ARCHARC1: Thread not mounted
Tue Feb 24 02:37:37 2009
Successful mount of redo thread 1, with mount id 3158354029
Tue Feb 24 02:37:37 2009
Database mounted in Exclusive Mode.
Completed: alter database mount exclusive
Tue Feb 24 02:37:37 2009
alter database open
Tue Feb 24 02:37:38 2009
LGWR: Primary database is in CLUSTER CONSISTENT mode
Thread 1 opened at log sequence 3030
Current log# 5 seq# 3030 mem# 0: D:\ORACLE\ORADATA\DBCENTR\REDO05.LOG
Successful open of redo thread 1
Tue Feb 24 02:37:38 2009
SMON: enabling cache recovery
Tue Feb 24 02:37:39 2009
Successfully onlined Undo Tablespace 1.
Tue Feb 24 02:37:39 2009
SMON: enabling tx recovery
Tue Feb 24 02:37:39 2009
Database Characterset is CL8MSWIN1251
replication_dependency_tracking turned off (no async multimaster replication found)
Completed: alter database open
Shutting down instance: further logons disabled
Shutting down instance (immediate)
License high water mark = 2
Tue Feb 24 07:03:43 2009
alter database close normal
Tue Feb 24 07:03:43 2009
SMON: disabling tx recovery
SMON: disabling cache recovery
Tue Feb 24 07:03:43 2009
Shutting down archive processes
Archiving is disabled
Tue Feb 24 07:03:43 2009
ARCH shutting down
Tue Feb 24 07:03:43 2009
ARC0: Archival stopped
Tue Feb 24 07:03:43 2009
Thread 1 closed at log sequence 3030
Successful close of redo thread 1
Tue Feb 24 07:03:43 2009
Completed: alter database close normal
Tue Feb 24 07:03:43 2009
alter database dismount
Tue Feb 24 07:03:44 2009
ARCH shutting down
Tue Feb 24 07:03:44 2009
ARC1: Archival stopped
Tue Feb 24 07:03:46 2009
Completed: alter database dismount
ARCH: Archiving is disabled
Shutting down archive processes
Archiving is disabled
Archive process shutdown avoided: 0 active
ARCH: Archiving is disabled
Shutting down archive processes
Archiving is disabled
Archive process shutdown avoided: 0 active
Dump file d:\oracle\admin\dbcentr\bdump\alert_dbcentr.log
Tue Feb 24 07:04:02 2009
ORACLE V9.2.0.8.0 - Production vsnsta=0
vsnsql=12 vsnxtr=3
Windows 2000 Version 5.2 Service Pack 2, CPU type 586
Tue Feb 24 07:04:02 2009
Starting ORACLE instance (normal)
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
SCN scheme 2
Using log_archive_dest parameter default value
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Starting up ORACLE RDBMS Version: 9.2.0.8.0.
System parameters with non-default values:
processes = 150
timed_statistics = TRUE
shared_pool_size = 159383552
sga_max_size = 1328097332
large_pool_size = 8388608
java_pool_size = 67108864
control_files = D:\oracle\oradata\dbcentr\control01.ctl, D:\oracle\oradata\dbcentr\control02.ctl, D:\oracle\oradata\dbcentr\control03.ctl
db_block_checksum = FALSE
db_block_size = 8192
db_cache_size = 947912704
compatible = 9.2.0.0.0
log_archive_start = TRUE
log_archive_dest_1 = LOCATION=D:\arh MANDATORY REOPEN
log_archive_format = ARC%S.%T.LOG
log_buffer = 10485760
db_file_multiblock_read_count= 32
fast_start_mttr_target = 300
transactions_per_rollback_segment= 1
max_rollback_segments = 99
undo_management = AUTO
undo_tablespace = UNDOTBS1
undo_retention = 10800
O7_DICTIONARY_ACCESSIBILITY= TRUE
remote_login_passwordfile= EXCLUSIVE
db_domain =
global_names = TRUE
instance_name = dbcentr
session_cached_cursors = 30
job_queue_processes = 2
cursor_sharing = SIMILAR
hash_join_enabled = TRUE
hash_area_size = 16384000
background_dump_dest = D:\oracle\admin\dbcentr\bdump
user_dump_dest = D:\oracle\admin\dbcentr\udump
core_dump_dest = D:\oracle\admin\dbcentr\cdump
sort_area_size = 8192000
db_name = dbcentr
open_cursors = 300
star_transformation_enabled= FALSE
parallel_automatic_tuning= TRUE
optimizer_index_cost_adj = 30
optimizer_index_caching = 90
query_rewrite_enabled = TRUE
pga_aggregate_target = 125829120
PMON started with pid=2, OS id=13396
DBW0 started with pid=3, OS id=13352
LGWR started with pid=4, OS id=13244
CKPT started with pid=5, OS id=12980
SMON started with pid=6, OS id=13316
RECO started with pid=7, OS id=13080
CJQ0 started with pid=8, OS id=13192
Tue Feb 24 07:04:04 2009
ARCH: STARTING ARCH PROCESSES
ARC0 started with pid=9, OS id=13592
ARC0: Archival started
ARC1 started with pid=10, OS id=13432
Tue Feb 24 07:04:05 2009
ARCH: STARTING ARCH PROCESSES COMPLETE
Tue Feb 24 07:04:05 2009
ARC0: Becoming the 'no FAL' ARCH
Tue Feb 24 07:04:05 2009
alter database mount exclusive
Tue Feb 24 07:04:05 2009
ARC1: Archival started
Tue Feb 24 07:04:05 2009
ARC1: Becoming the heartbeat ARCH
ARC1: Becoming the heartbeat ARCHARC1: Thread not mounted
Tue Feb 24 07:04:06 2009
ARC0: Becoming the 'no FAL' ARCHARC0: Thread not mounted
Tue Feb 24 07:04:10 2009
Successful mount of redo thread 1, with mount id 3158334694
Tue Feb 24 07:04:10 2009
Database mounted in Exclusive Mode.
Completed: alter database mount exclusive
Tue Feb 24 07:04:10 2009
alter database open
Tue Feb 24 07:04:10 2009
LGWR: Primary database is in CLUSTER CONSISTENT mode
Thread 1 opened at log sequence 3030
Current log# 5 seq# 3030 mem# 0: D:\ORACLE\ORADATA\DBCENTR\REDO05.LOG
Successful open of redo thread 1
Tue Feb 24 07:04:10 2009
SMON: enabling cache recovery
Tue Feb 24 07:04:10 2009
Successfully onlined Undo Tablespace 1.
Tue Feb 24 07:04:10 2009
SMON: enabling tx recovery
Tue Feb 24 07:04:10 2009
Database Characterset is CL8MSWIN1251
replication_dependency_tracking turned off (no async multimaster replication found)
Completed: alter database open
Sat Feb 24 07:08:30 2007
ALTER SYSTEM SET timed_statistics=TRUE SCOPE=BOTH;
Tue Feb 24 07:39:22 2009
Thread 1 advanced to log sequence 3031
Current log# 1 seq# 3031 mem# 0: D:\ORACLE\ORADATA\DBCENTR\REDO01.LOG
Tue Feb 24 07:39:22 2009
ARC0: Evaluating archive log 5 thread 1 sequence 3030
ARC0: Beginning to archive log 5 thread 1 sequence 3030
Creating archive destination LOG_ARCHIVE_DEST_1: 'D:\ARH\ARC03030.001.LOG'
ARC0: Completed archiving log 5 thread 1 sequence 3030

С базой все нормально было, с чего ты взял, что она повисла? В журналах только то, что ты ее ребутил бекапером и потом, наверное, когда она показалась тебе зависшей.

Супермаг к ней не цеплялся только после перезапуска базы подцепился

Дык это другой разговор... Мало ли что ему в голову там пришло... Хотя листенер мог повиснуть... По логам все чисто было.