Dimoniy

Братья и сестры! Может есть кто на форуме, знающий SQL Server 2000, на уровне администратора? Случилась беда - стал самопроизвольно останавливаться сервис, а сам не могу разобраться чего ему надо.
Буду благодарен за любую помощь.

Land

Первое что надо сделать - посмотреть логи.

Dimoniy

Land, Андрей, конечно логи смотрел. Внимательно и вдумчиво. Сообщение об ошибке там есть. Но вот как с ней бороться...

Сообщение в системном журнале:
Error: 3314, Severity: 21, State: 4
Error while undoing logged operation in database 'tempdb'. Error at log record ID (15:35151:29).

Сообщение SQLя:
2010-04-01 15:12:30.56 spid55 TotalPhysicalMemory = 8587489280, AvailablePhysicalMemory = 7134707712
2010-04-01 15:12:30.56 spid55 AvailableVirtualMemory = 2422431744, AvailablePagingFile = 9244983296
2010-04-01 15:12:30.59 spid55 Error: 3314, Severity: 21, State: 4
2010-04-01 15:12:30.59 spid55 Error while undoing logged operation in database 'tempdb'. Error at log record ID (15:35151:29)..
2010-04-01 15:12:30.60 spid55 Error: 3449, Severity: 21, State: 1
2010-04-01 15:12:30.60 spid55 An error has occurred that requires SQL Server to shut down so that recovery can be performed on database ID 2..

eimosin

Для начала не паниковать. А сделать надо следующее - во-первых узнать может кто-нибудь делает очень емкие действия с базой, соответственно и растет tempdb, во-вторых проверить ограничения на базе tempdb - может стоять какое-то число, tempdb до него выросла вот сиквель и ругается, да и плюс уточни версию sql сервера (experess, standart, enterprise). Плюс перегрузить сервер на котором стоит сиквель. Пока примерно такие действия. Если что не так пиши, но только чур с логами, чем смогу помогу!

добавлено через 4 минуты
Да и еще пока помню - а место то на диске где живет tempdb есть??????

Kir

Тут можно почитать FIX: Assertion and Error Message 3314 Occurs If You Try to Roll Back a Text Operation with READ UNCOMMITTED

__________________
Тише едешь - дальше будешь от того места куда едешь.

eimosin

Возможно, но опять вопрос к топикстартеру - версия sql сервера и установленные патчи, т.к. данная статья относится к sql до 3 sp !

Dimoniy

eimosin, Спасибо, стараюсь не паниковать.
По порядку:
1. Действия с базой емкие делаются постоянно - на сервере живут 5 баз 1С (две под 7.7 три под 8.1). Сейчас время подходит к сдаче квартальной отчетности, поэтому работает без передышек.
2. Ограничений на tempdb не стоят. После начала свистоплясок специально выставил ей размер в 500 метров на mdf файл и 250 метров на ldf. Приращение поставил в 50%. Один фиг останавливается.
3. Сервер 2000 Standart SP4.
4. Обперезагружался ужо весь...
5. Места свободного на диске почти 50 гигов. База самая большая весит 3.5 гига.

Логи:

.............
2010-04-01 15:29:17.93 spid54
2010-04-01 15:29:17.93 spid54 TotalPhysicalMemory = 8587489280, AvailablePhysicalMemory = 6988328960
2010-04-01 15:29:17.93 spid54 AvailableVirtualMemory = 2419367936, AvailablePagingFile = 9097650176
2010-04-01 15:29:17.96 spid54 Error: 3314, Severity: 21, State: 4
2010-04-01 15:29:17.96 spid54 Error while undoing logged operation in database 'tempdb'. Error at log record ID (15:25596:635)..
2010-04-01 15:29:17.96 spid54 Error: 3449, Severity: 21, State: 1
2010-04-01 15:29:17.96 spid54 An error has occurred that requires SQL Server to shut down so that recovery can be performed on database ID 2..

Вот он упал раз. Я его стартую:

2010-04-01 15:29:31.82 server Microsoft SQL Server 2000 - 8.00.2039 (Intel X86)
2010-04-01 15:29:31.82 server Copyright (C) 1988-2002 Microsoft Corporation.
2010-04-01 15:29:31.82 server All rights reserved.
2010-04-01 15:29:31.82 server Server Process ID is 3480.
2010-04-01 15:29:31.82 server Logging SQL Server messages in file 'd:\MSSQL\log\ERRORLOG'.
2010-04-01 15:29:31.83 server SQL Server is starting at priority class 'normal'(8 CPUs detected).
2010-04-01 15:29:31.85 server SQL Server configured for thread mode processing.
2010-04-01 15:29:31.86 server Using dynamic lock allocation. [2500] Lock Blocks, [5000] Lock Owner Blocks.
2010-04-01 15:29:31.99 server Attempting to initialize Distributed Transaction Coordinator.
2010-04-01 15:29:34.00 spid3 Starting up database 'master'.
2010-04-01 15:29:34.07 spid3 0 transactions rolled back in database 'master' (1).
2010-04-01 15:29:34.07 spid3 Recovery is checkpointing database 'master' (1)
2010-04-01 15:29:34.08 server Using 'SSNETLIB.DLL' version '8.0.2039'.
2010-04-01 15:29:34.08 spid5 Starting up database 'model'.
2010-04-01 15:29:34.10 spid3 Server name is 'S2'.
2010-04-01 15:29:34.10 spid8 Starting up database 'msdb'.
2010-04-01 15:29:34.10 spid10 Starting up database 'Northwind'.
2010-04-01 15:29:34.10 spid9 Starting up database 'pubs'.
2010-04-01 15:29:34.10 spid11 Starting up database 'LPG'.
2010-04-01 15:29:34.10 spid12 Starting up database 'Jur'.
2010-04-01 15:29:34.10 spid13 Starting up database 'My_TMP'.
2010-04-01 15:29:34.10 spid14 Starting up database 'Reserv'.
2010-04-01 15:29:34.10 spid15 Starting up database 'Salary'.
2010-04-01 15:29:34.10 server SQL server listening on 192.168.5.252: 1433.
2010-04-01 15:29:34.10 server SQL server listening on 127.0.0.1: 1433.
2010-04-01 15:29:34.10 spid16 Starting up database 'USL'.
2010-04-01 15:29:34.11 spid17 Starting up database 'x_comp'.
2010-04-01 15:29:34.11 spid18 Starting up database 'BookBase'.
2010-04-01 15:29:34.13 spid19 Starting up database 'OAO'.
2010-04-01 15:29:34.13 spid20 Starting up database 'Price2008'.
2010-04-01 15:29:34.14 spid21 Starting up database '1Cv8'.
2010-04-01 15:29:34.16 spid22 Starting up database 'SalaryV8'.
2010-04-01 15:29:34.16 server SQL server listening on TCP, Shared Memory, Named Pipes.
2010-04-01 15:29:34.16 server SQL Server is ready for client connections
2010-04-01 15:29:34.16 spid23 Starting up database 'trade82'.
2010-04-01 15:29:34.30 spid11 Analysis of database 'LPG' (7) is 100% complete (approximately 0 more seconds)
2010-04-01 15:29:34.33 spid20 Analysis of database 'Price2008' (16) is 100% complete (approximately 0 more seconds)
2010-04-01 15:29:34.41 spid5 Clearing tempdb database.
2010-04-01 15:29:34.43 spid13 Analysis of database 'My_TMP' (9) is 100% complete (approximately 0 more seconds)
2010-04-01 15:29:34.66 spid5 Starting up database 'tempdb'.
2010-04-01 15:29:34.66 spid5 Analysis of database 'tempdb' (2) is 100% complete (approximately 0 more seconds)
2010-04-01 15:29:35.75 spid21 584 transactions rolled forward in database '1Cv8' (17).
2010-04-01 15:29:35.75 spid21 0 transactions rolled back in database '1Cv8' (17).
2010-04-01 15:29:35.79 spid21 Recovery is checkpointing database '1Cv8' (17)
2010-04-01 15:29:37.13 spid22 475 transactions rolled forward in database 'SalaryV8' (19).
2010-04-01 15:29:37.14 spid22 0 transactions rolled back in database 'SalaryV8' (19).
2010-04-01 15:29:37.18 spid22 Recovery is checkpointing database 'SalaryV8' (19)
2010-04-01 15:29:37.21 spid3 Recovery complete.
2010-04-01 15:29:37.21 spid3 SQL global counter collection task is created.
2010-04-01 15:31:43.81 spid54 04/01/10 15:31:43 Stack Overflow Dump not possible - Exception c00000fd E at 0x00402483
2010-04-01 15:31:43.81 spid54 Address=402483 Exception Code = c00000fd
2010-04-01 15:31:43.81 spid54 eax=00003e54 ebx=81d74000 ecx=04c890c0 edx=000000da
2010-04-01 15:31:43.81 spid54 esi=81d75cee edi=04c8f1a6 eip=00402483 esp=04c8f0b8
2010-04-01 15:31:43.81 spid54 ebp=04c8f0c0 efl=00010202
2010-04-01 15:31:43.81 spid54 cs=23 ss=2b ds=2b es=2b fs=53 gs=2b
2010-04-01 15:31:43.81 spid54 1: Frame: 04C8F0C0 Return Address 00402483
2010-04-01 15:31:43.81 spid54 2: Frame: 04C8F1C8 Return Address 006DB4DF
2010-04-01 15:31:43.81 spid54 3: Frame: 04C8F278 Return Address 004231A4
2010-04-01 15:31:43.81 spid54 4: Frame: 04C8F444 Return Address 006148DE
2010-04-01 15:31:43.81 spid54 5: Frame: 04C973C4 Return Address 00614678
2010-04-01 15:31:43.81 spid54 6: Frame: 04C9750C Return Address 00613465
2010-04-01 15:31:43.81 spid54 7: Frame: 04C975E8 Return Address 00612AAE
2010-04-01 15:31:43.81 spid54 8: Frame: 04C97654 Return Address 0061294D
2010-04-01 15:31:43.81 spid54 9: Frame: 04CFFA80 Return Address 00642592
2010-04-01 15:31:43.81 spid54 10: Frame: 04CFFF00 Return Address 0049C46A
2010-04-01 15:31:43.81 spid54 11: Frame: 003B7890 Return Address 41075309
2010-04-01 15:31:43.81 spid54 12: Frame: 00000000 Return Address 00000003
2010-04-01 15:31:43.85 spid54 exec sp_executesql N'SELECT #V8TblAli1_Q_000_T_002._Fld7969RRef AS _Q_00
2010-04-01 15:31:43.86 spid54 0_F_000RRef, #V8TblAli1_Q_000_T_002._Value1_TYPE AS _Q_000_F_001_TYPE, #
2010-04-01 15:31:43.89 spid54 V8TblAli1_Q_000_T_002._Value1_RTRef AS _Q_000_F_001_RTRef, #V8TblAli1_Q_
2010-04-01 15:31:43.91 spid54 000_T_002._Value1_RRRef AS _Q_000_F_001_RRRef, #V8TblAli1_Q_000_T_002._V
2010-04-01 15:31:43.94 spid54 alue2_TYPE AS _Q_000_F_002_TYPE, #V8TblAli1_Q_000_T_002._Value2_RTRef AS
2010-04-01 15:31:43.97 spid54 _Q_000_F_002_RTRef, #V8TblAli1_Q_000_T_002._Value2_RRRef AS _Q_000_F_00
2010-04-01 15:31:43.99 spid54 2_RRRef, #V8TblAli1_Q_000_T_002._Value3_TYPE AS _Q_000_F_003_TYPE, #V8Tb
2010-04-01 15:31:44.02 spid54 lAli1_Q_000_T_002._Value3_RTRef AS _Q_000_F_003_RTRef, #V8TblAli1_Q_000_
2010-04-01 15:31:44.03 spid54 T_002._Value3_RRRef AS _Q_000_F_003_RRRef, ISNULL(#V8TblAli1_Q_000_T_002
2010-04-01 15:31:44.06 spid54 ._Fld7970InitialBalanceDt, CAST(0. AS NUMERIC(1,0))) AS _Q_000_F_004, IS
2010-04-01 15:31:44.10 spid54 NULL(#V8TblAli1_Q_000_T_002._Fld7970FinalBalanceDt , CAST(0. AS NUMERIC(1
2010-04-01 15:31:44.11 spid54 ,0))) AS _Q_000_F_005, #V8TblAli2_Q_000_T_003._AccountRRef AS _Q_000_F_0
2010-04-01 15:31:44.14 spid54 06RRef, ISNULL(#V8TblAli2_Q_000_T_003._Fld7970InitialBalan ceDt, CAST(0.
2010-04-01 15:31:44.16 spid54 AS NUMERIC(1,0))) AS _Q_000_F_007, ISNULL(#V8TblAli2_Q_000_T_003._Fld797
2010-04-01 15:31:44.19 spid54 0FinalBalanceDt, CAST(0. AS NUMERIC(1,0))) AS _Q_000_F_008, ISNULL(#V8Tb
2010-04-01 15:31:44.21 spid54 lAli2_Q_000_T_003._Fld7970TurnoverCt, CAST(0. AS NUMERIC(1,0))) AS _Q_00
2010-04-01 15:31:44.24 spid54 0_F_009, ISNULL(#V8TblAli3_Q_000_T_004._Fld7971FinalBalance Dt, CAST(0. A
2010-04-01 15:31:44.25 spid54 S NUMERIC(1,0))) AS _Q_000_F_010, ISNULL(#V8TblAli3_Q_000_T_004._Fld7971
2010-04-01 15:31:44.28 spid54 TurnoverCt, CAST(0. AS NUMERIC(1,0))) AS _Q_000_F_011, _Document149_VT37
2010-04-01 15:31:44.30 spid54 77_Q_000_T_005._Fld3780RRef AS _Q_000_F_012RRef, _Reference33._Fld560RRe
2010-04-01 15:31:44.33 spid54 f AS _Q_000_F_013RRef, _Reference33._Fld561 AS _Q_000_F_014, _Reference3
2010-04-01 15:31:44.35 spid54 3._Fld562 AS _Q_000_F_015, _Reference33._Fld564RRef AS _Q_000_F_016RRef,
2010-04-01 15:31:44.38 spid54 CAST(CAST(0. AS NUMERIC(1,0)) AS NUMERIC(22,3)) AS _Q_000_F_017 FROM #t
2010-04-01 15:31:44.39 spid54 t62 #T749cc123fd9445b3a3e9b2ebf0d53a1a_Q_000_T_001 WITH(NOLOCK) LEFT OUT
2010-04-01 15:31:44.42 spid54 ER JOIN ( SELECT #V8TblAli1_R._Value1_TYPE AS _Value1_TYPE, #V8TblAli1_R
2010-04-01 15:31:44.46 spid54 ._Value1_RTRef AS _Value1_RTRef, #V8TblAli1_R._Value1_RRRef AS _Value1_R
2010-04-01 15:31:44.47 spid54 RRef, #V8TblAli1_R._Value2_TYPE AS _Value2_TYPE, #V8TblAli1_R._Value2_RT
2010-04-01 15:31:44.50 spid54 Ref AS _Value2_RTRef, #V8TblAli1_R._Value2_RRRef AS _Value2_RRRef, #V8Tb
2010-04-01 15:31:44.52 spid54 lAli1_R._Value3_TYPE AS _Val
2010-04-01 15:31:44.55 spid54
2010-04-01 15:31:44.55 spid54 TotalPhysicalMemory = 8587489280, AvailablePhysicalMemory = 7168299008
2010-04-01 15:31:44.55 spid54 AvailableVirtualMemory = 2422808576, AvailablePagingFile = 9273094144
2010-04-01 15:31:44.55 spid54 Error: 3314, Severity: 21, State: 4
2010-04-01 15:31:44.55 spid54 Error while undoing logged operation in database 'tempdb'. Error at log record ID (15:3239:495)..
2010-04-01 15:31:44.56 spid54 Error: 3449, Severity: 21, State: 1
2010-04-01 15:31:44.56 spid54 An error has occurred that requires SQL Server to shut down so that recovery can be performed on database ID 2..

Вот он упал два, проработав пару минут.

Dimoniy

Kir, Спасибо, уже видел. Не мой случай, поскольку SP4 стоит.

eimosin

Если внимательно вот тут глянуть то видно что у тебя 584 и 475 транзакций прошли после восстановления tempdb, при этом никто из них не откатился, следователь вопрос - что им мещает жить и спокойно проходить до остановки sql. Для этого нужно через QUERY ANALYZER посмотреть все идущие операции на предмет длинных операций и больших. Есть вариант глянуть блокировки на сервере, которые возможно лочат tempdb и не дают остальным с ней работать, если нет блокировок, то пересоздать tempdb, выполнить надо вот это -
USE master
GO
A_lter DATABASE tempdb
MODIFY FILE (NAME = tempdev, FILENAME = 'Путь до нового места\tempdb.mdf')
GO
A_lter DATABASE tempdb
MODIFY FILE (NAME = templog, FILENAME = 'Путь до нового места\templog.ldf')
GO

впринципе tempdb создается каждый раз при запуске sql, но у меня был случай когда помог именно перенос tempdb в новое место.

WildMan

Дмитрий, вышлите пожалуйста весь лог. Нужно проанализировать историю ошибки. Возможно смогу помочь.

Dimoniy

eimosin, Спасибо, попробую.

WildMan, Ответил в личку.

Dimoniy

Спасибо всем за участие. Причина проблемы найдены. SQL был ни причём. Т.е. причём, конечно, но его на такое поведение провоцировало славное создание не менее славной фирмы 1С.
Если кому интересно, расскажу подробности.

GERR

Сто процентов - восьмёрка?

Dimoniy

GERR, она самая.

GERR

Не зря мои друзья-профи 1С её так не любят