Discussion :

[farenheiit]

Bonjour,

Nous avons un client qui a migré en 10GR2 récemment.
Le client lourd de notre appli se trouve sur une machine windows et utilise un client 10.2.

Le problème est le suivant: si la fenêtre de l'application reste pendant un certain temps inactive (plus de 10 minutes) la session est déconnectée du serveur.

Il n'y a pas de message d'erreur du côté serveur (ni erreur session ni erreur listener). notre DBA de prod a activé une trace SQL*NET (niveau USER car le niveau SUPPORT faisait ralentir l'application) côté application cliente.
Voici le genre de message obtenu:

Code :

Sélectionner tout - Visualiser dans une fenêtre à part

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
 
[19-NOV-2009 09:35:32:718] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 09:35:32:718] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 09:35:32:734] nsrdr: got NSPTMK packet
[19-NOV-2009 09:35:32:734] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 09:35:32:734] nsdo: sending NSPTMK packet
[19-NOV-2009 09:35:32:734] nsrdr: got NSPTMK packet
[19-NOV-2009 09:35:32:734] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 09:35:32:734] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 09:35:32:734] nsrdr: got NSPTMK packet
[19-NOV-2009 09:35:32:734] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 09:35:32:734] nsdo: sending NSPTMK packet
[19-NOV-2009 09:35:32:734] nsrdr: got NSPTMK packet
[19-NOV-2009 09:35:32:734] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 09:35:32:734] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 12:39:59:578] ntt2err: soc 240 error - operation=5, ntresnt[0]=517, ntresnt[1]=54, ntresnt[2]=0
[19-NOV-2009 12:39:59:578] nserror: nsres: id=0, op=68, ns=12547, ns2=12560; nt[0]=517, nt[1]=54, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 15:11:20:203] nsc2addr: (DESCRIPTION=(ADDRESS=(PROTOCOL=BEQ)(PROGRAM=oracle)(ARGV0=oracleORCL)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))'))(CONNECT_DATA=(SID=ORCL)(CID=(PROGRAM=T:\clearvision\FrontEnd\Cv98.exe)(HOST=DSF090827B)(USER=ODBWBR))))
[19-NOV-2009 15:11:20:265] nsopen: opening transport...
[19-NOV-2009 15:11:20:265] nserror: nsres: id=0, op=65, ns=12560, ns2=0; nt[0]=530, nt[1]=126, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 15:11:20:265] nsopen: unable to open transport
 
[19-NOV-2009 15:11:26:765] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 15:12:23:531] nsclose: global context check-out (from slot 0) complete
[19-NOV-2009 17:51:49:328] ntt2err: soc 368 error - operation=5, ntresnt[0]=517, ntresnt[1]=54, ntresnt[2]=0
[19-NOV-2009 17:51:49:328] nserror: nsres: id=0, op=68, ns=12547, ns2=12560; nt[0]=517, nt[1]=54, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 17:51:49:328] nioqrc:  wanted 1 got 0, type 0
 
[19-NOV-2009 09:36:17:546] nsclose: global context check-out (from slot 0) complete
[19-NOV-2009 15:11:00:640] ntt2err: soc 360 error - operation=5, ntresnt[0]=517, ntresnt[1]=54, ntresnt[2]=0
[19-NOV-2009 15:11:00:656] nserror: nsres: id=0, op=68, ns=12547, ns2=12560; nt[0]=517, nt[1]=54, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0

Est-ce que ça parle à qqu'un ?
inutile de suspecter les PROFILES les IDLE_TIME sont à UNLIMITED

merci de votre iade

[laurentleturgez]

Salut,

Alors, visiblement ta couche réseau rencontre des erreurs ORA-12582, ORA-12630 et ORA-12547 (paramètre ns dans les lignes nserror: nsres.

Malheureusement, ca n'est pas très causant :

$ oerr tns 12582
12582, 00000, "TNS:invalid operation"
// *Cause: An internal function received an invalid request.
// *Action: Not normally visible to the user. For further details, turn
// on tracing and reexecute the operation. If error persists, contact
// Oracle Customer Support.

$ oerr tns 12630
12630, 00000, "Native service operation not supported"
// *Cause: An operation requested by a user is not supported by the native
// services component.
// *Action: This may be an internal error if the operation should have been
// supported.

$ oerr ORA 12547
12547, 00000, "TNS:lost contact"
// *Cause: Partner has unexpectedly gone away, usually during process
// startup.
// *Action: Investigate partner application for abnormal termination. On an
// Interchange, this can happen if the machine is overloaded.

Le cas échéant, y a t-il eu des modifications dans le fichier sqlnet.ora du client ?

Tu pourrais peut-être passé ton fichier de trace réseau dans l'utilitaire trcasst.

Ex :
$ trcasst -e2 -od -s tonfichierDeTrace.trc

Ca peut donner des infos intéressantes. Mais à ta place, j'ouvrirai un SR en parallèle.

Laurent

[farenheiit]

Merci Laurent pour ta réponse.

j'ai demandé au client de me fournir le sqlnet.ora

j'ai lancé l'assistant de traces. j'ai l'impression ça ne donne rien d'interessant:

Code :

Sélectionner tout - Visualiser dans une fenêtre à part

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
 
trcasst -e2 -od -s cli_2208_2.trc
 
Trace Assistant Utility : Version 10.2.0.4.0 Production (20 novembre 2009 15:18:29)
 
Copyright (c) 2001, 2007, Oracle. Tous droits rÚservÚs.
 
 
    *************************************************************************
    *                        Assistant Trace                                *
    *************************************************************************
 
[19-NOV-2009 15:11:26:734] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:734] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 15:11:26:734] nsdo: sending NSPTMK packet
[19-NOV-2009 15:11:26:765] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 15:11:26:734] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 15:11:26:734] nsdo: sending NSPTMK packet
[19-NOV-2009 15:11:26:765] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 15:11:26:765] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:765] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 15:11:26:765] nsdo: sending NSPTMK packet
[19-NOV-2009 15:11:26:765] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 15:11:26:765] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 15:11:26:765] nsdo: sending NSPTMK packet
[19-NOV-2009 15:11:26:765] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 15:11:26:765] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 15:11:26:781] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:781] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 15:11:26:781] nsdo: sending NSPTMK packet
[19-NOV-2009 15:11:26:781] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:781] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 15:11:26:781] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 15:11:26:781] nsdo: sending NSPTMK packet
[19-NOV-2009 15:11:26:781] nsrdr: got NSPTMK packet
[19-NOV-2009 15:11:26:781] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 15:11:26:781] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
 
----------------------
Statistiques du fichier de trace :
----------------------
Nombre total de sessions : 0
 
DATABASE :
  Nombre d'opÚrations :    0 ouvertures (OPEN),    0 analyses (PARSE),    0 exÚcutions (EXECUTE),    0 extractions (FETCH)
 
 
ORACLE NET SERVICES :
  Nombre total d'appels : Úmis         0, reþus         0, OCI         0
  Nombre total d'octets : Úmis         0, reþus         0
    Nombre moyen d'octets par paquet : Úmis , reþus
    Nombre maximum d'octets : Úmis         0, reþus         0
 
  Total gÚnÚral des paquets : Úmis      0, reþus      0
 
 
    *************************************************************************
    *                    Lassistant Trace a terminÚ.                      *
    *************************************************************************
 
 
trcasst -e2 -od -s cli_2092.trc
 
Trace Assistant Utility : Version 10.2.0.4.0 Production (20 novembre 2009 15:20:04)
 
Copyright (c) 2001, 2007, Oracle. Tous droits rÚservÚs.
 
 
    *************************************************************************
    *                        Assistant Trace                                *
    *************************************************************************
 
 
----------------------
Statistiques du fichier de trace :
----------------------
Nombre total de sessions : 0
 
DATABASE :
  Nombre d'opÚrations :    0 ouvertures (OPEN),    0 analyses (PARSE),    0 exÚcutions (EXECUTE),    0 extractions (FETCH)
 
 
ORACLE NET SERVICES :
  Nombre total d'appels : Úmis         0, reþus         0, OCI         0
  Nombre total d'octets : Úmis         0, reþus         0
    Nombre moyen d'octets par paquet : Úmis , reþus
    Nombre maximum d'octets : Úmis         0, reþus         0
 
  Total gÚnÚral des paquets : Úmis      0, reþus      0
 
 
    *************************************************************************
    *                    Lassistant Trace a terminÚ.                      *
    *************************************************************************
 
trcasst -e2 -od -s cli_3080_1.trc
 
Trace Assistant Utility : Version 10.2.0.4.0 Production (20 novembre 2009 15:20:48)
 
Copyright (c) 2001, 2007, Oracle. Tous droits rÚservÚs.
 
 
    *************************************************************************
    *                        Assistant Trace                                *
    *************************************************************************
 
[19-NOV-2009 12:41:04:609] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:609] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 12:41:04:609] nsdo: sending NSPTMK packet
[19-NOV-2009 12:41:04:609] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:609] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 12:41:04:609] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 12:41:04:609] nsdo: sending NSPTMK packet
[19-NOV-2009 12:41:04:609] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:609] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 12:41:04:609] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 12:41:04:609] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:609] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 12:41:04:609] nsdo: sending NSPTMK packet
[19-NOV-2009 12:41:04:609] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:609] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 12:41:04:609] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 12:41:04:609] nsdo: sending NSPTMK packet
[19-NOV-2009 12:41:04:609] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:609] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 12:41:04:609] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 12:41:04:625] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:625] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 12:41:04:625] nsdo: sending NSPTMK packet
[19-NOV-2009 12:41:04:625] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:625] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
[19-NOV-2009 12:41:04:625] nioqrc:  Recieve: returning error: 3111
[19-NOV-2009 12:41:04:625] nsdo: sending NSPTMK packet
[19-NOV-2009 12:41:04:625] nsrdr: got NSPTMK packet
[19-NOV-2009 12:41:04:625] nserror: nsres: id=0, op=70, ns=12582, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-NOV-2009 12:41:04:625] nserror: nsres: id=0, op=70, ns=12630, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
 
 
----------------------
Statistiques du fichier de trace :
----------------------
Nombre total de sessions : 0
 
DATABASE :
  Nombre d'opÚrations :    0 ouvertures (OPEN),    0 analyses (PARSE),    0 exÚcutions (EXECUTE),    0 extractions (FETCH)
 
 
ORACLE NET SERVICES :
  Nombre total d'appels : Úmis         0, reþus         0, OCI         0
  Nombre total d'octets : Úmis         0, reþus         0
    Nombre moyen d'octets par paquet : Úmis , reþus
    Nombre maximum d'octets : Úmis         0, reþus         0
 
  Total gÚnÚral des paquets : Úmis      0, reþus      0
 
 
    *************************************************************************
    *                    Lassistant Trace a terminÚ.                      *
    *************************************************************************

Comment interpréter ces résultats? y'a t'il des paramètres au niveau du listener ou au niveau du sqlnet.ora qui pourrait influer? ça ne sent pas le bug oracle ça ?

[laurentleturgez]

Comment interpréter ces résultats?

Euh ... ca me semble être le néant !!! On dirait qu'il ne se passe rien !
Peut-être faudrait-il avoir une trace un peu plus conséquente.

y'a t'il des paramètres au niveau du listener ou au niveau du sqlnet.ora qui pourrait influer?

Comme ca ... je n'en sais rien. Faudrait voir le sqlnet.ora et le listener.ora
et analyser le présent cas.

ça ne sent pas le bug oracle ça ?

Le seul moyen de le savoir serait d'ouvrir un SR. Mais le support risque de te demander une nouvelle trace certainement en niveau de trace SUPPORT.

Tes clients ne recoivent-ils pas une ORA-3113 ou similaire ?

Le problème est le suivant: si la fenêtre de l'application reste pendant un certain temps inactive (plus de 10 minutes) la session est déconnectée du serveur.

N'y a t-il pas non plus un firewall qui trainerait entre le client et le serveur qui shooterait la session après 10minutes d'inactivité ?

Laurent

[farenheiit]

voici la tête du sqlnet.ora:

Code :

Sélectionner tout - Visualiser dans une fenêtre à part

1
2
3
4
5
6
7
8
9
10
 
TRACE_LEVEL_CLIENT = USER
TRACE_DIRECTORY_CLIENT = c:\TEMP\trace
TRACE_TIMESTAMP_CLIENT = TRUE
TRACE_UNIQUE_CLIENT = TRUE
#sqlnet.authentication_services = (NONE)
names.directory_path = (TNSNAMES, HOSTNAME)
names.default_domain = world
name.default_zone = world
automatic_ipc = off

Apparemment les admin réseaux m'ont dit que ce n'était pas un pb de firewall.

[farenheiit]

Finalement, on a peut-être un pb au niveau du firewall mais l'équipe réseau ne veut pas investiguer plus que ça prétextant qu'elle ne connait pas la valeur par defaut du timed_out ni comment la modifier. De plus pour des mesures de sécurité ils ne veulent pas désactiver le timed out

On m'a parlé du paramètre SQLNET.EXPIRE_TIME.
Pensez-vous que ça peut résoudre mon pb ? qq'un l'a t'il déjà utiliser? si oui y'a t'il des effets de bord possibles ?

[laurentleturgez]

Je l'ai déjà utilisé sans effet de bord vu que ca a le même effet qu'un KeepAlive réseau.
Dans la mesure où le paquet de KeepAlive n'est pas envoyé toutes les secondes ... ca ne pose pas de problème.

Tu peux donc tester ce paramètre ... j'aurai fait la même chose !

Laurent