Уважаемые господа,

есть такая "задача" (Linux+Informix Dynamic Server 2000 Version 9.21.UC4 ): обнаружено было, что сервер вдруг оказался не поднятым. (подняли, все ок)
но можно ли как-то средствами информикса выяснить по какой причине был отключен сервер

пока идеи 2:
1. был произведен запуск onmode -ky
2. был прибит процесс oninit на linux-е

возможно ли такое внезапное падение сервера из-за внутренних ошибок?
где-то в информиксе можно посмотреть, когда сервер отключали, и каким образом это прозошло, причины?

вопрос второй:

на линуксе есть пользователь informix
к чему может привести смена пароля этого пользователя, если сервер как был так и останется на этом серваке? повлияет ли это как-то на приложения, которые взаимодействуют с информиксом? (есть C-приложения на том же сервере, и на других серверах, есть Delphi -приложения, коннектящиеся через BDE, есть php - скрипты, работающие с базой). Все таблицы и базы созданы от имени пользователя informix, всем остальным приложениям не нужно создание/удаление баз, только добавление/удаление данных.

Сспасибо.

вот что дает online.log:
===========================================
12:26:44 Fuzzy Checkpoint Completed: duration was 2 seconds, 1049 buffers not flushed.
12:26:44 Checkpoint loguniq 7186, logpos 0xfc122c

12:31:06 Logical Log 7186 Complete.
12:31:09 Logical Log 7186 - Backup Started
12:31:10 Logical Log 7186 - Backup Completed
12:31:20 Assert Failed: Mutex Holder Value is not Null
12:31:20 Informix Dynamic Server 2000 Version 9.21.UC4
12:31:20 Who: Session(752, oper@db1, 21229, 349179392)
Thread(962, sqlexec, 14cd56d8, 1)
File: mt.c Line: 7055
12:31:20 stack trace for pid 19153 written to /tmp/af.7aa54e7
12:31:20 See Also: /tmp/af.7aa54e7
12:31:23 mt.c, line 7055, thread 962, proc id 19153, Mutex Holder Value is not Null.
12:31:23 The Master Daemon Died
12:31:23 PANIC: Attempting to bring system down
12:43:16 Informix Dynamic Server 2000 Started.
Wed Nov 24 12:43:17 2004

12:43:17 Event alarms enabled. ALARMPROG = '/opt/informix-9.21/etc/log_full.sh'
12:43:17 Booting Language <c> from module <>
12:43:17 Loading Module <CNULL>
12:43:17 Booting Language <builtin> from module <>
12:43:17 Loading Module <BUILTINNULL>
12:43:23 Informix Dynamic Server 2000 Version 9.21.UC4 Software Serial Number AAD#J125560
12:43:24 Dynamically allocated new virtual shared memory segment (size 8192KB)
12:43:27 Informix Dynamic Server 2000 Initialized -- Shared Memory Initialized.

12:43:27 Physical Recovery Started at Page(8:49736).
12:43:27 Physical Recovery Complete: 192 Pages Examined 192 Pages Restored.

12:43:27 Logical Recovery Started.
12:43:27 10 recovery worker threads will be started.
12:43:32 Fast Recovery Switching to Log 7187
12:43:35 Logical Recovery Complete.
8065 Committed, 2 Rolled Back, 0 Open, 0 Bad Locks

12:43:36 Dataskip is now OFF for all dbspaces

12:43:44 (9) connection rejected - no calls allowed for sqlexec
12:43:44 listener-thread: err = -27002: oserr = 0: errstr = : No connections are allowed in quiescent mode.

12:43:44 Checkpoint Completed: duration was 8 seconds.
12:43:44 Checkpoint loguniq 7187, logpos 0x2018

12:43:44 Dropping temporary TBLspace 100056, recovering 8 pages.
12:43:44 Dropping temporary TBLspace 100057, recovering 8 pages.
12:43:44 On-Line Mode
12:43:48 Booting Language <spl> from module <>
12:43:48 Loading Module <SPLNULL>
12:44:28 Dynamically allocated new virtual shared memory segment (size 8192KB)
12:48:47 Fuzzy Checkpoint Completed: duration was 2 seconds, 106 buffers not flushed.
12:48:47 Checkpoint loguniq 7187, logpos 0x6b704

12:53:50 Fuzzy Checkpoint Completed: duration was 2 seconds, 224 buffers not flushed.
12:53:50 Checkpoint loguniq 7187, logpos 0xed0f0

12:57:20 Dynamically allocated new virtual shared memory segment (size 8192KB)
12:58:52 Fuzzy Checkpoint Completed: duration was 2 seconds, 267 buffers not flushed.
12:58:52 Checkpoint loguniq 7187, logpos 0x161100


===========================================
файл /tmp/af.7aa54e7 достаточно большой, чтобы привести его здесь...
но если что-то надо - посмотрю в нем.

на сервере крутится ontape -c, который перезапускается раз в сутки (интересно, с чем может быть связана такая логика?) и файл бэекапа логического журнала скидывается по ftp на другой сервак.

если смотреть в файле console.log
=======================================
<<Informix Dynamic Server 2000>>> Logical Log 7186 Complete.
Assertion Failure Type:\t\c
CRASH
Host Name:\t\tdb1
Database Server Name:\tdb1_shm
Time of failure:\t\c
Wed Nov 24 12:31:20 MSK 2004
AF file:\t\t/tmp/af.7aa54e7
Shared memory file:\tNone
System Blocking:\t\c
OFF



<<Informix Dynamic Server 2000>>> Logical Log 7187 Complete.

<<Informix Dynamic Server 2000>>> Logical Log 7188 Complete.

<<Informix Dynamic Server 2000>>> Logical Log 7189 Complete.

<<Informix Dynamic Server 2000>>> Logical Log 7190 Complete.

<<Informix Dynamic Server 2000>>> Logical Log 7191 Complete.

<<Informix Dynamic Server 2000>>> Logical Log 7192 Complete.
=======================================

ну что ошибка может возникнуть при бэекапе - это конечно, более менее понятно, что из-за нее мог упасть сервер - не понятно.

попробовала килнуть (kill -9 pid)
другой сервер аналогичной конфигурации.
вот что выдал в online.log:

===================================
17:49:53 The Master Daemon Died
17:49:53 PANIC: Attempting to bring system down
17:49:53 semctl: errno = 22

17:49:58 Informix Dynamic Server 2000 Started.

Thu Nov 25 17:49:59 2004

17:49:59 Event alarms enabled. ALARMPROG = '/opt/informix-9.21/etc/log_full.sh'
17:49:59 Booting Language <c> from module <>
17:49:59 Loading Module <CNULL>
17:49:59 Booting Language <builtin> from module <>
17:49:59 Loading Module <BUILTINNULL>
17:50:04 Informix Dynamic Server 2000 Version 9.21.UC4 Software Serial Number AAD#J125560
17:50:07 Informix Dynamic Server 2000 Initialized -- Shared Memory Initialized.

17:50:07 Physical Recovery Started at Page(1:566).
17:50:07 Physical Recovery Complete: 0 Pages Examined 0 Pages Restored.

17:50:07 Logical Recovery Started.
17:50:07 10 recovery worker threads will be started.
17:50:10 Logical Recovery Complete.
0 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks

17:50:11 Dataskip is now OFF for all dbspaces
17:50:11 On-Line Mode
17:50:11 Checkpoint Completed: duration was 0 seconds.
17:50:11 Checkpoint loguniq 5096, logpos 0x11fd018

===================================

остановка сервака штатными средствами тоже не подтвердилась, так как выдаются совсем другие сообщения типа простого:

18:42:35 Informix Dynamic Server 2000 Stopped.

18:43:32 Informix Dynamic Server 2000 Started.

===================================

что это было? и как предупредить появление такого рода проблем в дальнейшем?

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

в /var/log/messages
в районе этого "происшествия" никакой информации о рестарте сервера linux:

=====================================

Nov 24 10:01:00 db1 PAM_pwdb[24389]: (su) session closed for user informix
Nov 24 11:01:00 db1 PAM_pwdb[24496]: (su) session opened for user informix by (uid=0)
Nov 24 11:01:00 db1 PAM_pwdb[24496]: (su) session closed for user informix
Nov 24 12:01:00 db1 PAM_pwdb[24603]: (su) session opened for user informix by (uid=0)
Nov 24 12:01:00 db1 PAM_pwdb[24603]: (su) session closed for user informix
Nov 24 13:01:00 db1 PAM_pwdb[25179]: (su) session opened for user informix by (uid=0)
Nov 24 13:01:00 db1 PAM_pwdb[25179]: (su) session closed for user informix
Nov 24 14:01:00 db1 PAM_pwdb[25293]: (su) session opened for user informix by (uid=0)
=====================================

то есть никто сервер не перегружал, скорее всего

cprа вообще надо прибивать того кто прибивает oninit вручную

informix зело надежен даже в таком случае, но руки надо рубить по самые плечи.

вот и пытаемся выяснить, есть ли такие хм.... №"№;%
но суда по логам, вручную он не останавливался.
а почему упал - пытаюсь выяснить, но пока не ясно.

Журавлев Денис

Ирина, вы о чем? В логе ясно видно что информикс умер из-за assertion fail !!!


так..., чтобы посмотреть что пишется в логи в таком варианте.
благо его кошки, на которых можно протестировать.

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

да она отсутвует.
интересно, а как это может быть связано с бэкапом?
может ли это быть связано с тем, что накрылись индексы? или с тем, что начинает сыпаться винт?

vybegalloIrina, the most important piece of information is stack trace from af file. Could you post it here ?
Ирина, это очень похоже на баг Информикса. Запостьте stack trace из af file

Он достаточно большой. больше 20тыс строк. может быть подскажите, на что именно в нем обратить внимание?

Jek_aПосмотрите в stack trace af.7aa54e7 что связанно вот с этой сесией :
12:31:20 Who: Session(752, oper@db1, 21229, 349179392)
Thread(962, sqlexec, 14cd56d8, 1) что эта сесия в этот момент делала.
/opt/informix-9.21/bin/onstat -g ses 752:

Informix Dynamic Server 2000 Version 9.21.UC4 -- On-Line -- Up 1 days 17:17:13 -- 134712 Kbytes

session #RSAM total used
id user tty pid hostname threads memory memory
752 oper - 21229 db1 1 1695744 1655872

tid name rstcb flags curstk status
962 sqlexec 14cd56d8 ---P--- 2772 14cd56d8 running

Memory pools count 2
name class addr totalsize freesize #allocfrag #freefrag
752 V 17455020 1671168 34024 3262 55
752_SORT_0 V 17348020 24576 5848 7 2

name free used name free used
overhead 0 3296 mtmisc 0 1280
scb 0 96 opentable 0 122392
filetable 0 12712 ru 0 224
misc 0 152 log 0 2152
temprec 0 16200 keys 0 192
ralloc 0 1286472 gentcb 0 1400
ostcb 0 2632 sort 0 17256
sqscb 0 87656 sql 0 40
srtmembuf 0 72 rdahead 0 112
hashfiletab 0 280 osenv 0 1504
buft_buffer 0 14952 sqtcb 0 13680
fragman 0 70712 shmblklist 0 136
sapi 0 128

Sess SQL Current Iso Lock SQL ISAM F.E.
Id Stmt type Database Lvl Mode ERR ERR Vers
752 SELECT stelt1 DR Not Wait 0 0 9.03

Last parsed SQL statement :
execute procedure
ufReadTariffs(fdDate='24/11/2004',fsNum_dialed='73212424478',fiRE_service=0
,fiTPlan=111,fiCrcyCust=2,fiCrcyDef=0,fiCust=3231,fiAcc=13424,
fiADtl=21573,fiAgent=1000058,fiProv_WS =0,fiTPlan_WS =0)



===========------------- - - - - - -
/opt/informix-9.21/bin/onstat -g sql 752:

Informix Dynamic Server 2000 Version 9.21.UC4 -- On-Line -- Up 1 days 17:17:13 -- 134712 Kbytes

Sess SQL Current Iso Lock SQL ISAM F.E.
Id Stmt type Database Lvl Mode ERR ERR Vers
752 SELECT stelt1 DR Not Wait 0 0 9.03

Last parsed SQL statement :
execute procedure
ufReadTariffs(fdDate='24/11/2004',fsNum_dialed='73212424478',fiRE_service=0
,fiTPlan=111,fiCrcyCust=2,fiCrcyDef=0,fiCust=3231,fiAcc=13424,
fiADtl=21573,fiAgent=1000058,fiProv_WS =0,fiTPlan_WS =0)



===========------------- - - - - - -
/opt/informix-9.21/bin/onstat -g sql:

Informix Dynamic Server 2000 Version 9.21.UC4 -- On-Line -- Up 1 days 17:17:14 -- 134712 Kbytes

Sess SQL Current Iso Lock SQL ISAM F.E.
Id Stmt type Database Lvl Mode ERR ERR Vers
820 - stelt1 CR Not Wait 0 0 9.03
804 - stelt1 CR Not Wait 0 0 9.03
779 SELECT stelt1 CR Wait 5 0 0 9.03
762 SELECT stelt1 CR Wait 5 0 0 9.03
752 SELECT stelt1 DR Not Wait 0 0 9.03
723 - stelt1 CR Wait 5 0 0 9.03
696 - stelt1 CR Wait 5 0 0 9.03
678 SELECT stelt1 CR Wait 5 0 0 9.03
673 SELECT stelt1 CR Wait 5 0 0 9.03
670 - stelt1 CR Wait 5 0 0 9.03
667 - stelt1 CR Wait 5 0 0 9.03
666 SELECT stelt1 CR Wait 5 0 0 9.03
642 - - - Not Wait 0 0 9.03
74 - stelt1 CR Not Wait 0 0 9.03
11 - stelt1 CR Not Wait 0 0 9.03



===========------------- - - - - - -
/opt/informix-9.21/bin/onstat -g stk 962 light:

Informix Dynamic Server 2000 Version 9.21.UC4 -- On-Line -- Up 1 days 17:17:12 -- 134712 Kbytes

Stack for thread: 962 sqlexec
base: 0x17647000
len: 266240
pc: 0x084fcf56
tos: 0x1768752c
state: running
vp: 1

0x084fcf56 (oninit)mt_system(0x1768767c, 0x157cd848, 0x0, 0x15b23870, 0x7aa54e7, 0x15b1ca80)
0x085149e8 (oninit)afhandler(0x3, 0x15301370, 0x0, 0x0, 0x601, 0x1)
0x08514286 (oninit)afcrash_interface(0x15301370, 0x0, 0x0, 0x871fc48, 0x1b8f, 0x164febe0)
0x084f8a1b (oninit)mt_free_mutex(0x157cd848, 0x165a8488, 0x17687ae4, 0x80f362c, 0x164febe0, 0x15ac03f8)
0x080f1e8e (oninit)sapi_destroy_mem(0x164febe0, 0x15ac03f8, 0x17687b34, 0x82a05c2, 0x7a19, 0x20)
0x080f362c (oninit)remove_curmem(0x7a19, 0x20, 0x16767030, 0x15a1ddf0, 0x0, 0x1)
0x082a05c2 (oninit)close_cursor(0x16767030, 0x1354, 0x1420, 0x138c, 0x5f9a, 0x16767030)
0x08145a39 (oninit)ip_clsthiscur(0x16767030, 0x1354, 0xffffffff, 0x15a1ddf0, 0x16767030, 0x0)
0x081459a5 (oninit)ip_evalclscur(0x16767030, 0x402a1, 0x17320838, 0xc, 0x16061098, 0x16061030)
0x0813f54e (oninit)runproc (0x17687c8c, 0x17687c90, 0x16061098, 0x17320838, 0x0, 0x17687cdc)
0x08148e77 (oninit)udrlm_spl_execute(0x16b2c020, 0x165a0020, 0x0, 0x16061098, 0x0, 0x1)
0x082bb44b (oninit)udrlm_exec_routine(0x16b2c020, 0x165a0020, 0x0, 0x17320838, 0x17320820, 0x17687e94)
0x08160f4d (oninit)udr_execute(0x17320838, 0x0, 0x0, 0x169569d0, 0x16767030, 0x0)
0x0814975c (oninit)udr_spl_curnext(0x16767030, 0x17687e94, 0x166e9a10, 0x169569d0, 0x0, 0x17687e84)
0x081454bd (oninit)ip_curnext(0x0, 0xffffffff, 0x5f9a, 0x15e11a98, 0x17687e94, 0x0)
0x0814521f (oninit)ip_fetch(0x169569d0, 0x166e9a10, 0x169569d0, 0x166923e0, 0x85268ff, 0x16956990)
0x081e782b (oninit)getrow (0x169569d0, 0x1, 0x169569d0, 0x166923e0, 0x164fb120, 0x164fffff)
0x081e776a (oninit)fetchrow(0x169569d0, 0x1, 0x166923e0, 0x0, 0x17687f80, 0x81eb430)
0x081eb4e9 (oninit)sgetnext(0x169569d0, 0x166e9a10, 0x0, 0x4016fd60, 0x17687fc8, 0x82a0b51)
0x081eb430 (oninit)sgetrow (0x169569d0, 0x1, 0x0, 0x17, 0x64, 0x4016fd60)
0x082a0b51 (oninit)sq_sfetch(0xa, 0x4, 0x0, 0x1, 0x160aeb48, 0x17687ff8)
0x082f371d (oninit)sqmain (0x0, 0x0, 0x0, 0x0, 0x0, 0xc00056)
0x084feb59 (oninit)startup (0xc00056, 0x138a9a57, 0x1000d, 0x3ee03da, 0x0, 0x0)
0x00000000 (*nosymtab*)0x0




===========------------- - - - - - -


где-то так....

vybegalloИнформикс грохается пытаясь закрыть курсор, открытый в вашей процедуре (точнее, освободить память занятую курсором).
Это может быть

http://www-306.ibm.com/software/data/informix/pubs/library/notes/relnotes/ids_win_fixed_and_known_defects_9_30.txt

bug_number 104337
description SERVER CRASHED AT MT_FREE_MUTEX FUNCTION SAYING MUTEX HOLDER VALUE IS NOT
NULL WHEN CLIENTS WERE KILLED WHILE RUNNING MUX TEST
product_code ONLINE
component_code ASF
починенный в 9.30.тс1. Клиента не убивали непосредственно перед подением ?
Точнее вам может сказать только IBM саппорт.

одновременно запущено достаточно много клиентов, и отследить когда пользователи его отрубали - не знаю как.
или это можно опять-таки в логах на сервере посмотреть? в каких?

vybegalloЛоги клиентов, если специально не включена трассировка, не создаются и не хранятся, поскольку это достаточно затратный механизм.
Хорошо, а как посмотреть, включена трассировка или нет?

vybegalloНе включена :-).
Трассировка включается выставлением переменной окружения SQLIDEBUG Если она отсуствует - то трассировка не включена.
да, действительно не включена.
посмотрела в onconfig и и командой set