Link to home
Start Free TrialLog in
Avatar of oagunbiade
oagunbiade

asked on

Tnsping works but sqlplus doesnt

Hi Guys, Im in the middle of doing a client install of oracle 9i on our network. The install went well without any problems but when i try to connect to the oracle server using sqlplus i get "request timed out" error. But if i try tnsping from the same machine it works fine. Ive checked to make sure there are no firewalls running.
Hope u can help please :(
 
Here is a copy of the trace file that was generated.

[05-DEC-2006 12:02:44:738]
--- TRACE CONFIGURATION INFORMATION FOLLOWS ---
[05-DEC-2006 12:02:44:738] New trace stream is C:\oracle\ora92\bin\cli_3404_5.trc
[05-DEC-2006 12:02:44:738] New trace level is 16
[05-DEC-2006 12:02:44:738] --- TRACE CONFIGURATION INFORMATION ENDS ---
[05-DEC-2006 12:02:44:738]
--- PARAMETER SOURCE INFORMATION FOLLOWS ---
[05-DEC-2006 12:02:44:738] Attempted load of system pfile source C:\oracle\ora92\network\admin\sqlnet.ora
[05-DEC-2006 12:02:44:738] Parameter source loaded successfully
[05-DEC-2006 12:02:44:738]
[05-DEC-2006 12:02:44:738] Attempted load of local pfile source C:\oracle\ora92\bin\sqlnet.ora
[05-DEC-2006 12:02:44:738] Parameter source was not loaded
[05-DEC-2006 12:02:44:738]
[05-DEC-2006 12:02:44:738]  -> PARAMETER TABLE LOAD RESULTS FOLLOW <-
[05-DEC-2006 12:02:44:738] Successful parameter table load
[05-DEC-2006 12:02:44:738]  -> PARAMETER TABLE HAS THE FOLLOWING CONTENTS <-
[05-DEC-2006 12:02:44:738]   TRACE_LEVEL_CLIENT = 16
[05-DEC-2006 12:02:44:738]   NAMES.DIRECTORY_PATH = (TNSNAMES, ONAMES, HOSTNAME)
[05-DEC-2006 12:02:44:738]   TRACE_UNIQUE_CLIENT = YES
[05-DEC-2006 12:02:44:738]   NAMES.DEFAULT_DOMAIN = world
[05-DEC-2006 12:02:44:738] --- PARAMETER SOURCE INFORMATION ENDS ---
[05-DEC-2006 12:02:44:738]
--- LOG CONFIGURATION INFORMATION FOLLOWS ---
[05-DEC-2006 12:02:44:738] Log stream will be "C:\oracle\ora92\bin\sqlnet.log"
[05-DEC-2006 12:02:44:738] Log stream validation not requested
[05-DEC-2006 12:02:44:738] --- LOG CONFIGURATION INFORMATION ENDS ---

[05-DEC-2006 12:02:44:738] nigini: entry
[05-DEC-2006 12:02:44:738] nigini: Count in NI global area now: 1
[05-DEC-2006 12:02:44:738] nigini: Count in NI global area now: 1
[05-DEC-2006 12:02:44:738] nrigbi: entry
[05-DEC-2006 12:02:44:738] nrigbni: entry
[05-DEC-2006 12:02:44:738] nrigbni: Unable to get data from navigation file tnsnav.ora
[05-DEC-2006 12:02:44:738] nrigbni: exit
[05-DEC-2006 12:02:44:738] nrigbi: exit
[05-DEC-2006 12:02:44:738] nigini: exit
[05-DEC-2006 12:02:44:738] niqname: Hst is already an NVstring.
[05-DEC-2006 12:02:44:738] niqname: Inserting CID.
[05-DEC-2006 12:02:44:738] niotns: entry
[05-DEC-2006 12:02:44:738] niotns: niotns: setting up interrupt handler...
[05-DEC-2006 12:02:44:738] niotns: Not trying to enable dead connection detection.
[05-DEC-2006 12:02:44:738] niotns: Calling address: (DESCRIPTION=(ADDRESS=(PROTOCOL=TCP)(HOST=10.1.1.164)(PORT=1521))(CONNECT_DATA=(SERVICE_NAME=Gprs)(CID=(PROGRAM=C:\oracle\ora92\bin\sqlplusw.exe)(HOST=GX270-PACKAGE1)(USER=fjsupport))))
[05-DEC-2006 12:02:44:738] nscall: entry
[05-DEC-2006 12:02:44:738] nsmal: entry
[05-DEC-2006 12:02:44:738] nsmal: 164 bytes at 0xd92658
[05-DEC-2006 12:02:44:738] nsmal: normal exit
[05-DEC-2006 12:02:44:738] nscall: connecting...
[05-DEC-2006 12:02:44:738] nladini: entry
[05-DEC-2006 12:02:44:738] nladini: exit
[05-DEC-2006 12:02:44:738] nladget: entry
[05-DEC-2006 12:02:44:738] nladget: exit
[05-DEC-2006 12:02:44:738] nsc2addr: entry
[05-DEC-2006 12:02:44:738] nttbnd2addr: entry
[05-DEC-2006 12:02:44:738] nttgetport: entry
[05-DEC-2006 12:02:44:738] nttgetport: port resolved to 1521
[05-DEC-2006 12:02:44:738] nttgetport: exit
[05-DEC-2006 12:02:44:738] nttbnd2addr: using host IP address: 10.1.1.164
[05-DEC-2006 12:02:44:738] nttbnd2addr: exit
[05-DEC-2006 12:02:44:738] nsc2addr: normal exit
[05-DEC-2006 12:02:44:738] nsopen: entry
[05-DEC-2006 12:02:44:738] nsmal: entry
[05-DEC-2006 12:02:44:738] nsmal: 492 bytes at 0xd93460
[05-DEC-2006 12:02:44:738] nsmal: normal exit
[05-DEC-2006 12:02:44:738] nsopenmplx: entry
[05-DEC-2006 12:02:44:738] nsmal: entry
[05-DEC-2006 12:02:44:753] nsmal: 2348 bytes at 0xdabff8
[05-DEC-2006 12:02:44:753] nsmal: normal exit
[05-DEC-2006 12:02:44:753] nsopenmplx: normal exit
[05-DEC-2006 12:02:44:753] nsopen: opening transport...
[05-DEC-2006 12:02:44:753] nttcon: entry
[05-DEC-2006 12:02:44:753] nttcon: toc = 1
[05-DEC-2006 12:02:44:753] nttcnp: entry
[05-DEC-2006 12:02:44:753] ntvlin: entry
[05-DEC-2006 12:02:44:753] ntvllt: entry
[05-DEC-2006 12:02:44:753] ntvllt: tcp.validnode_checking not turned on
[05-DEC-2006 12:02:44:753] ntvllt: exit
[05-DEC-2006 12:02:44:753] ntvlin: exit
[05-DEC-2006 12:02:44:753] nttcnp: Validnode Table IN use; err 0x0
[05-DEC-2006 12:02:44:753] nttcnp: creating a socket.
[05-DEC-2006 12:02:44:753] nttcnp: exit
[05-DEC-2006 12:02:44:753] nttcni: entry
[05-DEC-2006 12:02:44:753] nttcni: trying to connect to socket 232.
[05-DEC-2006 12:02:44:753] nttcni: connected on ipaddr 10.141.9.102
[05-DEC-2006 12:02:44:753] nttcni: exit
[05-DEC-2006 12:02:44:753] nttcon: NT layer TCP/IP connection has been established.
[05-DEC-2006 12:02:44:753] nttcon: set TCP_NODELAY on 232
[05-DEC-2006 12:02:44:753] nttcon: exit
[05-DEC-2006 12:02:44:753] nsopen: transport is open
[05-DEC-2006 12:02:44:753] nsnainit: entry
[05-DEC-2006 12:02:44:753] nsnainit: call
[05-DEC-2006 12:02:44:753] nsnadct: entry
[05-DEC-2006 12:02:44:753] nsnadct: normal exit
[05-DEC-2006 12:02:44:753] nsnasvnainfo: entry
[05-DEC-2006 12:02:44:753] nsnasvnainfo: normal exit
[05-DEC-2006 12:02:44:753] nainit: entry
[05-DEC-2006 12:02:44:753] nagblini: entry
[05-DEC-2006 12:02:44:753] nau_gin: entry
[05-DEC-2006 12:02:44:753] nau_gparams: entry
[05-DEC-2006 12:02:44:753] nam_gbp: Reading parameter "sqlnet.authentication_required" from parameter file
[05-DEC-2006 12:02:44:753] nam_gbp: Parameter not found
[05-DEC-2006 12:02:44:753] nau_gparams: Using default value "FALSE"
[05-DEC-2006 12:02:44:753] nau_gslf: entry
[05-DEC-2006 12:02:44:753] nam_gic: entry
[05-DEC-2006 12:02:44:753] nam_gic: Counting # of items in "sqlnet.authentication_services" parameter
[05-DEC-2006 12:02:44:753] nam_gic: Parameter not found
[05-DEC-2006 12:02:44:753] nam_gic: Found 0 items
[05-DEC-2006 12:02:44:753] nam_gic: exit
[05-DEC-2006 12:02:44:753] nau_gslf: Using default value "all available adapters"
[05-DEC-2006 12:02:44:753] nauss_set_state: entry
[05-DEC-2006 12:02:44:753] nauss_set_state: exit
[05-DEC-2006 12:02:44:753] nau_gslf: exit
[05-DEC-2006 12:02:44:753] nau_gparams: exit
[05-DEC-2006 12:02:44:753] nau_gin: exit
[05-DEC-2006 12:02:44:753] nagblini: exit
[05-DEC-2006 12:02:44:753] na_saveprot: entry
[05-DEC-2006 12:02:44:753] na_saveprot: exit
[05-DEC-2006 12:02:44:753] nacomin: entry
[05-DEC-2006 12:02:44:753] nas_init: entry
[05-DEC-2006 12:02:44:753] nas_init: exit
[05-DEC-2006 12:02:44:753] nau_ini: entry
[05-DEC-2006 12:02:44:753] naugcp_get_connect_parameters: entry
[05-DEC-2006 12:02:44:753] nauss_set_state: entry
[05-DEC-2006 12:02:44:753] nauss_set_state: exit
[05-DEC-2006 12:02:44:753] naugcp_get_connect_parameters: exit
[05-DEC-2006 12:02:44:753] nau_gettab: entry
[05-DEC-2006 12:02:44:753] nau_tadv: entry
[05-DEC-2006 12:02:44:753] nau_fad: entry
[05-DEC-2006 12:02:44:753] nau_fad: The following authentication adapter dll does not exist: orank59.dll
[05-DEC-2006 12:02:44:753] nau_fad: exit
[05-DEC-2006 12:02:44:753] nau_fad: entry
[05-DEC-2006 12:02:44:753] nau_fad: The following authentication adapter dll does not exist: orangss9.dll
[05-DEC-2006 12:02:44:753] nau_fad: exit
[05-DEC-2006 12:02:44:753] nau_fad: entry
[05-DEC-2006 12:02:44:753] nau_fad: exit
[05-DEC-2006 12:02:44:753] nau_fad: entry
[05-DEC-2006 12:02:44:753] nau_fad: The following authentication adapter dll does not exist: oranrad9.dll
[05-DEC-2006 12:02:44:753] nau_fad: exit
[05-DEC-2006 12:02:44:753] nau_tadv: Found at least one invalid entry
[05-DEC-2006 12:02:44:753] nau_tadv: exit
[05-DEC-2006 12:02:44:753] nau_gettab: using authentication adapter table "dynamic table"
[05-DEC-2006 12:02:44:753] nau_gettab: dynamic table contains the following services:
[05-DEC-2006 12:02:44:753] nau_gettab:       NTS
[05-DEC-2006 12:02:44:753] nau_gettab: exit
[05-DEC-2006 12:02:44:753] nau_sini: entry
[05-DEC-2006 12:02:44:753] nau_sini: exit
[05-DEC-2006 12:02:44:753] nau_ini: connection type: "standard"
[05-DEC-2006 12:02:44:753] nau_ini: exit
[05-DEC-2006 12:02:44:753] naeeinit: entry
[05-DEC-2006 12:02:44:753] nam_gbp: Reading parameter "SQLNET.FIPS_140" from parameter file
[05-DEC-2006 12:02:44:753] nam_gbp: Parameter not found
[05-DEC-2006 12:02:44:753] nam_gnsp: Reading parameter "SQLNET.ENCRYPTION_CLIENT" from parameter file
[05-DEC-2006 12:02:44:753] nam_gnsp: Parameter not found
[05-DEC-2006 12:02:44:753] naequad: Using default value "ACCEPTED"
[05-DEC-2006 12:02:44:753] nam_gic: entry
[05-DEC-2006 12:02:44:753] nam_gic: Counting # of items in "SQLNET.ENCRYPTION_TYPES_CLIENT" parameter
[05-DEC-2006 12:02:44:753] nam_gic: Parameter not found
[05-DEC-2006 12:02:44:753] nam_gic: exit
[05-DEC-2006 12:02:44:753] naesno: Using default value "all available algorithms"
[05-DEC-2006 12:02:44:753] naeshow: entry
[05-DEC-2006 12:02:44:753] naeshow: These are the encryption algorithms that the client will accept:
[05-DEC-2006 12:02:44:753] naeshow: Choice 0: no algorithm; encryption inactive
[05-DEC-2006 12:02:44:753] naeshow: exit
[05-DEC-2006 12:02:44:753] naeeinit: exit
[05-DEC-2006 12:02:44:753] naecinit: entry
[05-DEC-2006 12:02:44:753] nam_gnsp: Reading parameter "SQLNET.CRYPTO_CHECKSUM_CLIENT" from parameter file
[05-DEC-2006 12:02:44:753] nam_gnsp: Parameter not found
[05-DEC-2006 12:02:44:753] naequad: Using default value "ACCEPTED"
[05-DEC-2006 12:02:44:753] nam_gic: entry
[05-DEC-2006 12:02:44:753] nam_gic: Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_CLIENT" parameter
[05-DEC-2006 12:02:44:753] nam_gic: Parameter not found
[05-DEC-2006 12:02:44:753] nam_gic: exit
[05-DEC-2006 12:02:44:753] naesno: Using default value "all available algorithms"
[05-DEC-2006 12:02:44:753] naeshow: entry
[05-DEC-2006 12:02:44:753] naeshow: These are the checksumming algorithms that the client will accept:
[05-DEC-2006 12:02:44:753] naeshow: Choice 0: no algorithm; checksumming inactive
[05-DEC-2006 12:02:44:753] naeshow: exit
[05-DEC-2006 12:02:44:753] naecinit: exit
[05-DEC-2006 12:02:44:753] nainit: exit
[05-DEC-2006 12:02:44:753] nagetctxinfo: entry
[05-DEC-2006 12:02:44:753] nagetctxinfo: exit
[05-DEC-2006 12:02:44:753] nsnainit: NS Connection version: 312
[05-DEC-2006 12:02:44:753] nsnainit: inf->nsinfflg[0]: 0x1 inf->nsinfflg[1]: 0x1
[05-DEC-2006 12:02:44:753] nsnainit: "or" info flags: 0x1      Translations follow:
      native service(s) is (are) wanted

[05-DEC-2006 12:02:44:753] nsnainit: "or" info flags: 0x1      Translations follow:
      native service(s) is (are) wanted
"and" info flags: 0x1      Translations follow:
      native service(s) is (are) wanted

[05-DEC-2006 12:02:44:753] nsnainit: normal exit
[05-DEC-2006 12:02:44:753] nsoptions: entry
[05-DEC-2006 12:02:44:753] nsoptions: lcl[0]=0x0, lcl[1]=0x100000, gbl[0]=0x0, gbl[1]=0x0, cha=0x0
[05-DEC-2006 12:02:44:753] nsoptions: lcl[0]=0xffefff, lcl[1]=0x100000, gbl[0]=0xfe3f, gbl[1]=0x0
[05-DEC-2006 12:02:44:753] nsoptions: normal exit
[05-DEC-2006 12:02:44:753] nsopen: global context check-in (to slot 0) complete
[05-DEC-2006 12:02:44:753] nsopen: lcl[0]=0xffefff, lcl[1]=0x100000, gbl[0]=0xfe3f, gbl[1]=0x0, tdu=32767, sdu=2048
[05-DEC-2006 12:02:44:753] nsdo: entry
[05-DEC-2006 12:02:44:753] nsdo: cid=0, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2
[05-DEC-2006 12:02:44:753] nsdo: rank=64, nsctxrnk=0
[05-DEC-2006 12:02:44:753] nsdo: nsctx: state=7, flg=0x4201, mvd=0
[05-DEC-2006 12:02:44:753] nsbal: entry
[05-DEC-2006 12:02:44:753] nsbgetfl: entry
[05-DEC-2006 12:02:44:753] nsbgetfl: normal exit
[05-DEC-2006 12:02:44:753] nsmal: entry
[05-DEC-2006 12:02:44:753] nsmal: 48 bytes at 0xd85a48
[05-DEC-2006 12:02:44:753] nsmal: normal exit
[05-DEC-2006 12:02:44:753] nsbal: normal exit
[05-DEC-2006 12:02:44:753] nsbal: entry
[05-DEC-2006 12:02:44:753] nsbgetfl: entry
[05-DEC-2006 12:02:44:753] nsbgetfl: normal exit
[05-DEC-2006 12:02:44:753] nsmal: entry
[05-DEC-2006 12:02:44:753] nsmal: 48 bytes at 0xd93880
[05-DEC-2006 12:02:44:753] nsmal: normal exit
[05-DEC-2006 12:02:44:753] nsbal: normal exit
[05-DEC-2006 12:02:44:753] nsepcIniCFI: entry
[05-DEC-2006 12:02:44:753] nlidg8: entry
[05-DEC-2006 12:02:44:753] nlidg8: exit
[05-DEC-2006 12:02:44:753] nsepcIniCFI: normal exit
[05-DEC-2006 12:02:44:753] nsdo: nsctxrnk=0
[05-DEC-2006 12:02:44:753] nsdo: normal exit
[05-DEC-2006 12:02:44:753] nsopen: normal exit
[05-DEC-2006 12:02:44:753] nsdo: entry
[05-DEC-2006 12:02:44:753] nsdo: cid=0, opcode=67, *bl=185, *what=8, uflgs=0x0, cflgs=0x3
[05-DEC-2006 12:02:44:753] nsdo: rank=64, nsctxrnk=0
[05-DEC-2006 12:02:44:753] nsdo: nsctx: state=14, flg=0x4205, mvd=0
[05-DEC-2006 12:02:44:753] nsdo: gtn=0, gtc=0, ptn=10, ptc=2047
[05-DEC-2006 12:02:44:753] nscon: entry
[05-DEC-2006 12:02:44:753] nscon: doing connect handshake...
[05-DEC-2006 12:02:44:753] nscon: sending NSPTCN packet
[05-DEC-2006 12:02:44:753] nspsend: entry
[05-DEC-2006 12:02:44:753] nspsend: plen=243, type=1
[05-DEC-2006 12:02:44:753] nttwr: entry
[05-DEC-2006 12:02:44:753] nttwr: socket 232 had bytes written=243
[05-DEC-2006 12:02:44:753] nttwr: exit
[05-DEC-2006 12:02:44:753] nspsend: 243 bytes to transport
[05-DEC-2006 12:02:44:753] nspsend: packet dump
[05-DEC-2006 12:02:44:753] nspsend: 00 F3 00 00 01 00 00 00  |........|
[05-DEC-2006 12:02:44:753] nspsend: 01 38 01 2C 00 00 08 00  |.8.,....|
[05-DEC-2006 12:02:44:753] nspsend: 7F FF 86 0E 00 00 01 00  |........|
[05-DEC-2006 12:02:44:753] nspsend: 00 B9 00 3A 00 00 02 00  |...:....|
[05-DEC-2006 12:02:44:753] nspsend: 01 01 00 00 00 00 00 00  |........|
[05-DEC-2006 12:02:44:753] nspsend: 00 00 00 00 08 50 00 00  |.....P..|
[05-DEC-2006 12:02:44:753] nspsend: 13 82 00 00 00 00 00 00  |........|
[05-DEC-2006 12:02:44:753] nspsend: 00 00 28 44 45 53 43 52  |..(DESCR|
[05-DEC-2006 12:02:44:753] nspsend: 49 50 54 49 4F 4E 3D 28  |IPTION=(|
[05-DEC-2006 12:02:44:753] nspsend: 41 44 44 52 45 53 53 3D  |ADDRESS=|
[05-DEC-2006 12:02:44:753] nspsend: 28 50 52 4F 54 4F 43 4F  |(PROTOCO|
[05-DEC-2006 12:02:44:753] nspsend: 4C 3D 54 43 50 29 28 48  |L=TCP)(H|
[05-DEC-2006 12:02:44:753] nspsend: 4F 53 54 3D 31 30 2E 31  |OST=10.1|
[05-DEC-2006 12:02:44:753] nspsend: 2E 31 2E 31 36 34 29 28  |.1.164)(|
[05-DEC-2006 12:02:44:753] nspsend: 50 4F 52 54 3D 31 35 32  |PORT=152|
[05-DEC-2006 12:02:44:753] nspsend: 31 29 29 28 43 4F 4E 4E  |1))(CONN|
[05-DEC-2006 12:02:44:753] nspsend: 45 43 54 5F 44 41 54 41  |ECT_DATA|
[05-DEC-2006 12:02:44:753] nspsend: 3D 28 53 45 52 56 49 43  |=(SERVIC|
[05-DEC-2006 12:02:44:753] nspsend: 45 5F 4E 41 4D 45 3D 47  |E_NAME=G|
[05-DEC-2006 12:02:44:753] nspsend: 70 72 73 29 28 43 49 44  |prs)(CID|
[05-DEC-2006 12:02:44:753] nspsend: 3D 28 50 52 4F 47 52 41  |=(PROGRA|
[05-DEC-2006 12:02:44:753] nspsend: 4D 3D 43 3A 5C 6F 72 61  |M=C:\ora|
[05-DEC-2006 12:02:44:753] nspsend: 63 6C 65 5C 6F 72 61 39  |cle\ora9|
[05-DEC-2006 12:02:44:753] nspsend: 32 5C 62 69 6E 5C 73 71  |2\bin\sq|
[05-DEC-2006 12:02:44:753] nspsend: 6C 70 6C 75 73 77 2E 65  |lplusw.e|
[05-DEC-2006 12:02:44:753] nspsend: 78 65 29 28 48 4F 53 54  |xe)(HOST|
[05-DEC-2006 12:02:44:753] nspsend: 3D 47 58 32 37 30 2D 50  |=GX270-P|
[05-DEC-2006 12:02:44:753] nspsend: 41 43 4B 41 47 45 31 29  |ACKAGE1)|
[05-DEC-2006 12:02:44:753] nspsend: 28 55 53 45 52 3D 66 6A  |(USER=fj|
[05-DEC-2006 12:02:44:753] nspsend: 73 75 70 70 6F 72 74 29  |support)|
[05-DEC-2006 12:02:44:753] nspsend: 29 29 29                 |)))     |
[05-DEC-2006 12:02:44:753] nspsend: normal exit
[05-DEC-2006 12:02:44:753] nscon: exit (0)
[05-DEC-2006 12:02:44:753] nsdo: nsctxrnk=0
[05-DEC-2006 12:02:44:753] nsdo: normal exit
[05-DEC-2006 12:02:44:753] nsdo: entry
[05-DEC-2006 12:02:44:753] nsdo: cid=0, opcode=68, *bl=512, *what=9, uflgs=0x0, cflgs=0x3
[05-DEC-2006 12:02:44:753] nsdo: rank=64, nsctxrnk=0
[05-DEC-2006 12:02:44:753] nsdo: nsctx: state=2, flg=0x4205, mvd=0
[05-DEC-2006 12:02:44:753] nsdo: gtn=0, gtc=0, ptn=10, ptc=2047
[05-DEC-2006 12:02:44:753] nscon: entry
[05-DEC-2006 12:02:44:753] nscon: recving a packet
[05-DEC-2006 12:02:44:753] nsprecv: entry
[05-DEC-2006 12:02:44:753] nsbal: entry
[05-DEC-2006 12:02:44:753] nsbgetfl: entry
[05-DEC-2006 12:02:44:753] nsbgetfl: normal exit
[05-DEC-2006 12:02:44:753] nsmal: entry
[05-DEC-2006 12:02:44:753] nsmal: 48 bytes at 0xd938b8
[05-DEC-2006 12:02:44:753] nsmal: normal exit
[05-DEC-2006 12:02:44:753] nsbal: normal exit
[05-DEC-2006 12:02:44:753] nsprecv: reading from transport...
[05-DEC-2006 12:02:44:753] nttrd: entry
[05-DEC-2006 12:02:44:769] nttrd: socket 232 had bytes read=62
[05-DEC-2006 12:02:44:769] nttrd: exit
[05-DEC-2006 12:02:44:769] nsprecv: 62 bytes from transport
[05-DEC-2006 12:02:44:769] nsprecv: tlen=62, plen=62, type=5
[05-DEC-2006 12:02:44:769] nsprecv: packet dump
[05-DEC-2006 12:02:44:769] nsprecv: 00 3E 00 00 05 00 00 00  |.>......|
[05-DEC-2006 12:02:44:769] nsprecv: 00 34 28 41 44 44 52 45  |.4(ADDRE|
[05-DEC-2006 12:02:44:769] nsprecv: 53 53 3D 28 50 52 4F 54  |SS=(PROT|
[05-DEC-2006 12:02:44:769] nsprecv: 4F 43 4F 4C 3D 74 63 70  |OCOL=tcp|
[05-DEC-2006 12:02:44:769] nsprecv: 29 28 48 4F 53 54 3D 31  |)(HOST=1|
[05-DEC-2006 12:02:44:769] nsprecv: 30 2E 31 2E 31 2E 31 36  |0.1.1.16|
[05-DEC-2006 12:02:44:769] nsprecv: 34 29 28 50 4F 52 54 3D  |4)(PORT=|
[05-DEC-2006 12:02:44:769] nsprecv: 31 35 34 38 29 29        |1548))  |
[05-DEC-2006 12:02:44:769] nsprecv: normal exit
[05-DEC-2006 12:02:44:769] nscon: got NSPTRD packet
[05-DEC-2006 12:02:44:769] nscon: got 52 bytes connect data
[05-DEC-2006 12:02:44:769] nscon: exit (0)
[05-DEC-2006 12:02:44:769] nsdo: nsctxrnk=0
[05-DEC-2006 12:02:44:769] nsdo: normal exit
[05-DEC-2006 12:02:44:769] nscall: redirected
[05-DEC-2006 12:02:44:769] nsclose: entry
[05-DEC-2006 12:02:44:769] nstimarmed: entry
[05-DEC-2006 12:02:44:769] nstimarmed: no timer allocated
[05-DEC-2006 12:02:44:769] nstimarmed: normal exit
[05-DEC-2006 12:02:44:769] nsdo: entry
[05-DEC-2006 12:02:44:769] nsdo: cid=0, opcode=98, *bl=0, *what=0, uflgs=0x440, cflgs=0x2
[05-DEC-2006 12:02:44:769] nsdo: rank=64, nsctxrnk=0
[05-DEC-2006 12:02:44:769] nsdo: nsctx: state=3, flg=0x4201, mvd=0
[05-DEC-2006 12:02:44:769] nsbfr: entry
[05-DEC-2006 12:02:44:769] nsbaddfl: entry
[05-DEC-2006 12:02:44:769] nsbaddfl: normal exit
[05-DEC-2006 12:02:44:769] nsbfr: normal exit
[05-DEC-2006 12:02:44:769] nsbfr: entry
[05-DEC-2006 12:02:44:769] nsbaddfl: entry
[05-DEC-2006 12:02:44:769] nsbaddfl: normal exit
[05-DEC-2006 12:02:44:769] nsbfr: normal exit
[05-DEC-2006 12:02:44:769] nsdo: nsctxrnk=0
[05-DEC-2006 12:02:44:769] nsdo: normal exit
[05-DEC-2006 12:02:44:769] nsclose: closing transport
[05-DEC-2006 12:02:44:769] nttdisc: entry
[05-DEC-2006 12:02:44:769] nttdisc: Closed socket 232
[05-DEC-2006 12:02:44:769] nttdisc: exit
[05-DEC-2006 12:02:44:769] nsclose: global context check-out (from slot 0) complete
[05-DEC-2006 12:02:44:769] nsnadisc: entry
[05-DEC-2006 12:02:44:769] nadisc: entry
[05-DEC-2006 12:02:44:769] nacomtm: entry
[05-DEC-2006 12:02:44:769] nacompd: entry
[05-DEC-2006 12:02:44:769] nacompd: exit
[05-DEC-2006 12:02:44:769] nacompd: entry
[05-DEC-2006 12:02:44:769] nacompd: exit
[05-DEC-2006 12:02:44:769] nacomtm: exit
[05-DEC-2006 12:02:44:769] nas_dis: entry
[05-DEC-2006 12:02:44:769] nas_dis: exit
[05-DEC-2006 12:02:44:769] nau_dis: entry
[05-DEC-2006 12:02:44:769] nau_fad: exit
[05-DEC-2006 12:02:44:769] nau_dis: exit
[05-DEC-2006 12:02:44:769] naeetrm: entry
[05-DEC-2006 12:02:44:769] naeetrm: exit
[05-DEC-2006 12:02:44:769] naectrm: entry
[05-DEC-2006 12:02:44:769] naectrm: exit
[05-DEC-2006 12:02:44:769] nagbltrm: entry
[05-DEC-2006 12:02:44:769] nau_gtm: entry
[05-DEC-2006 12:02:44:769] nau_gtm: exit
[05-DEC-2006 12:02:44:769] nagbltrm: exit
[05-DEC-2006 12:02:44:769] nadisc: exit
[05-DEC-2006 12:02:44:769] nsnadisc: normal exit
[05-DEC-2006 12:02:44:769] nsbfr: entry
[05-DEC-2006 12:02:44:769] nsbaddfl: entry
[05-DEC-2006 12:02:44:769] nsbaddfl: normal exit
[05-DEC-2006 12:02:44:769] nsbfr: normal exit
[05-DEC-2006 12:02:44:769] nsbfr: entry
[05-DEC-2006 12:02:44:769] nsbfr: normal exit
[05-DEC-2006 12:02:44:769] nsmfr: entry
[05-DEC-2006 12:02:44:769] nsmfr: 2348 bytes at 0xdabff8
[05-DEC-2006 12:02:44:769] nsmfr: normal exit
[05-DEC-2006 12:02:44:769] nsmfr: entry
[05-DEC-2006 12:02:44:769] nsmfr: 492 bytes at 0xd93460
[05-DEC-2006 12:02:44:769] nsmfr: normal exit
[05-DEC-2006 12:02:44:769] nsclose: normal exit
[05-DEC-2006 12:02:44:769] nscall: connecting...
[05-DEC-2006 12:02:44:769] nsc2addr: entry
[05-DEC-2006 12:02:44:769] nttbnd2addr: entry
[05-DEC-2006 12:02:44:769] nttgetport: entry
[05-DEC-2006 12:02:44:769] nttgetport: port resolved to 1548
[05-DEC-2006 12:02:44:769] nttgetport: exit
[05-DEC-2006 12:02:44:769] nttbnd2addr: using host IP address: 10.1.1.164
[05-DEC-2006 12:02:44:769] nttbnd2addr: exit
[05-DEC-2006 12:02:44:769] nsc2addr: normal exit
[05-DEC-2006 12:02:44:769] nsopen: entry
[05-DEC-2006 12:02:44:769] nsmal: entry
[05-DEC-2006 12:02:44:769] nsmal: 492 bytes at 0xd93460
[05-DEC-2006 12:02:44:769] nsmal: normal exit
[05-DEC-2006 12:02:44:769] nsopenmplx: entry
[05-DEC-2006 12:02:44:769] nsmal: entry
[05-DEC-2006 12:02:44:769] nsmal: 2348 bytes at 0xdabfd0
[05-DEC-2006 12:02:44:769] nsmal: normal exit
[05-DEC-2006 12:02:44:769] nsopenmplx: normal exit
[05-DEC-2006 12:02:44:769] nsopen: opening transport...
[05-DEC-2006 12:02:44:769] nttcon: entry
[05-DEC-2006 12:02:44:769] nttcon: toc = 1
[05-DEC-2006 12:02:44:769] nttcnp: entry
[05-DEC-2006 12:02:44:769] ntvlin: entry
[05-DEC-2006 12:02:44:769] ntvlin: exit
[05-DEC-2006 12:02:44:769] nttcnp: Validnode Table IN use; err 0x0
[05-DEC-2006 12:02:44:769] nttcnp: creating a socket.
[05-DEC-2006 12:02:44:769] nttcnp: exit
[05-DEC-2006 12:02:44:769] nttcni: entry
[05-DEC-2006 12:02:44:769] nttcni: trying to connect to socket 240.
[05-DEC-2006 12:03:05:735] ntt2err: entry
[05-DEC-2006 12:03:05:735] ntt2err: soc 240 error - operation=1, ntresnt[0]=505, ntresnt[1]=60, ntresnt[2]=0
[05-DEC-2006 12:03:05:735] ntt2err: exit
[05-DEC-2006 12:03:05:735] nttcni: exit
[05-DEC-2006 12:03:05:735] nttcon: exit
[05-DEC-2006 12:03:05:735] nserror: entry
[05-DEC-2006 12:03:05:735] nserror: nsres: id=0, op=65, ns=12535, ns2=12560; nt[0]=505, nt[1]=60, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
[05-DEC-2006 12:03:05:735] nsopen: unable to open transport
[05-DEC-2006 12:03:05:735] nsbfr: entry
[05-DEC-2006 12:03:05:735] nsbfr: normal exit
[05-DEC-2006 12:03:05:735] nsbfr: entry
[05-DEC-2006 12:03:05:735] nsbfr: normal exit
[05-DEC-2006 12:03:05:735] nsmfr: entry
[05-DEC-2006 12:03:05:735] nsmfr: 2348 bytes at 0xdabfd0
[05-DEC-2006 12:03:05:735] nsmfr: normal exit
[05-DEC-2006 12:03:05:735] nsmfr: entry
[05-DEC-2006 12:03:05:735] nsmfr: 492 bytes at 0xd93460
[05-DEC-2006 12:03:05:735] nsmfr: normal exit
[05-DEC-2006 12:03:05:735] nsopen: error exit
[05-DEC-2006 12:03:05:735] nsmfr: entry
[05-DEC-2006 12:03:05:735] nsmfr: 185 bytes at 0xd9a6a8
[05-DEC-2006 12:03:05:735] nsmfr: normal exit
[05-DEC-2006 12:03:05:735] nsmfr: entry
[05-DEC-2006 12:03:05:735] nsmfr: 164 bytes at 0xd92658
[05-DEC-2006 12:03:05:735] nsmfr: normal exit
[05-DEC-2006 12:03:05:735] nladtrm: entry
[05-DEC-2006 12:03:05:735] nladtrm: exit
[05-DEC-2006 12:03:05:735] nscall: error exit
[05-DEC-2006 12:03:05:735] nioqper:  error from nscall
[05-DEC-2006 12:03:05:735] nioqper:    nr err code: 0
[05-DEC-2006 12:03:05:735] nioqper:    ns main err code: 12535
[05-DEC-2006 12:03:05:735] nioqper:    ns (2)  err code: 12560
[05-DEC-2006 12:03:05:735] nioqper:    nt main err code: 505
[05-DEC-2006 12:03:05:735] nioqper:    nt (2)  err code: 60
[05-DEC-2006 12:03:05:735] nioqper:    nt OS   err code: 0
[05-DEC-2006 12:03:05:735] niomapnserror: entry
[05-DEC-2006 12:03:05:735] niqme: entry
[05-DEC-2006 12:03:05:735] niqme: reporting NS-12535 error as ORA-12535
[05-DEC-2006 12:03:05:735] niqme: exit
[05-DEC-2006 12:03:05:735] niomapnserror: returning error 12535
[05-DEC-2006 12:03:05:735] niomapnserror: exit
[05-DEC-2006 12:03:05:735] niotns: Couldn't connect, returning 12535
[05-DEC-2006 12:03:05:735] niotns: exit
[05-DEC-2006 12:03:05:735] nsbfrfl: entry
[05-DEC-2006 12:03:05:735] nsbrfr: entry
[05-DEC-2006 12:03:05:735] nsbrfr: nsbfs at 0xd938b8, data at 0xdadf88.
[05-DEC-2006 12:03:05:735] nsbrfr: normal exit
[05-DEC-2006 12:03:05:735] nsbrfr: entry
[05-DEC-2006 12:03:05:735] nsbrfr: nsbfs at 0xd93880, data at 0xdae7a8.
[05-DEC-2006 12:03:05:735] nsbrfr: normal exit
[05-DEC-2006 12:03:05:735] nsbrfr: entry
[05-DEC-2006 12:03:05:735] nsbrfr: nsbfs at 0xd85a48, data at 0xdaefc8.
[05-DEC-2006 12:03:05:735] nsbrfr: normal exit
[05-DEC-2006 12:03:05:735] nsbfrfl: normal exit
[05-DEC-2006 12:03:05:735] nigtrm: Count in the NI global area is now 0
[05-DEC-2006 12:03:05:735] nrigbd: entry
[05-DEC-2006 12:03:05:735] nrigbd: exit
[05-DEC-2006 12:03:05:735] nigtrm: Count in the NL global area is now 0


Thanks

Avatar of slightwv (䄆 Netminder)
slightwv (䄆 Netminder)

I know you've already checked for firewalls but from my experience, 99.99% of the time this error is caused by a firewall blocking the connection.

It looks like you are already hardcoding the IP address in the config files instead of server names so there shouldn't be a NIC problem.

I doubt you'll be able to install this to help troubleshoot but NMap should help you toubleshoot this ( http://insecure.org/nmap/ ).  You need to make sure the random high ports are open between the client and the server.  In your logs, SQL*Net attempted to hand the connection off the port 1548.  It wasn't long that the problems started.
Our network staff also suggested another tool called netcat ( http://netcat.sourceforge.net/ ).  You should be able to tell this tool to try to talk on a specific port.  This should help you test connectivity by telling the software to talk on port 1548.
Avatar of oagunbiade

ASKER

Thanks for the reply m8. Unfortunately the way the system is setup i wont be able to do installs. I spoke to a m8 that encountered this same problem and he said that the IT department sorted it by disabling Microsoft Firewall Client. Ive checked the client machine and cant seem to find Microsoft Firewall Client.
What is the client OS?  If it is XP, the firewall comes enabled by default.  If you aren't running 'classic' mode, right click on 'My Network Places' and select 'Properties'.  You should see 'Network Tasks' on the left side of the panel and 'Change Windows Firewall settings' should be an option.
Thanks for the reply m8. Unfortunately the way the system is setup i wont be able to do installs. I spoke to a m8 that encountered this same problem and he said that the IT department sorted it by disabling Microsoft Firewall Client. Ive checked the client machine and cant seem to find Microsoft Firewall Client.
Look in Settings. There should be Firewall icon.
Thanks for the reply m8. Unfortunately the way the system is setup i wont be able to do installs. I spoke to a m8 that encountered this same problem and he said that the IT department sorted it by disabling Microsoft Firewall Client. Ive checked the client machine and cant seem to find Microsoft Firewall Client.
Its windows xp. And there are policies setup which disable windows firewall. You cant even turn it on.  
Do you have a test database server which will reproduce these symptoms from this client?

I still bet its firewall related.  This is the only things that I have ever seen cause this error.  There are rumors that it could be a slow network but I've never seen a network that slow.  How fast did the tnsping take to respond?

If you have a test server we can confirm this be telling ALL sql*Net connections to use the listeners port (USE_SHARED_SOCKET).  You don't want to mess with this on a production box because you have to bring down the listener to change it.

My first thought was that the database itself is down.  TNSPING only checks that there is a listener at teh designated IP address and port that knows about the database specified in the service name, it doesn't confirm that the database is up or even that it exists.  SQL*Plus actually connects to the database.

What ORA- or TNS- error messages are you getting?  What numbers?  The trace file reports an ORA-12535 but doesn't mention the secondary error messages (ORA-12535 is usually accompanied by secondary error messages).

Can you connect to the database from another machine on the network, if so how is that different to the one you're having problem with (different subnet, VLAN, Oracle client version, O/S version &c)?  Can you connect from that machine to a different database, if so how is it different from the database you're having problems with?

If you have access to the server try checking for firewalling/port blocking on that, if you don't have access get access and check.  Try stopping the listener, stopping the database (if it's Windows make sure to stop the service as well), restarting  the database (restarting the service first) then restarting the listener.  Use "lsnrctl status" to check the status of the listener and make sure the database is listed.

Stephen
Thanks for your replies guys.
1. We dont have a test database server
2. Tnsping takes about 20ms
3. The database is running ok on the server cause i checked it remotely and it was ok.
4. I get ORA-12535: TNS operation timed out. This does not come with any secondary message
5. I cant connect to the database from another client machine. I get the same error
6. Ive tried stoping both the listener, the database and restarting them both but it did not make any difference.

I know our network has a proxy server. Any ideas if this is the problem?
You do not mentioned the Oracle server version.
I guess it is 10g.
ASKER CERTIFIED SOLUTION
Avatar of slightwv (䄆 Netminder)
slightwv (䄆 Netminder)

Link to home
membership
This solution is only available to members.
To access this solution, you must be a member of Experts Exchange.
Start Free Trial
Thanks very much for all ur help guys. I used your option 3 slightwv and it all works fine now.
Just remember that you can't EVER stop your listener without dropping ALL existing connections.  That's the 1 major drawback to that approach.
Thanks i will bear that in mind :)