Помогите с расшифровкой trace файлов

arhey

Дата: 29.05.2006 13:08:05

Ситуация следующая.
Имеется сервер, на нем крутится база.
Часть компьютеров коннектится к ней, другая часть с недавнего времени перестала.

Пишет при коннекте ORA - 12560.

Поискал на форуме, ничего путного не нашел, кроме USE_Shared_Socket.
Его ставил не помогает.

Оттрассировал коннект.
Получил 2 файла.

(1976) [29-MAY-2006 12:56:41:775] 
--- TRACE CONFIGURATION INFORMATION FOLLOWS ---
(1976) [29-MAY-2006 12:56:41:775] New trace stream is c:\trace\lalalal.log_1976.trc
(1976) [29-MAY-2006 12:56:41:775] New trace level is 4
(1976) [29-MAY-2006 12:56:41:775] --- TRACE CONFIGURATION INFORMATION ENDS ---
(1976) [29-MAY-2006 12:56:41:775] 
--- PARAMETER SOURCE INFORMATION FOLLOWS ---
(1976) [29-MAY-2006 12:56:41:775] Attempted load of system pfile source C:\oracle\ora92\network\admin\sqlnet.ora
(1976) [29-MAY-2006 12:56:41:775] Parameter source loaded successfully
(1976) [29-MAY-2006 12:56:41:775] 
(1976) [29-MAY-2006 12:56:41:775] Attempted load of local pfile source C:\Program Files\PLSQL Developer\sqlnet.ora
(1976) [29-MAY-2006 12:56:41:775] Parameter source was not loaded
(1976) [29-MAY-2006 12:56:41:775] 
(1976) [29-MAY-2006 12:56:41:775]  -> PARAMETER TABLE LOAD RESULTS FOLLOW <-
(1976) [29-MAY-2006 12:56:41:775] Successful parameter table load
(1976) [29-MAY-2006 12:56:41:775]  -> PARAMETER TABLE HAS THE FOLLOWING CONTENTS <-
(1976) [29-MAY-2006 12:56:41:775]   TRACE_DIRECTORY_SERVER = c:\trace\server
(1976) [29-MAY-2006 12:56:41:775]   TRACE_DIRECTORY_CLIENT = c:\trace
(1976) [29-MAY-2006 12:56:41:775]   NAMES.DIRECTORY_PATH = (TNSNAMES, HOSTNAME)
(1976) [29-MAY-2006 12:56:41:775]   TRACE_LEVEL_CLIENT = USER
(1976) [29-MAY-2006 12:56:41:775]   TRACE_FILE_SERVER = server.log
(1976) [29-MAY-2006 12:56:41:775]   SQLNET.AUTHENTICATION_SERVICES = (NTS)
(1976) [29-MAY-2006 12:56:41:775]   TRACE_LEVEL_SERVER = USER
(1976) [29-MAY-2006 12:56:41:775]   TRACE_FILE_CLIENT = lalalal.log
(1976) [29-MAY-2006 12:56:41:775]   NAMES.DEFAULT_DOMAIN = alfa.bitec.ru
(1976) [29-MAY-2006 12:56:41:775] --- PARAMETER SOURCE INFORMATION ENDS ---
(1976) [29-MAY-2006 12:56:41:775] 
--- LOG CONFIGURATION INFORMATION FOLLOWS ---
(1976) [29-MAY-2006 12:56:41:775] Log stream will be "C:\Program Files\PLSQL Developer\sqlnet.log"
(1976) [29-MAY-2006 12:56:41:775] Log stream validation not requested
(1976) [29-MAY-2006 12:56:41:775] --- LOG CONFIGURATION INFORMATION ENDS ---

(1976) [29-MAY-2006 12:56:41:775] nigini: Count in NI global area now: 1
(1976) [29-MAY-2006 12:56:41:775] nigini: Count in NI global area now: 1
(1976) [29-MAY-2006 12:56:41:785] nrigbni: Unable to get data from navigation file tnsnav.ora
(1976) [29-MAY-2006 12:56:41:785] nnftmlf_make_local_addrfile: construction of local names file failed
(1976) [29-MAY-2006 12:56:41:785] nnftmlf_make_system_addrfile: system names file is C:\oracle\ora92\network\admin\tnsnames.ora
(1976) [29-MAY-2006 12:56:41:785] nigtrm: Count in the NI global area is now 0
(1976) [29-MAY-2006 12:56:41:785] nigtrm: Count in the NL global area is now 0

и второй

(1976) [29-MAY-2006 12:56:41:835] 
--- TRACE CONFIGURATION INFORMATION FOLLOWS ---
(1976) [29-MAY-2006 12:56:41:835] New trace stream is c:\trace\lalalal.log_1976_1.trc
(1976) [29-MAY-2006 12:56:41:835] New trace level is 4
(1976) [29-MAY-2006 12:56:41:835] --- TRACE CONFIGURATION INFORMATION ENDS ---
(1976) [29-MAY-2006 12:56:41:835] 
--- PARAMETER SOURCE INFORMATION FOLLOWS ---
(1976) [29-MAY-2006 12:56:41:835] Attempted load of system pfile source C:\oracle\ora92\network\admin\sqlnet.ora
(1976) [29-MAY-2006 12:56:41:835] Parameter source loaded successfully
(1976) [29-MAY-2006 12:56:41:835] 
(1976) [29-MAY-2006 12:56:41:835] Attempted load of local pfile source C:\Program Files\PLSQL Developer\sqlnet.ora
(1976) [29-MAY-2006 12:56:41:835] Parameter source was not loaded
(1976) [29-MAY-2006 12:56:41:835] 
(1976) [29-MAY-2006 12:56:41:835]  -> PARAMETER TABLE LOAD RESULTS FOLLOW <-
(1976) [29-MAY-2006 12:56:41:835] Successful parameter table load
(1976) [29-MAY-2006 12:56:41:835]  -> PARAMETER TABLE HAS THE FOLLOWING CONTENTS <-
(1976) [29-MAY-2006 12:56:41:835]   TRACE_DIRECTORY_SERVER = c:\trace\server
(1976) [29-MAY-2006 12:56:41:835]   TRACE_DIRECTORY_CLIENT = c:\trace
(1976) [29-MAY-2006 12:56:41:835]   NAMES.DIRECTORY_PATH = (TNSNAMES, HOSTNAME)
(1976) [29-MAY-2006 12:56:41:835]   TRACE_LEVEL_CLIENT = USER
(1976) [29-MAY-2006 12:56:41:835]   TRACE_FILE_SERVER = server.log
(1976) [29-MAY-2006 12:56:41:835]   SQLNET.AUTHENTICATION_SERVICES = (NTS)
(1976) [29-MAY-2006 12:56:41:835]   TRACE_LEVEL_SERVER = USER
(1976) [29-MAY-2006 12:56:41:835]   TRACE_FILE_CLIENT = lalalal.log
(1976) [29-MAY-2006 12:56:41:835]   NAMES.DEFAULT_DOMAIN = alfa.bitec.ru
(1976) [29-MAY-2006 12:56:41:835] --- PARAMETER SOURCE INFORMATION ENDS ---
(1976) [29-MAY-2006 12:56:41:835] 
--- LOG CONFIGURATION INFORMATION FOLLOWS ---
(1976) [29-MAY-2006 12:56:41:835] Log stream will be "C:\Program Files\PLSQL Developer\sqlnet.log"
(1976) [29-MAY-2006 12:56:41:835] Log stream validation not requested
(1976) [29-MAY-2006 12:56:41:835] --- LOG CONFIGURATION INFORMATION ENDS ---

(1976) [29-MAY-2006 12:56:41:835] nigini: Count in NI global area now: 1
(1976) [29-MAY-2006 12:56:41:835] nigini: Count in NI global area now: 1
(1976) [29-MAY-2006 12:56:41:835] nrigbni: Unable to get data from navigation file tnsnav.ora
(1976) [29-MAY-2006 12:56:41:835] niotns: niotns: setting up interrupt handler...
(1976) [29-MAY-2006 12:56:41:835] niotns: Not trying to enable dead connection detection.
(1976) [29-MAY-2006 12:56:41:835] niotns: Calling address: (DESCRIPTION=(ADDRESS_LIST=(ADDRESS=(PROTOCOL=TCP)(HOST=s18)(PORT=1521)))(CONNECT_DATA=(SERVER=DEDICATED)(SERVICE_NAME=dvp)(CID=(PROGRAM=C:\Program Files\PLSQL Developer\plsqldev.exe)(HOST=B8)(USER=dandreev))))
(1976) [29-MAY-2006 12:56:41:835] nscall: connecting...
(1976) [29-MAY-2006 12:56:41:845] nttgetport: port resolved to 1521
(1976) [29-MAY-2006 12:56:41:845] nttbnd2addr: looking up IP addr for host: s18
(1976) [29-MAY-2006 12:58:14:408] nsopen: opening transport...
(1976) [29-MAY-2006 12:58:14:408] nttcnp: Validnode Table IN use; err 0x0
(1976) [29-MAY-2006 12:58:14:408] nttcni: trying to connect to socket 548.
(1976) [29-MAY-2006 12:58:14:509] ntt2err: soc 548 error - operation=1, ntresnt[0]=530, ntresnt[1]=50, ntresnt[2]=0
(1976) [29-MAY-2006 12:58:14:529] nserror: nsres: id=0, op=65, ns=12560, ns2=0; nt[0]=530, nt[1]=50, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
(1976) [29-MAY-2006 12:58:14:529] nsopen: unable to open transport
(1976) [29-MAY-2006 12:58:14:529] nioqper:  error from nscall
(1976) [29-MAY-2006 12:58:14:529] nioqper:    nr err code: 0
(1976) [29-MAY-2006 12:58:14:529] nioqper:    ns main err code: 12560
(1976) [29-MAY-2006 12:58:14:529] nioqper:    ns (2)  err code: 0
(1976) [29-MAY-2006 12:58:14:529] nioqper:    nt main err code: 530
(1976) [29-MAY-2006 12:58:14:529] nioqper:    nt (2)  err code: 50
(1976) [29-MAY-2006 12:58:14:529] nioqper:    nt OS   err code: 0
(1976) [29-MAY-2006 12:58:14:529] niqme: reporting NS-12560 error as ORA-12560
(1976) [29-MAY-2006 12:58:14:529] niomapnserror: returning error 12560
(1976) [29-MAY-2006 12:58:14:529] niotns: Couldn't connect, returning 12560
(1976) [29-MAY-2006 12:58:14:589] nigtrm: Count in the NI global area is now 0
(1976) [29-MAY-2006 12:58:14:589] nigtrm: Count in the NL global area is now 0

прокомментируйте плз.

hell

Дата: 29.05.2006 13:34:39

Поставили firewall?
__________________
For more information, please proceed to http://www.ot-e.biz

arhey

Дата: 29.05.2006 13:43:19

hell

Поставили firewall?

скорее всего да.
я не являюсь админом сетки, и точно сказать не могу.

Но это очень вероятно.

Что делать?
(или вариант снести firewall единственный ? :) )

arhey

Дата: 29.05.2006 14:55:37

Ок.
Вопрос снимается.
Дело было действительно в фаере, на клиенте установленном.

Но все равно буду признателен за ссылку, где хотя бы в общих чертах по этим трейс файлам есть.

или такой штуки нету и единственный способ получить комментарий - это обратится в support oracle, как написано в документации?

arhey

Дата: 29.05.2006 14:57:20

to hell.
Спасибо.

hell

Дата: 29.05.2006 15:37:15

Да я в первый раз их читаю))

вот строчка ключевая - (1976) [29-MAY-2006 12:58:14:408] nttcni: trying to connect to socket 548

И туда он законнектится не смог...

__________________
For more information, please proceed to http://www.ot-e.biz

arhey

Дата: 29.05.2006 15:52:44

hell

И туда он законнектится не смог...

это понятно... :)
мне было интересно почему не смог.

ну ладно. Расшифровка таких файлов - это вопрос скорее для системных администраторов, видимо.

Barkovsky

Дата: 29.05.2006 21:33:59

для всех любопытных.

arhey

Дата: 30.05.2006 17:16:11

Barkovsky

для всех любопытных.

:)

именно оттуда я узнал как получить эти логи, но про то, как их расшифровать там нет ни слова, кроме

As an example, consider the following nserror entry logged in the trace file shown in Figure 17-6:

[22-JUL-2002 13:35:09:625] nserror: nsres: id=0, op=68, ns=12537, 
ns2=12560;
nt[0]=507, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0


Using oerr, you can find out more information about return codes 12537 and 507. (Bold denotes user input.)

oerr tns 12537
12537, 00000, "TNS:connection closed"
// *Cause: "End of file" condition has been reached; partner has
disconnected.
// *Action: None needed; this is an information message.

oerr tns 507
00507, 00000, "Connection closed"
// *Cause: Normal "end of file" condition has been reached; partner has
// disconnected.
// *Action: None needed; this is an information message.

etc.

но это просто отсылка на мою же ошибку 12560

(1976) [29-MAY-2006 12:58:14:529] nserror: nsres: id=0, op=65, ns=12560, ns2=0; nt[0]=530, nt[1]=50, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0

потому и спрашивал.

я связать это с фаерами не хватило знаний о сетях.
Спасибо еще раз hell за наводку.

Barkovsky

Дата: 30.05.2006 21:40:42

неправда, там еще есть утилитка trcasst, от нее, может, понятней и не становится - но это уже оффтопик.