7 Replies Latest reply: Apr 6, 2012 12:47 PM by 857230 RSS

    server 2008 r2 sp1 oracle client 10.2.0.100

    857230
      We are trying to set up a build server to connect to our existing working 11g R1 linux base standard 4 node RAC database.

      With client 10.2.0.100 install we can connect with .net applications, sqlplus and we are able to tnsping successfully.

      Problem is our VB6 oo4o application will not connect.
      Weird thing is on another 2008 r2 server we don't have this problem.

      I have tried.
      opening up perms on the C:\oracle folder and its children
      setting environment variables, TNS_ADMIN,ORACLE_HOME,ORACLE_BASE and a few others out of desperation
      I have confirmed registry settings
      I have compared that c:\windows\assembly Oracle and Policy.Oracle GAC entries match between problem server and working server
      I have confirmed that the tnsnames.ora file match the working servers version

      I have set up sqlnet.ora to gather more info which for the most part is not digestable by my brain, and I am hoping someone can look at results and point me in the right direction.

      ====sqlnet.ora content===========
      #SQLNET.AUTHENTICATION_SERVICES = (NTS)
      TNSPING.TRACE_LEVEL =support
      TNSPING.TRACE_DIRECTORY=C:\oraclelogs
      TRACE_LEVEL_CLIENT = support
      TRACE_DIRECTORY_CLIENT=C:\oraclelogs
      LOG_DIRECTORY_CLIENT=C:\oraclelogs
      NAMES.DIRECTORY_PATH=(tnsnames,hostname)
      ==============================

      ===sqlnet.log====================


      ***********************************************************************
      Fatal NI connect error 12560, connecting to:
      (DESCRIPTION=(ADDRESS=(PROTOCOL=BEQ)(PROGRAM=oracle)(ARGV0=oracleORCL)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))'))(CONNECT_DATA=(SID=ORCL)(CID=(PROGRAM=C:\Bertch\CabMaker\CabMaker.exe)(HOST=DEVSERVER01)(USER=tonyedmo))))

      VERSION INFORMATION:
           TNS for 32-bit Windows: Version 10.2.0.1.0 - Production
           Oracle Bequeath NT Protocol Adapter for 32-bit Windows: Version 10.2.0.1.0 - Production
      Time: 05-APR-2012 10:37:04
      Tracing to file: C:\oraclelogs\cli_6104.trc
      Tns error struct:
      ns main err code: 12560
      TNS-12560: TNS:protocol adapter error
      ns secondary err code: 0
      nt main err code: 530
      TNS-00530: Protocol adapter error
      nt secondary err code: 126
      nt OS err code: 0
      =============================




      ====trace file====================
      (6104) [05-APR-2012 10:37:04:934] --- TRACE CONFIGURATION INFORMATION FOLLOWS ---
      (6104) [05-APR-2012 10:37:04:934] New trace stream is C:\oraclelogs\cli_6104.trc
      (6104) [05-APR-2012 10:37:04:934] New trace level is 16
      (6104) [05-APR-2012 10:37:04:934] --- TRACE CONFIGURATION INFORMATION ENDS ---
      (6104) [05-APR-2012 10:37:04:934] --- PARAMETER SOURCE INFORMATION FOLLOWS ---
      (6104) [05-APR-2012 10:37:04:934] Attempted load of system pfile source C:\oracle\product\10.2.0\client_1\NETWORK\ADMIN\sqlnet.ora
      (6104) [05-APR-2012 10:37:04:934] Parameter source loaded successfully
      (6104) [05-APR-2012 10:37:04:934]
      (6104) [05-APR-2012 10:37:04:934] Attempted load of local pfile source C:\Bertch\CabMaker\sqlnet.ora
      (6104) [05-APR-2012 10:37:04:935] Parameter source was not loaded
      (6104) [05-APR-2012 10:37:04:935]
      (6104) [05-APR-2012 10:37:04:935] -> PARAMETER TABLE LOAD RESULTS FOLLOW <-
      (6104) [05-APR-2012 10:37:04:935] Successful parameter table load
      (6104) [05-APR-2012 10:37:04:935] -> PARAMETER TABLE HAS THE FOLLOWING CONTENTS <-
      (6104) [05-APR-2012 10:37:04:935] TNSPING.TRACE_LEVEL = support
      (6104) [05-APR-2012 10:37:04:935] TRACE_LEVEL_CLIENT = support
      (6104) [05-APR-2012 10:37:04:935] NAMES.DIRECTORY_PATH = (tnsnames,hostname)
      (6104) [05-APR-2012 10:37:04:935] TNSPING.TRACE_DIRECTORY = C:\oraclelogs
      (6104) [05-APR-2012 10:37:04:935] LOG_DIRECTORY_CLIENT = C:\oraclelogs
      (6104) [05-APR-2012 10:37:04:935] TRACE_DIRECTORY_CLIENT = C:\oraclelogs
      (6104) [05-APR-2012 10:37:04:935] --- PARAMETER SOURCE INFORMATION ENDS ---
      (6104) [05-APR-2012 10:37:04:935] --- LOG CONFIGURATION INFORMATION FOLLOWS ---
      (6104) [05-APR-2012 10:37:04:935] Log stream will be "C:\oraclelogs\sqlnet.log"
      (6104) [05-APR-2012 10:37:04:935] Log stream validation not requested
      (6104) [05-APR-2012 10:37:04:935] --- LOG CONFIGURATION INFORMATION ENDS ---

      (6104) [05-APR-2012 10:37:04:935] nlstdipi: entry
      (6104) [05-APR-2012 10:37:04:936] nlstdipi: exit
      (6104) [05-APR-2012 10:37:04:936] nigini: entry
      (6104) [05-APR-2012 10:37:04:936] nigini: Count in the NL global area is now 1
      (6104) [05-APR-2012 10:37:04:936] nigini: Count in NI global area now: 1
      (6104) [05-APR-2012 10:37:04:936] nrigbi: entry
      (6104) [05-APR-2012 10:37:04:936] nrigbni: entry
      (6104) [05-APR-2012 10:37:04:937] nrigbni: Unable to get data from navigation file tnsnav.ora
      (6104) [05-APR-2012 10:37:04:937] nrigbni: exit
      (6104) [05-APR-2012 10:37:04:937] nrigbi: exit
      (6104) [05-APR-2012 10:37:04:937] nigini: exit
      (6104) [05-APR-2012 10:37:04:937] niqname: Hst is already an NVstring.
      (6104) [05-APR-2012 10:37:04:937] niqname: Inserting CID.
      (6104) [05-APR-2012 10:37:04:938] niotns: entry
      (6104) [05-APR-2012 10:37:04:938] snsbitini_ts: entry
      (6104) [05-APR-2012 10:37:04:938] snsbitini_ts: normal exit
      (6104) [05-APR-2012 10:37:04:938] snsbitini_ts: entry
      (6104) [05-APR-2012 10:37:04:938] snsbitini_ts: normal exit
      (6104) [05-APR-2012 10:37:04:938] niotns: Not trying to enable dead connection detection.
      (6104) [05-APR-2012 10:37:04:938] niotns: Calling address: (DESCRIPTION=(ADDRESS_LIST=(ADDRESS=(PROTOCOL=BEQ)(PROGRAM=oracle)(ARGV0=oracleORCL)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))')))(CONNECT_DATA=(SID=ORCL)(CID=(PROGRAM=C:\Bertch\CabMaker\CabMaker.exe)(HOST=DEVSERVER01)(USER=tonyedmo))))
      (6104) [05-APR-2012 10:37:04:938] nscall: entry
      (6104) [05-APR-2012 10:37:04:938] nsmal: entry
      (6104) [05-APR-2012 10:37:04:938] nsmal: 212 bytes at 0x5f4bd80
      (6104) [05-APR-2012 10:37:04:938] nsmal: normal exit
      (6104) [05-APR-2012 10:37:04:938] nscall: connecting...
      (6104) [05-APR-2012 10:37:04:938] nladini: entry
      (6104) [05-APR-2012 10:37:04:938] nladini: exit
      (6104) [05-APR-2012 10:37:04:938] nladget: entry
      (6104) [05-APR-2012 10:37:04:938] nladget: exit
      (6104) [05-APR-2012 10:37:04:938] nsc2addr: entry
      (6104) [05-APR-2012 10:37:04:938] nsc2addr: (DESCRIPTION=(ADDRESS=(PROTOCOL=BEQ)(PROGRAM=oracle)(ARGV0=oracleORCL)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))'))(CONNECT_DATA=(SID=ORCL)(CID=(PROGRAM=C:\Bertch\CabMaker\CabMaker.exe)(HOST=DEVSERVER01)(USER=tonyedmo))))
      (6104) [05-APR-2012 10:37:04:939] nlpcaini: entry
      (6104) [05-APR-2012 10:37:04:939] nlpcaini: prg = oracle
      (6104) [05-APR-2012 10:37:04:939] nlpcaini: arg[0] = oracleORCL
      (6104) [05-APR-2012 10:37:04:939] nlpcaini: arg[1] = (DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))
      (6104) [05-APR-2012 10:37:04:939] nlpcaini: env[0] = =::=::\
      (6104) [05-APR-2012 10:37:04:939] nlpcaini: exit
      (6104) [05-APR-2012 10:37:04:939] nsc2addr: normal exit
      (6104) [05-APR-2012 10:37:04:939] nsopen: entry
      (6104) [05-APR-2012 10:37:04:939] nsmal: entry
      (6104) [05-APR-2012 10:37:04:939] nsmal: 736 bytes at 0x5f50b68
      (6104) [05-APR-2012 10:37:04:940] nsmal: normal exit
      (6104) [05-APR-2012 10:37:04:940] nsopenmplx: entry
      (6104) [05-APR-2012 10:37:04:940] nsmal: entry
      (6104) [05-APR-2012 10:37:04:940] nsmal: 1644 bytes at 0x5f50e70
      (6104) [05-APR-2012 10:37:04:940] nsmal: normal exit
      (6104) [05-APR-2012 10:37:04:940] snsbitini_ts: entry
      (6104) [05-APR-2012 10:37:04:940] snsbitini_ts: normal exit
      (6104) [05-APR-2012 10:37:04:940] nsiorini: entry
      (6104) [05-APR-2012 10:37:04:940] nsbal: entry
      (6104) [05-APR-2012 10:37:04:940] nsbgetfl: entry
      (6104) [05-APR-2012 10:37:04:940] nsbgetfl: normal exit
      (6104) [05-APR-2012 10:37:04:940] nsmal: entry
      (6104) [05-APR-2012 10:37:04:940] nsmal: 84 bytes at 0x5f51620
      (6104) [05-APR-2012 10:37:04:940] nsmal: normal exit
      (6104) [05-APR-2012 10:37:04:940] nsbal: normal exit
      (6104) [05-APR-2012 10:37:04:940] nsiorini: exit (0)
      (6104) [05-APR-2012 10:37:04:940] nscpxget: entry
      (6104) [05-APR-2012 10:37:04:940] snsbitts_ts: entry
      (6104) [05-APR-2012 10:37:04:940] snsbitts_ts: acquired the bit
      (6104) [05-APR-2012 10:37:04:940] snsbitts_ts: normal exit
      (6104) [05-APR-2012 10:37:04:940] snsbitcl_ts: entry
      (6104) [05-APR-2012 10:37:04:940] snsbitcl_ts: normal exit
      (6104) [05-APR-2012 10:37:04:940] nscpxget: normal exit
      (6104) [05-APR-2012 10:37:04:940] nsopenalloc_nsntx: nlhthput on mplx_ht_nsgbu:ctx=5f50b68, nsntx=5f50e70
      (6104) [05-APR-2012 10:37:04:940] nsopenmplx: normal exit
      (6104) [05-APR-2012 10:37:04:940] nsopen: opening transport...
      (6104) [05-APR-2012 10:37:04:940] ntpcon: entry
      (6104) [05-APR-2012 10:37:04:940] ntpcon: toc = 1
      (6104) [05-APR-2012 10:37:04:940] sntpcall: entry
      (6104) [05-APR-2012 10:37:04:940] snlpcss: entry
      (6104) [05-APR-2012 10:37:04:941] snlpcss: Failed to load ORASPAWN.DLL, err = 126
      (6104) [05-APR-2012 10:37:04:941] sntpcall: exit
      (6104) [05-APR-2012 10:37:04:941] ntpcon: exit
      (6104) [05-APR-2012 10:37:04:941] nserror: entry
      (6104) [05-APR-2012 10:37:04:941] 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
      (6104) [05-APR-2012 10:37:04:941] nsopen: unable to open transport
      (6104) [05-APR-2012 10:37:04:941] snsbitts_ts: entry
      (6104) [05-APR-2012 10:37:04:941] snsbitts_ts: acquired the bit
      (6104) [05-APR-2012 10:37:04:941] snsbitts_ts: normal exit
      (6104) [05-APR-2012 10:37:04:941] snsbitcl_ts: entry
      (6104) [05-APR-2012 10:37:04:941] snsbitcl_ts: normal exit
      (6104) [05-APR-2012 10:37:04:941] nsiocancel: entry
      (6104) [05-APR-2012 10:37:04:941] nsiofrrg: entry
      (6104) [05-APR-2012 10:37:04:941] nsbfr: entry
      (6104) [05-APR-2012 10:37:04:941] nsbaddfl: entry
      (6104) [05-APR-2012 10:37:04:941] nsbaddfl: normal exit
      (6104) [05-APR-2012 10:37:04:941] nsbfr: normal exit
      (6104) [05-APR-2012 10:37:04:941] nsiofrrg: exit
      (6104) [05-APR-2012 10:37:04:941] nsiocancel: exit
      (6104) [05-APR-2012 10:37:04:941] nsopenfree_nsntx: nlhthdel from mplx_ht_nsgbu, ctx=5f50b68 nsntx=5f50e70
      (6104) [05-APR-2012 10:37:04:941] nsiocancel: entry
      (6104) [05-APR-2012 10:37:04:941] nsiofrrg: entry
      (6104) [05-APR-2012 10:37:04:941] nsiofrrg: exit
      (6104) [05-APR-2012 10:37:04:941] nsiocancel: exit
      (6104) [05-APR-2012 10:37:04:941] snsbittrm_ts: entry
      (6104) [05-APR-2012 10:37:04:941] snsbittrm_ts: normal exit
      (6104) [05-APR-2012 10:37:04:941] snsbitts_ts: entry
      (6104) [05-APR-2012 10:37:04:941] snsbitts_ts: acquired the bit
      (6104) [05-APR-2012 10:37:04:941] snsbitts_ts: normal exit
      (6104) [05-APR-2012 10:37:04:941] snsbitcl_ts: entry
      (6104) [05-APR-2012 10:37:04:941] snsbitcl_ts: normal exit
      (6104) [05-APR-2012 10:37:04:941] nsmfr: entry
      (6104) [05-APR-2012 10:37:04:941] nsmfr: 1644 bytes at 0x5f50e70
      (6104) [05-APR-2012 10:37:04:941] nsmfr: normal exit
      (6104) [05-APR-2012 10:37:04:941] nsmfr: entry
      (6104) [05-APR-2012 10:37:04:941] nsmfr: 736 bytes at 0x5f50b68
      (6104) [05-APR-2012 10:37:04:941] nsmfr: normal exit
      (6104) [05-APR-2012 10:37:04:942] nsopen: error exit
      (6104) [05-APR-2012 10:37:04:942] nsclose: entry
      (6104) [05-APR-2012 10:37:04:942] nsclose: normal exit
      (6104) [05-APR-2012 10:37:04:942] nladget: entry
      (6104) [05-APR-2012 10:37:04:942] nladget: exit
      (6104) [05-APR-2012 10:37:04:942] nsmfr: entry
      (6104) [05-APR-2012 10:37:04:942] nsmfr: 212 bytes at 0x5f4bd80
      (6104) [05-APR-2012 10:37:04:942] nsmfr: normal exit
      (6104) [05-APR-2012 10:37:04:942] nladtrm: entry
      (6104) [05-APR-2012 10:37:04:942] nladtrm: exit
      (6104) [05-APR-2012 10:37:04:942] nscall: error exit
      (6104) [05-APR-2012 10:37:04:942] nioqper: error from nscall
      (6104) [05-APR-2012 10:37:04:942] nioqper: ns main err code: 12560
      (6104) [05-APR-2012 10:37:04:942] nioqper: ns (2) err code: 0
      (6104) [05-APR-2012 10:37:04:942] nioqper: nt main err code: 530
      (6104) [05-APR-2012 10:37:04:942] nioqper: nt (2) err code: 126
      (6104) [05-APR-2012 10:37:04:942] nioqper: nt OS err code: 0
      (6104) [05-APR-2012 10:37:04:942] niomapnserror: entry
      (6104) [05-APR-2012 10:37:04:942] niqme: entry
      (6104) [05-APR-2012 10:37:04:942] niqme: reporting NS-12560 error as ORA-12560
      (6104) [05-APR-2012 10:37:04:942] niqme: exit
      (6104) [05-APR-2012 10:37:04:942] niomapnserror: exit
      (6104) [05-APR-2012 10:37:04:942] niotns: Couldn't connect, returning 12560
      (6104) [05-APR-2012 10:37:04:943] niotns: exit
      (6104) [05-APR-2012 10:37:04:943] snsbittrm_ts: entry
      (6104) [05-APR-2012 10:37:04:943] snsbittrm_ts: normal exit
      (6104) [05-APR-2012 10:37:04:943] snsbittrm_ts: entry
      (6104) [05-APR-2012 10:37:04:943] snsbittrm_ts: normal exit
      (6104) [05-APR-2012 10:37:04:943] nsbfrfl: entry
      (6104) [05-APR-2012 10:37:04:943] nsbrfr: entry
      (6104) [05-APR-2012 10:37:04:943] nsbrfr: nsbfs at 0x5f51620, data at 0x5f516a0.
      (6104) [05-APR-2012 10:37:04:943] nsbrfr: normal exit
      (6104) [05-APR-2012 10:37:04:943] nsbfrfl: normal exit
      (6104) [05-APR-2012 10:37:04:943] nigtrm: Count in the NI global area is now 0
      (6104) [05-APR-2012 10:37:04:943] nrigbd: entry
      (6104) [05-APR-2012 10:37:04:943] nrigbd: exit
      (6104) [05-APR-2012 10:37:04:943] nigtrm: Count in the NL global area is now 0
      (6104) [05-APR-2012 10:37:04:943] nlse_term_audit: entry
      (6104) [05-APR-2012 10:37:04:943] nlse_term_audit: exit

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




      Edit:
      I compared the trace files from working to non-working clients, they are the same up until

      niotns: Calling address: (DESCRIPTION=(ADDRESS=(PROTOCOL=TCP)(HOST=10.1.1.76)(PORT=1521))(ADDRESS=(PROTOCOL=TCP)(HOST=10.1.1.77)(PORT=1521))(ADDRESS=(PROTOCOL=TCP)(HOST=10.1.1.78)(PORT=1521))(ADDRESS=(PROTOCOL=TCP)(HOST=10.1.1.79)(PORT=1521))(LOAD_BALANCE=yes)(CONNECT_DATA=(SERVER=DEDICATED)(SERVICE_NAME=bcdat.bertch.com)(FAILOVER_MODE=(TYPE=SELECT)(METHOD=BASIC))(CID=(PROGRAM=C:\Bertch\CabMaker\CabMaker.exe)(HOST=DEVSERVER02)(USER=tonyedmo))))

      so it looks like the non-working client is not reading from tnsnames.ora?

      Edited by: Wieknot on Apr 5, 2012 10:24 AM
        • 1. Re: server 2008 r2 sp1 oracle client 10.2.0.100
          gdarling - oracle
          Sometimes when the client fails to connect to a remote database, it will then try to connect to a local database on the machine (for reasons I've never understood truthfully), and when a local db doesnt exist, you get 12560. In that case, ignore the 12560 and troubleshoot the error that came before that. Usually there are two traces generated in that case; is the error in the other one?

          What's the actual error you get reported to the oo4o app?

          Greg
          • 2. Re: server 2008 r2 sp1 oracle client 10.2.0.100
            857230
            It only creates one .trc and one .log, the content of which are included above.

            The vb app just displays the 12560 error.

            Am I missing some logging options in my sqlnet.ora file, in order to get this second trace file?
            • 3. Re: server 2008 r2 sp1 oracle client 10.2.0.100
              gdarling - oracle
              My assumption was that the 12560 wasn't the actual error. If that's what OO4O is reporting, then it's probably the actual error.

              What is your connect string in the OO4O app?

              Greg
              • 4. Re: server 2008 r2 sp1 oracle client 10.2.0.100
                857230
                they use the the alias from our tnsnames.ora file

                here is an example of one of the aliases
                #########################################
                # cluster production database all nodes #
                #########################################
                BCDAT =
                (DESCRIPTION =
                (ADDRESS = (PROTOCOL = TCP)(HOST = 10.1.1.76)(PORT = 1521))
                (ADDRESS = (PROTOCOL = TCP)(HOST = 10.1.1.77)(PORT = 1521))
                (ADDRESS = (PROTOCOL = TCP)(HOST = 10.1.1.78)(PORT = 1521))
                (ADDRESS = (PROTOCOL = TCP)(HOST = 10.1.1.79)(PORT = 1521))
                (LOAD_BALANCE = yes)
                (CONNECT_DATA =
                (SERVER = DEDICATED)
                (SERVICE_NAME = bcdat.bertch.com)
                (FAILOVER_MODE =
                (TYPE = SELECT)
                (METHOD = BASIC)
                )
                )
                )


                code snippet from vb6 app

                OraSession.DbOpenDatabase(sDatabaseName, sUserName & "/" & sPassword, ORADB_ORAMODE)

                where sDatabaseName would be "BCDAT"
                • 5. Re: server 2008 r2 sp1 oracle client 10.2.0.100
                  gdarling - oracle
                  Did you debug the app to CONFIRM that "BCDAT" is being passed? If you pass null, then a bequeath connection will be attempted, and you'll get 12560. The sqlnet trace snippet you've provided fairly clearly shows the app is trying to connect via bequeath.

                  Greg

                  Edited by: gdarling on Apr 6, 2012 10:24 AM
                  • 6. Re: server 2008 r2 sp1 oracle client 10.2.0.100
                    857230
                    vb6 is not installed on the server so I have not

                    this is a production app, that does work on all of our client PCs and on our other server 2008 r2 server

                    I can make a temp release that will confirm what is sent in, but I'm very confident it is not sending a null
                    • 7. Re: server 2008 r2 sp1 oracle client 10.2.0.100
                      857230
                      well I'll be a son of you know what

                      I did a test release and it appears you are correct on the null value, I will do some more digging and let you know the final results.

                      Thanks, I have a bit of hope now :-)