Недопустимая операция. Подключение закрыто : Супермаг Плюс (Супермаг 2000)

Oracle 9.2.0.8 в режиме архивлог
БД 1.027.5 SP4

Наблюдаю ситуацию. Ночью примерно после полного бакапа RMAN'ом получаю в супермаге ошибку (взято из лога супермага): полный бакап закончился 01:40:29

2011.10.03 (понедельник) 01:57:24 1.27.5.0
----- Прерывание работы программы -----
сообщение: "Недопустимая операция. Подключение закрыто."
исключение: System.InvalidOperationException
источник: Sm.AppServer
данные:
соединено с: База даных=DBAMIK02; Пользователь=Supermag
текст команды: begin Supermag.Core.ExitSysSession; end;
тип команды: Text
метод: Void Licensing_Completed(System.Object, System.ComponentModel.RunWorkerCompletedEventArgs)
в Sm.AppServer.LicensingService.Licensing_Completed(Object sender, RunWorkerCompletedEventArgs e)

и дальше соответственно пошли ошибки до пока службу супермаговского сервера приложений не перезапустишь:

2011.10.03 (понедельник) 01:58:25 1.27.5.0 Sm.Post.Server
----- Прерывание работы программы -----
сообщение: "ORA-20017: Клиентский процесс не зарегистрирован сервером
ORA-06512: на "SUPERMAG.CORE", line 300
ORA-06512: на "SUPERMAG.CORE", line 1445
ORA-06512: на line 1


Отимайзер живёт на этой базе, специально перевёл его запуск на другое время, что бы не пересекался с бакапом (на всякий случай). В оракле нет ошибок, только ошибки запуска назначенных заданий появляются, после того как сервис супермага ошибается. Последнюю ночь ошибку получил после бакапа, в предыдущие дни ошибки появлялись и во время работы оптимайзера в maintenance time.
Ошибка периодическая ночная. Грешил на оптимайзер - но последний раз получил эту ошибку уже без оптимайзера.

Подскажите куда копать при таком раскладе ?

С профилями не игрался? База в автостарте в сервере приложений? База круглосуточная? Я на ночь обычно почтовик останавливаю, а утром запускаю. Сервер лицензий перезапускаю, бо достаточно глючные вещи. От RMANа ни разу таких проблем не получал. Попробуй на 10ку обновиться? В 9ке RMAN достаточно убог.

База не круглосуточная, автостарт. С профилями не игрался. Глючность понятно, но пере запускать ежедневно не думал про такое - больше 20-ти баз на 9-ке без перезапусков живут. Сейчас если никто не подскажет чего то доброго - придётся так и сделать.
Но всё таки, где бы и как можно ещё посмотреть с чем ошибка ?

на 10-ку переехать хочу, но сейчас чел ресурсов нет для этого :(

А логи RMAN-а можно увидеть?

В нём нормально

Менеджер восстановления: Реализация 9.2.0.8.0 - Production

Copyright (c) 1995, 2002, Oracle Corporation. All rights reserved.

RMAN> CONNECT TARGET sys/qqq@DBAMIK02
2> #CONFIGURE RETENTION POLICY TO RECOVERY WINDOW OF 2 DAYS;
3> CONFIGURE RETENTION POLICY TO REDUNDANCY 3;
4> BACKUP DATABASE FORMAT 'E:\BackUp\dbamik02\rman_%D_%d_%U.bkp';
5> CROSSCHECK BACKUP;
6> CROSSCHECK archivelog all;
7> DELETE NOPROMPT OBSOLETE;
8> DELETE NOPROMPT EXPIRED BACKUP;
9>
подключение к базе данных назначения: DBAMIK02 (DBID=3313219141)
используется управляющий файл базы данных назначения вместо каталога восстановления

старые параметры конфигурации RMAN:
CONFIGURE RETENTION POLICY TO REDUNDANCY 3;
новые параметры конфигурации RMAN:
CONFIGURE RETENTION POLICY TO REDUNDANCY 3;
новые параметры конфигурации RMAN успешно сохранены

Запуск backup в 03.10.11
занятый канал: ORA_DISK_1
канал ORA_DISK_1: sid=29 devtype=DISK
канал ORA_DISK_1: запущен резервный набор файла данных full
канал ORA_DISK_1: задается файл данных в резервном наборе
ввод файла данных, номер=00005 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS01.DBF
ввод файла данных, номер=00006 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS02.DBF
ввод файла данных, номер=00007 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS03.DBF
ввод файла данных, номер=00008 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS04.DBF
ввод файла данных, номер=00009 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS05.DBF
ввод файла данных, номер=00010 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS06.DBF
ввод файла данных, номер=00011 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS07.DBF
ввод файла данных, номер=00012 имя=D:\ORACLE\ORADATA\DBAMIK02\USERS08.DBF
ввод файла данных, номер=00001 имя=D:\ORACLE\ORADATA\DBAMIK02\SYSTEM01.DBF
ввод файла данных, номер=00002 имя=D:\ORACLE\ORADATA\DBAMIK02\UNDOTBS01.DBF
ввод файла данных, номер=00003 имя=D:\ORACLE\ORADATA\DBAMIK02\INDX01.DBF
ввод файла данных, номер=00004 имя=D:\ORACLE\ORADATA\DBAMIK02\TOOLS01.DBF
канал ORA_DISK_1: начало фрагмента 1 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 1 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_1_1.BKP комментарий=NONE
канал ORA_DISK_1: начало фрагмента 2 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 2 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_2_1.BKP комментарий=NONE
канал ORA_DISK_1: начало фрагмента 3 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 3 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_3_1.BKP комментарий=NONE
канал ORA_DISK_1: начало фрагмента 4 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 4 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_4_1.BKP комментарий=NONE
канал ORA_DISK_1: начало фрагмента 5 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 5 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_5_1.BKP комментарий=NONE
канал ORA_DISK_1: начало фрагмента 6 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 6 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_6_1.BKP комментарий=NONE
канал ORA_DISK_1: начало фрагмента 7 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 7 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_7_1.BKP комментарий=NONE
канал ORA_DISK_1: начало фрагмента 8 в 03.10.11
канал ORA_DISK_1: завершение фрагмента 8 в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_QPMO4R4Q_8_1.BKP комментарий=NONE
канал ORA_DISK_1: набор резервирования завершен, затраченное время: 00:10:10
Завершение backup в 03.10.11

Запуск Control File and SPFILE Autobackup в 03.10.11
ид_отрезка=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_AUTOCTL_C-3313219141-20111003-00.BKP комментарий=NONE
Завершение Control File and SPFILE Autobackup в 03.10.11

использование канала ORA_DISK_1
перекрестная проверка резервного фрагмента: найдено 'AVAILABLE'
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_1_1.BKP строка=15145 отметка=763176603
перекрестная проверка резервного фрагмента: найдено 'AVAILABLE'
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_2_1.BKP строка=15146 отметка=763176688
перекрестная проверка резервного фрагмента: найдено 'AVAILABLE'
ид.резервной порции=E:\BACKUP\DBAMIK02ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_03_DBAMIK02_AUTOCTL_C-3313219141-20111003-00.BKP строка=15180 отметка=763522813
и т.д. ..........
Выполнена перекрестная проверка 36 объектов


освобожденный канал: ORA_DISK_1
занятый канал: ORA_DISK_1
канал ORA_DISK_1: sid=29 devtype=DISK
проверка архивного журнала успешна
имя файла архивного журнала=E:\ARCHIVE\ARC15092.001 recid=878 отметка=763178581
проверка архивного журнала успешна
имя файла архивного журнала=E:\ARCHIVE\ARC15093.001 recid=879 отметка=763189395
проверка архивного журнала успешна
имя файла архивного журнала=E:\ARCHIVE\ARC15094.001 recid=880 отметка=763189457
и т.д. ..........
Выполнена перекрестная проверка 58 объектов


к данной команде будет применяться методика сохранения RMAN
для методики сохранения RMAN установлена избыточность 3
использование канала ORA_DISK_1
Удаление следующих устаревших резервных и обычных копий:
Тип Ключ Время завершения Имя файла/Указатель
-------------------- ------ ------------------ --------------------
Резервн. набор 2883 30.09.11
Рез.фрагм 15145 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_1_1.BKP
Рез.фрагм 15146 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_2_1.BKP
Рез.фрагм 15147 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_3_1.BKP
Рез.фрагм 15148 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_4_1.BKP
Рез.фрагм 15149 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_5_1.BKP
Рез.фрагм 15150 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_6_1.BKP
Рез.фрагм 15151 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_7_1.BKP
Рез.фрагм 15152 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_8_1.BKP
Резервн. набор 2884 30.09.11
Рез.фрагм 15153 30.09.11 E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_AUTOCTL_C-3313219141-20110930-00.BKP
Архивный журнал 878 30.09.11 E:\ARCHIVE\ARC15092.001
Архивный журнал 879 30.09.11 E:\ARCHIVE\ARC15093.001
Архивный журнал 880 30.09.11 E:\ARCHIVE\ARC15094.001
Архивный журнал 881 30.09.11 E:\ARCHIVE\ARC15095.001
Архивный журнал 882 30.09.11 E:\ARCHIVE\ARC15096.001
Архивный журнал 883 30.09.11 E:\ARCHIVE\ARC15097.001
Архивный журнал 884 30.09.11 E:\ARCHIVE\ARC15098.001
Архивный журнал 885 30.09.11 E:\ARCHIVE\ARC15099.001
Архивный журнал 886 30.09.11 E:\ARCHIVE\ARC15100.001
Архивный журнал 887 30.09.11 E:\ARCHIVE\ARC15101.001
Архивный журнал 888 30.09.11 E:\ARCHIVE\ARC15102.001
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_1_1.BKP строка=15145 отметка=763176603
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_2_1.BKP строка=15146 отметка=763176688
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_3_1.BKP строка=15147 отметка=763176763
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_4_1.BKP строка=15148 отметка=763176838
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_5_1.BKP строка=15149 отметка=763176913
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_6_1.BKP строка=15150 отметка=763176988
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_7_1.BKP строка=15151 отметка=763177063
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_QJMNQ9KR_8_1.BKP строка=15152 отметка=763177138
удалена часть копий
ид.резервной порции=E:\BACKUP\DBAMIK02\RMAN_30_DBAMIK02_AUTOCTL_C-3313219141-20110930-00.BKP строка=15153 отметка=763177213
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15092.001 recid=878 отметка=763178581
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15093.001 recid=879 отметка=763189395
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15094.001 recid=880 отметка=763189457
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15095.001 recid=881 отметка=763201749
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15096.001 recid=882 отметка=763202103
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15097.001 recid=883 отметка=763210676
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15098.001 recid=884 отметка=763223587
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15099.001 recid=885 отметка=763231901
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15100.001 recid=886 отметка=763238655
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15101.001 recid=887 отметка=763251116
удаленный архивный журнал
имя файла архивного журнала=E:\ARCHIVE\ARC15102.001 recid=888 отметка=763254128
Удалено 20 объектов


использование канала ORA_DISK_1

Менеджер восстановления завершен.

Есть какая-то система? Диапазон времени? Когда началось? Что менял?

Примерно неделю.
Я вечерком наверное сделаю отключение на ночь супермаговских сервисов. И проверю все параметры в оптимайзере - много их новых в последний месяц появилось.

ЗЫ: не получится полностью отключать супермаг по ночам. У меня выборочные ночные пересчёты минимум раз в месяц в каждом магазине - т.е. супермаг будет нужен. Даже раз в месяц не забыть отключить задание выключающее супермаг это проблема.

Решилась ваша проблема как-то ?

А версию СМ и Oracle озвучить? Не 11.2.0.1? А то уже были тут мучающиеся...

Есть такое...Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - Production - это так понимать диагноз?