Hi,
I am stakk with connection problem on customer Oracle server. Oracle database is installed on Microsoft Windows Server 2012 Standard. Version of Oracle DB is 11.2.0.4 Standard One Edition.
Oracle database works fine on server. I had no problems/errors while installing (as admin). My user is memer of Local Admin Group. I am also able to connect to it with sqlplus (sqlplus username/password@tns_alias) and SQL Developer.
Content of tnsnames, listener og sqlnet files are following:
tnsnames.ora
ORACLR_CONNECTION_DATA =
(DESCRIPTION =
(ADDRESS_LIST = (ADDRESS = (PROTOCOL = IPC)(KEY = EXTPROC1521)) )
(CONNECT_DATA = (SID = CLRExtProc) (PRESENTATION = RO) ) )
SALES =
(DESCRIPTION = (ADDRESS = (PROTOCOL = TCP)(HOST = 192.168.1.9)(PORT = 1521))
(CONNECT_DATA = (SERVER = DEDICATED) (SERVICE_NAME = sales.company.se)))
listener.ora
SID_LIST_LISTENER =
(SID_LIST =
(SID_DESC =
(SID_NAME = CLRExtProc) (ORACLE_HOME = D:\oracle\product\11.2.0\dbhome_1)
(PROGRAM = extproc) (ENVS = "EXTPROC_DLLS=ONLY:D:\oracle\product\11.2.0\dbhome_1\bin\oraclr11.dll") ) )
LISTENER =
(DESCRIPTION_LIST =
(DESCRIPTION = (ADDRESS = (PROTOCOL = TCP)(HOST = servername.company.se)(PORT = 1521))
(ADDRESS = (PROTOCOL = IPC)(KEY = EXTPROC1521))))
ADR_BASE_LISTENER = D:\oracle
INBOUND_CONNECTION_TIMEOUT_LISTENER=180
sqlnet.ora
SQLNET.AUTHENTICATION_SERVICES= (NONE)
SQLNET.INBOUND_CONNECT_TIMEOUT = 180
ADR_BASE = D:\oracle\product\11.2.0\dbhome_1\log
I am using VPN tunnel to connect to database from my client. I can ping server successfully. TNSPING to database works fine, but I am not able to connect to database. I get an error on client:
ORA-12537 connection closed error.
Alert log content is following:
***********************************************************************
Fatal NI connect error 12547, connecting to:
(LOCAL=NO)
VERSION INFORMATION:
TNS for 64-bit Windows: Version 11.2.0.4.0 - Production
Oracle Bequeath NT Protocol Adapter for 64-bit Windows: Version 11.2.0.4.0 - Production
Windows NT TCP/IP NT Protocol Adapter for 64-bit Windows: Version 11.2.0.4.0 - Production
Time: 19-DEC-2013 13:01:47
Tracing not turned on.
Tns error struct:
ns main err code: 12547
TNS-12547: TNS:lost contact
ns secondary err code: 12560
nt main err code: 0
nt secondary err code: 0
nt OS err code: 0
opiodr aborting process unknown ospid (88) as a result of ORA-609
2013-12-19 13:05:28.990000 +01:00
***********************************************************************
Fatal NI connect error 12170.
VERSION INFORMATION:
TNS for 64-bit Windows: Version 11.2.0.4.0 - Production
Oracle Bequeath NT Protocol Adapter for 64-bit Windows: Version 11.2.0.4.0 - Production
Windows NT TCP/IP NT Protocol Adapter for 64-bit Windows: Version 11.2.0.4.0 - Production
Time: 19-DEC-2013 13:05:28
Tracing not turned on.
Tns error struct:
ns main err code: 12535
TNS-12535: TNS:operation timed out
ns secondary err code: 12606
nt main err code: 0
nt secondary err code: 0
nt OS err code: 0
Client address: <unknown>
opiodr aborting process unknown ospid (2384) as a result of ORA-609
2013-12-19 13:44:44.855000 +01:00
opiodr aborting process unknown ospid (1292) as a result of ORA-609
2013-12-19 14:30:34.351000 +01:00
I supposed that connection could use some more time so I set inbound_connection to 180 (3 minutes). Same error.
Then I turn on support trace. Here is analyzed content:
server_4424.trc
Trying to get IP client address results in error. Host IP is not correct.
[19-DEC-2013 13:42:35:692] nsgetclientaddress: Client address: "(ADDRESS=(PROTOCOL=tcp)(HOST=::1)(PORT=53749))"
[19-DEC-2013 13:42:35:692] nsgetclientaddress: exit
[19-DEC-2013 13:42:35:692] nszgclient: entry
[19-DEC-2013 13:42:35:692] nszgclient: using dedicated context
[19-DEC-2013 13:42:35:692] nazsgsnm: entry
[19-DEC-2013 13:42:35:692] nau_genm: entry
[19-DEC-2013 13:42:35:692] nau_genm: exit
[19-DEC-2013 13:42:35:692] nazsgsnm: failed with error 12630
[19-DEC-2013 13:42:35:692] nazsgsnm: exit
[19-DEC-2013 13:42:35:692] nszgclient: returning no username
[19-DEC-2013 13:42:35:692] nszgclient: returning no username
[19-DEC-2013 13:42:35:692] nszgclient: exit
[19-DEC-2013 13:42:35:692] nszgetuser: entry
[19-DEC-2013 13:42:35:692] nszgetuser: using dedicated context
[19-DEC-2013 13:42:35:692] nazsgunm: entry
[19-DEC-2013 13:42:35:692] nau_gnm: entry
[19-DEC-2013 13:42:35:692] nau_gnm: exit
[19-DEC-2013 13:42:35:692] nazsgunm: failed with error 12630
Getting package from client. And when reached to @ we get EOF (daflag=64)
[19-DEC-2013 13:42:35:724] nttfprd: exit
[19-DEC-2013 13:42:35:724] nsbasic_brc: type=6, plen=10
[19-DEC-2013 13:42:35:724] nsbasic_brc: what=1, tot =10
[19-DEC-2013 13:42:35:724] nsbasic_brc: packet dump
[19-DEC-2013 13:42:35:724] nsbasic_brc: 00 0A 00 00 06 00 00 00 |........|
[19-DEC-2013 13:42:35:724] nsbasic_brc: 00 40 |.@ |
[19-DEC-2013 13:42:35:724] nsbasic_brc: Got EOF (daflag=64).
[19-DEC-2013 13:42:35:724] nserror: entry
[19-DEC-2013 13:42:35:724] nsbasic_brc: exit: oln=0, dln=0, tot=10, rc=-1
[19-DEC-2013 13:42:35:724] nioqer: entry
[19-DEC-2013 13:42:35:724] nioqer: incoming err = 12151
[19-DEC-2013 13:42:35:724] nioqce: entry
[19-DEC-2013 13:42:35:724] nioqce: exit
[19-DEC-2013 13:42:35:724] nioqer: returning err = 3113
[19-DEC-2013 13:42:35:724] nioqer: exit
[19-DEC-2013 13:42:35:724] nioqrc: exit
[19-DEC-2013 13:42:35:724] nioqds: entry
[19-DEC-2013 13:42:35:724] nioqds: disconnecting...
Handshake never succeded. Client was trying to read from transport but never succeeded. I assume it is because of wrong or non Client address: "(ADDRESS=(PROTOCOL=tcp)(HOST=::1)(PORT=53749))"
[19-DEC-2013 13:41:43:803] nscon: doing connect handshake...
[19-DEC-2013 13:41:43:803] nscon: recving a packet
[19-DEC-2013 13:41:43:803] nsprecv: entry
[19-DEC-2013 13:41:43:803] nsprecv: reading from transport...
[19-DEC-2013 13:41:43:803] nttrd: entry
[19-DEC-2013 13:44:44:855] nttrd: exit
[19-DEC-2013 13:44:44:855] ntt2err: entry
[19-DEC-2013 13:44:44:855] ntt2err: Read unexpected EOF ERROR on 2648
[19-DEC-2013 13:44:44:855] ntt2err: exit
[19-DEC-2013 13:44:44:855] nsprecv: error exit
[19-DEC-2013 13:44:44:855] nserror: entry
[19-DEC-2013 13:44:44:855] nserror: nsres: id=0, op=68, ns=12535, ns2=12606; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-DEC-2013 13:44:44:855] nscon: error exit
[19-DEC-2013 13:44:44:855] nsdo: nsctxrnk=0
[19-DEC-2013 13:44:44:855] nsdo: error exit
[19-DEC-2013 13:44:44:855] nsinh_hoff: error recving request
[19-DEC-2013 13:44:44:855] nsclose: entry
[19-DEC-2013 13:44:44:855] nsvntx_dei: entry
[19-DEC-2013 13:44:44:855] nsvntx_dei: exit
[19-DEC-2013 13:44:44:855] nstimarmed: entry
[19-DEC-2013 13:44:44:855] nstimarmed: no timer allocated
[19-DEC-2013 13:44:44:855] nstimarmed: normal exit
[19-DEC-2013 13:44:44:855] nstoClearTimeout: entry
[19-DEC-2013 13:44:44:855] nstoClearTimeout: ATO disabled for ctx=0x000000001812ED30
[19-DEC-2013 13:44:44:855] nstoClearTimeout: STO disabled for ctx=0x000000001812ED30
[19-DEC-2013 13:44:44:855] nstoClearTimeout: RTO disabled for ctx=0x000000001812ED30
[19-DEC-2013 13:44:44:855] nstoClearTimeout: PITO disabled for ctx=0x000000001812ED30
[19-DEC-2013 13:44:44:855] nstoUpdateActive: entry
[19-DEC-2013 13:44:44:855] nstoUpdateActive: Active timeout is -1 (see nstotyp)
[19-DEC-2013 13:44:44:855] nstoDestroyAlarm: entry
[19-DEC-2013 13:44:44:855] nstoDestroyAlarm: exit (0)
[19-DEC-2013 13:44:44:855] nttctl: entry
[19-DEC-2013 13:44:44:855] ntt2err: entry
[19-DEC-2013 13:44:44:855] ntt2err: soc -1 error - operation=7, ntresnt[0]=530, ntresnt[1]=38, ntresnt[2]=0
[19-DEC-2013 13:44:44:855] ntt2err: exit
[19-DEC-2013 13:44:44:855] nttctl: entry
[19-DEC-2013 13:44:44:855] ntt2err: entry
[19-DEC-2013 13:44:44:855] ntt2err: soc -1 error - operation=7, ntresnt[0]=530, ntresnt[1]=38, ntresnt[2]=0
[19-DEC-2013 13:44:44:855] ntt2err: exit
[19-DEC-2013 13:44:44:855] nsfull_cls: entry
[19-DEC-2013 13:44:44:855] nsfull_cls: cid=0, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x40
[19-DEC-2013 13:44:44:855] nsfull_cls: nsctx: state=1, flg=0x4000, mvd=0
[19-DEC-2013 13:44:44:855] nsbfr: entry
After 180 sec Oracle net sends following message:
[19-DEC-2013 13:44:44:855] nsclose: normal exit
[19-DEC-2013 13:44:44:855] nserror: entry
[19-DEC-2013 13:44:44:855] nserror: nsres: id=0, op=73, ns=12535, ns2=12606; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[19-DEC-2013 13:44:44:855] nsinherit: error exit
[19-DEC-2013 13:44:44:855] nioqper: error from nsinherit: failed...
[19-DEC-2013 13:44:44:855] nioqper: ns main err code: 12535
[19-DEC-2013 13:44:44:855] nioqper: ns (2) err code: 12606
[19-DEC-2013 13:44:44:855] nioqper: nt main err code: 0
[19-DEC-2013 13:44:44:855] nioqper: nt (2) err code: 0
[19-DEC-2013 13:44:44:855] nioqper: nt OS err code: 0
[19-DEC-2013 13:44:44:855] niotns: No broken-connection function available.
[19-DEC-2013 13:44:44:855] niomapnserror: entry
[19-DEC-2013 13:44:44:855] niqme: entry
[19-DEC-2013 13:44:44:855] niqme: reporting NS-12535 error as ORA-12535
[19-DEC-2013 13:44:44:855] niqme: exit
[19-DEC-2013 13:44:44:855] niomapnserror: exit
[19-DEC-2013 13:44:44:855] niotns: Couldn't connect, returning 12170
[19-DEC-2013 13:44:44:855] nioqer: entry
[19-DEC-2013 13:44:44:855] nioqer: incoming err = 12170
[19-DEC-2013 13:44:44:855] nioqce: entry
[19-DEC-2013 13:44:44:855] nioqce: exit
[19-DEC-2013 13:44:44:855] nioqer: returning err = 3136
Firewall is off on Server. Network administrator says that there is no rules which could compromise communication/traffic on port 1521. I tried trcroute too and I get following conntent
1 27ms 22 ms 17ms 192.168.97.1
2 * * * request aborted
3 20 ms 18 ms 18 ms server.company.se (192.168.1.9)
Any suggestion, help or comments would be appriciated. I am running out of ideas.
Tnx a lot,
adi