View Issue Details

IDProjectCategoryView StatusLast Update
0002045SAS.ПланетаБаг / Bugpublic11-11-2013 10:29
ReporterGarl Assigned Tozed  
PrioritynormalSeverityminorReproducibilityhave not tried
Status resolvedResolutionfixed 
PlatformWindowsOS7OS VersionProfessional
Product Version.Nightly 
Target Version131111Fixed in Version131111 
Summary0002045: EBerkeleyDBExeption: Error #-30994: DB_LOCK_DEADLOCK: Locker killed to resolve a deadlock.
Descriptionвылезло при импорте 14..18 зумов в версионный кэш

sdb.log
23-07-2013 08:31:09.076 EBerkeleyDBExeption: Error #-30994: DB_LOCK_DEADLOCK: Locker killed to resolve a deadlock
Additional InformationStorageConfig.ini
[BerkeleyDB]
IsReadOnly=0
SyncInterval=30000
CommitsCountToSync=3000
PoolSize=256
PoolObjectTTL=600000
DatabasePageSize=1024

DB_CONFIG.
set_flags DB_TXN_NOSYNC off
set_flags DB_TXN_WRITE_NOSYNC on
set_flags DB_DIRECT_DB off
set_flags DB_DSYNC_DB off
log_set_config DB_LOG_DIRECT off
log_set_config DB_LOG_DSYNC off
set_lg_dir .
set_data_dir ..
log_set_config DB_LOG_AUTO_REMOVE on
set_cachesize 0 2097152 1
mutex_set_max 30000
set_lg_max 10485760
set_lg_bsize 2097152
TagsBerkeleyDB, БД
Attached Files
SASPlanet.Debug.elf (85,988 bytes)
1031SASPlanet.Debug.elf (76,483 bytes)
1030 (5,277 bytes)   
Default locking region information:
681	Last allocated locker ID
0x7fffffff	Current maximum unused locker ID
9	Number of lock modes
1000	Maximum number of locks possible
1000	Maximum number of lockers possible
1000	Maximum number of lock objects possible
40	Number of lock object partitions
10	Number of current locks
232	Maximum number of locks at any one time
5	Maximum number of locks in any one bucket
0	Maximum number of locks stolen by for an empty partition
0	Maximum number of locks stolen for any one partition
10	Number of current lockers
123	Maximum number of lockers at any one time
10	Number of current lock objects
205	Maximum number of lock objects at any one time
4	Maximum number of lock objects in any one bucket
0	Maximum number of objects stolen by for an empty partition
0	Maximum number of objects stolen for any one partition
14M	Total number of locks requested (14973719)
14M	Total number of locks released (14973704)
0	Total number of locks upgraded
630	Total number of locks downgraded
4	Lock requests not available due to conflicts, for which we waited
5	Lock requests not available due to conflicts, for which we did not wait
0	Number of deadlocks
0	Lock timeout value
0	Number of locks that have timed out
0	Transaction timeout value
0	Number of transactions that have timed out
576KB	The size of the lock region
5	The number of partition locks that required waiting (0%)
4	The maximum number of times any partition lock was waited for (0%)
0	The number of object queue operations that required waiting (0%)
0	The number of locker allocations that required waiting (0%)
0	The number of region locks that required waiting (0%)
4	Maximum hash bucket length
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock REGINFO information:
Lock	Region type
5	Region ID
G:\cache_dbv\sat_all_v1\env\__db.005	Region name
0x330000	Region address
0x3300c8	Region primary address
0	Region maximum allocation
0	Region allocated
Region allocations: 87 allocations, 0 failures, 0 frees, 1 longest
Allocations by power-of-two sizes:
  1KB	2
  2KB	80
  4KB	1
  8KB	0
 16KB	2
 32KB	0
 64KB	0
128KB	2
256KB	0
512KB	0
1024KB	0
REGION_JOIN_OK	Region flags
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock region parameters:
30	Lock region region mutex [0/14 0% 5388/3984]
1031	locker table size
1031	object table size
752	obj_off
102384	locker_off
1	need_dd
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock conflict matrix:
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Locks grouped by lockers:
Locker   Mode      Count Status  ----------------- Object ---------------
     29f dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        2
     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        0
     2a0 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a1 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        2
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        0
     2a2 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a3 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        2
     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        0
     2a4 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a5 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        2
     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        0
     2a6 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a7 dd= 0 locks held 2    write locks 0    pid/thread 3488/1760 priority 100       
     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        2
     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        0
     2a8 dd= 0 locks held 0    write locks 0    pid/thread 3488/1760 priority 100       
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Locks grouped by object:
Locker   Mode      Count Status  ----------------- Object ---------------
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        0

     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        2

     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        2

     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        0

     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        0

     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        2

     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        0

     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        2

     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        2

     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        0

1030 (5,277 bytes)   
1031 (5,277 bytes)   
Default locking region information:
681	Last allocated locker ID
0x7fffffff	Current maximum unused locker ID
9	Number of lock modes
1000	Maximum number of locks possible
1000	Maximum number of lockers possible
1000	Maximum number of lock objects possible
40	Number of lock object partitions
10	Number of current locks
232	Maximum number of locks at any one time
5	Maximum number of locks in any one bucket
0	Maximum number of locks stolen by for an empty partition
0	Maximum number of locks stolen for any one partition
10	Number of current lockers
123	Maximum number of lockers at any one time
10	Number of current lock objects
205	Maximum number of lock objects at any one time
4	Maximum number of lock objects in any one bucket
0	Maximum number of objects stolen by for an empty partition
0	Maximum number of objects stolen for any one partition
14M	Total number of locks requested (14973719)
14M	Total number of locks released (14973704)
0	Total number of locks upgraded
630	Total number of locks downgraded
4	Lock requests not available due to conflicts, for which we waited
5	Lock requests not available due to conflicts, for which we did not wait
0	Number of deadlocks
0	Lock timeout value
0	Number of locks that have timed out
0	Transaction timeout value
0	Number of transactions that have timed out
576KB	The size of the lock region
5	The number of partition locks that required waiting (0%)
4	The maximum number of times any partition lock was waited for (0%)
0	The number of object queue operations that required waiting (0%)
0	The number of locker allocations that required waiting (0%)
0	The number of region locks that required waiting (0%)
4	Maximum hash bucket length
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock REGINFO information:
Lock	Region type
5	Region ID
G:\cache_dbv\sat_all_v1\env\__db.005	Region name
0x240000	Region address
0x2400c8	Region primary address
0	Region maximum allocation
0	Region allocated
Region allocations: 87 allocations, 0 failures, 0 frees, 1 longest
Allocations by power-of-two sizes:
  1KB	2
  2KB	80
  4KB	1
  8KB	0
 16KB	2
 32KB	0
 64KB	0
128KB	2
256KB	0
512KB	0
1024KB	0
REGION_JOIN_OK	Region flags
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock region parameters:
30	Lock region region mutex [0/17 0% 5316/5520]
1031	locker table size
1031	object table size
752	obj_off
102384	locker_off
1	need_dd
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock conflict matrix:
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Locks grouped by lockers:
Locker   Mode      Count Status  ----------------- Object ---------------
     29f dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        2
     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        0
     2a0 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a1 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        2
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        0
     2a2 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a3 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        2
     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        0
     2a4 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a5 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        2
     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        0
     2a6 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a7 dd= 0 locks held 2    write locks 0    pid/thread 3488/1760 priority 100       
     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        2
     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        0
     2a8 dd= 0 locks held 0    write locks 0    pid/thread 3488/1760 priority 100       
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Locks grouped by object:
Locker   Mode      Count Status  ----------------- Object ---------------
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        0

     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        2

     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        2

     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        0

     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        0

     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        2

     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        0

     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        2

     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        2

     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        0

1031 (5,277 bytes)   
1042 (5,277 bytes)   
Default locking region information:
681	Last allocated locker ID
0x7fffffff	Current maximum unused locker ID
9	Number of lock modes
1000	Maximum number of locks possible
1000	Maximum number of lockers possible
1000	Maximum number of lock objects possible
40	Number of lock object partitions
10	Number of current locks
232	Maximum number of locks at any one time
5	Maximum number of locks in any one bucket
0	Maximum number of locks stolen by for an empty partition
0	Maximum number of locks stolen for any one partition
10	Number of current lockers
123	Maximum number of lockers at any one time
10	Number of current lock objects
205	Maximum number of lock objects at any one time
4	Maximum number of lock objects in any one bucket
0	Maximum number of objects stolen by for an empty partition
0	Maximum number of objects stolen for any one partition
14M	Total number of locks requested (14973719)
14M	Total number of locks released (14973704)
0	Total number of locks upgraded
630	Total number of locks downgraded
4	Lock requests not available due to conflicts, for which we waited
5	Lock requests not available due to conflicts, for which we did not wait
0	Number of deadlocks
0	Lock timeout value
0	Number of locks that have timed out
0	Transaction timeout value
0	Number of transactions that have timed out
576KB	The size of the lock region
5	The number of partition locks that required waiting (0%)
4	The maximum number of times any partition lock was waited for (0%)
0	The number of object queue operations that required waiting (0%)
0	The number of locker allocations that required waiting (0%)
0	The number of region locks that required waiting (0%)
4	Maximum hash bucket length
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock REGINFO information:
Lock	Region type
5	Region ID
G:\cache_dbv\sat_all_v1\env\__db.005	Region name
0x370000	Region address
0x3700c8	Region primary address
0	Region maximum allocation
0	Region allocated
Region allocations: 87 allocations, 0 failures, 0 frees, 1 longest
Allocations by power-of-two sizes:
  1KB	2
  2KB	80
  4KB	1
  8KB	0
 16KB	2
 32KB	0
 64KB	0
128KB	2
256KB	0
512KB	0
1024KB	0
REGION_JOIN_OK	Region flags
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock region parameters:
30	Lock region region mutex [0/20 0% 4684/5052]
1031	locker table size
1031	object table size
752	obj_off
102384	locker_off
1	need_dd
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Lock conflict matrix:
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Locks grouped by lockers:
Locker   Mode      Count Status  ----------------- Object ---------------
     29f dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        2
     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        0
     2a0 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a1 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        2
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        0
     2a2 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a3 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        2
     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        0
     2a4 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a5 dd= 0 locks held 2    write locks 0    pid/thread 3488/1236 priority 100       
     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        2
     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        0
     2a6 dd= 0 locks held 0    write locks 0    pid/thread 3488/1236 priority 100       
     2a7 dd= 0 locks held 2    write locks 0    pid/thread 3488/1760 priority 100       
     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        2
     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        0
     2a8 dd= 0 locks held 0    write locks 0    pid/thread 3488/1760 priority 100       
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Locks grouped by object:
Locker   Mode      Count Status  ----------------- Object ---------------
     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        0

     2a1 READ          1 HELD    z18\77\46\310.184.tnev:    handle        2

     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        2

     2a3 READ          1 HELD    z18\77\46\310.185.sdbv:    handle        0

     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        0

     29f READ          1 HELD    z18\77\46\310.184.sdbv:    handle        2

     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        0

     2a7 READ          1 HELD    z13\2\1\9.5.sdbv:          handle        2

     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        2

     2a5 READ          1 HELD    z18\77\46\310.185.tnev:    handle        0

1042 (5,277 bytes)   

Relationships

related to 0002002 resolvedzed Error: EBerkeleyDBExeption: Error #-30994: DB_LOCK_DEADLOCK: Locker killed to resolve a deadlock 

Activities

zed

23-07-2013 05:17

manager   ~0012166

Было запущено несколько копий SAS?

Garl

23-07-2013 05:51

manager   ~0012167

нет всего одна копия,
но в процессах возможно висел зависший sdb_util.exe

zed

23-07-2013 06:10

manager   ~0012168

Сделал обработку дедлоков при открытии БД. Должно помочь, если вдруг два SAS одновременно ломанутся открывать один и тот же файл. Но от зависших захватов БД это естественно не спасёт.

Если ещё такая ошибка повторится, то нужно будет собрать дополнительную статистику по локерам: db_stat -C A -h %путь_до_папки_env%

Garl

23-07-2013 07:07

manager   ~0012169

это после упавшей САС и при каком-либо висящем процессе?

zed

23-07-2013 09:30

manager   ~0012170

Да.

Garl

25-07-2013 06:33

manager   ~0012193

Last edited: 25-07-2013 06:34

1030 до закрытия программы
1031 после закрытия программы

бил импорт кэша из Сас в версионный Беркли

zed

25-07-2013 06:40

manager   ~0012194

>после закрытия программы
А кто значится в процессах в диспетчере задач под pid 3488? После закрытия САС список локов должен был полностью очиститься, а у тебя что до, что после - картина одинаковая.

Garl

25-07-2013 06:42

manager   ~0012195

Last edited: 25-07-2013 06:43

уже нету

zed

25-07-2013 06:43

manager   ~0012196

А elf создался?

Garl

25-07-2013 06:44

manager   ~0012197

приложил

zed

25-07-2013 06:46

manager   ~0012198

Ты приложил 3-й идентичный файл.

Garl

25-07-2013 06:49

manager   ~0012199

Last edited: 25-07-2013 06:49

http://sasgis.org/mantis/file_download.php?file_id=1448&type=bug

1031SASPlanet.Debug.elf

zed

25-07-2013 06:57

manager   ~0012200

Версия: 13.7.23.7375, а мои изменения были в 7376. И судя по логу, pid 3488 принадлежал САСу, но мне не понятно, почему после его закрытия у тебя оно показывает несуществующие локи.

Garl

25-07-2013 09:57

manager   ~0012202

блин видать не в тут папку распаковывал ночнушку.
тады щас буду пробовать воспроизвести

zed

25-07-2013 09:59

manager   ~0012203

А нет ночнушки с этим билдом. Не залилась.

zed

25-07-2013 15:30

manager   ~0012204

Добавь в DB_CONFIG строчку:

set_verbose DB_VERB_DEADLOCK on

Тогда при дедлоке в msg.log может свалится какая-нибудь дополнительная информация.

zed

25-07-2013 15:36

manager   ~0012205

И кстати, на ошибку напоролась карта заполнения.

Issue History

Date Modified Username Field Change
23-07-2013 04:36 Garl New Issue
23-07-2013 04:36 Garl File Added: SASPlanet.Debug.elf
23-07-2013 04:37 Garl Relationship added related to 0002002
23-07-2013 04:38 Garl Tag Attached: BerkeleyDB
23-07-2013 04:38 Garl Tag Attached: БД
23-07-2013 05:17 zed Note Added: 0012166
23-07-2013 05:51 Garl Note Added: 0012167
23-07-2013 06:10 zed Note Added: 0012168
23-07-2013 07:07 Garl Note Added: 0012169
23-07-2013 09:30 zed Note Added: 0012170
25-07-2013 06:32 Garl File Added: 1031SASPlanet.Debug.elf
25-07-2013 06:32 Garl File Added: 1030
25-07-2013 06:33 Garl File Added: 1031
25-07-2013 06:33 Garl Note Added: 0012193
25-07-2013 06:34 Garl Note Edited: 0012193
25-07-2013 06:40 zed Note Added: 0012194
25-07-2013 06:42 Garl Note Added: 0012195
25-07-2013 06:42 Garl File Added: 1042
25-07-2013 06:43 zed Note Edited: 0012195
25-07-2013 06:43 zed Note Added: 0012196
25-07-2013 06:44 Garl Note Added: 0012197
25-07-2013 06:46 zed Note Added: 0012198
25-07-2013 06:49 Garl Note Added: 0012199
25-07-2013 06:49 Garl Note Edited: 0012199
25-07-2013 06:57 zed Note Added: 0012200
25-07-2013 09:57 Garl Note Added: 0012202
25-07-2013 09:59 zed Note Added: 0012203
25-07-2013 15:30 zed Note Added: 0012204
25-07-2013 15:36 zed Note Added: 0012205
08-08-2013 13:43 vdemidov Assigned To => zed
08-08-2013 13:43 vdemidov Status new => assigned
08-08-2013 13:43 vdemidov Target Version => 131111
08-08-2013 14:15 zed Status assigned => feedback
11-11-2013 08:43 zed Target Version 131111 => 140303
11-11-2013 09:30 zed Status feedback => resolved
11-11-2013 09:30 zed Fixed in Version => 131111
11-11-2013 09:30 zed Resolution open => fixed
11-11-2013 10:29 vdemidov Target Version 140303 => 131111
08-08-2025 13:22 zed Category Баг => Баг / Bug