rendered paste body$ cat client.trace_4465.trc
[12-FEB-2007 14:56:22:167] --- TRACE CONFIGURATION INFORMATION FOLLOWS ---
[12-FEB-2007 14:56:22:168] New trace stream is /home/oracle/client.trace_4465.trc
[12-FEB-2007 14:56:22:168] New trace level is 4
[12-FEB-2007 14:56:22:168] --- TRACE CONFIGURATION INFORMATION ENDS ---
[12-FEB-2007 14:56:22:168] --- PARAMETER SOURCE INFORMATION FOLLOWS ---
[12-FEB-2007 14:56:22:168] Attempted load of system pfile source /home/oracle/oracle/product/10.2.0/db_1/network/admin/sqlnet.ora
[12-FEB-2007 14:56:22:168] Parameter source loaded successfully
[12-FEB-2007 14:56:22:168]
[12-FEB-2007 14:56:22:168] Attempted load of local pfile source /home/oracle/.sqlnet.ora
[12-FEB-2007 14:56:22:168] Parameter source was not loaded
[12-FEB-2007 14:56:22:168]
[12-FEB-2007 14:56:22:168] -> PARAMETER TABLE LOAD RESULTS FOLLOW <-
[12-FEB-2007 14:56:22:168] Successful parameter table load
[12-FEB-2007 14:56:22:168] -> PARAMETER TABLE HAS THE FOLLOWING CONTENTS <-
[12-FEB-2007 14:56:22:168] TRACE_LEVEL_CLIENT = USER
[12-FEB-2007 14:56:22:168] NAMES.DIRECTORY_PATH = (TNSNAMES, EZCONNECT)
[12-FEB-2007 14:56:22:168] TRACE_DIRECTORY_CLIENT = /home/oracle/
[12-FEB-2007 14:56:22:168] TRACE_FILE_CLIENT = CLIENT.trace
[12-FEB-2007 14:56:22:168] --- PARAMETER SOURCE INFORMATION ENDS ---
[12-FEB-2007 14:56:22:168] --- LOG CONFIGURATION INFORMATION FOLLOWS ---
[12-FEB-2007 14:56:22:168] Log stream will be "/home/oracle/DSAccord6/sql/sqlnet.log"
[12-FEB-2007 14:56:22:168] Log stream validation not requested
[12-FEB-2007 14:56:22:168] --- LOG CONFIGURATION INFORMATION ENDS ---
[12-FEB-2007 14:56:22:168] nrigbni: Unable to get data from navigation file tnsnav.ora
[12-FEB-2007 14:56:22:169] nttbnd2addr: ip=loopback specified
[12-FEB-2007 14:56:22:169] nttbnd2addr: using 127.0.0.1
[12-FEB-2007 14:56:22:169] nttbnd2addr: using host IP address: 127.0.0.1
[12-FEB-2007 14:56:22:169] nttcnp: getting sockname
[12-FEB-2007 14:56:22:169] nttcnp: getting peername
[12-FEB-2007 14:56:22:170] niotns: Not trying to enable dead connection detection.
[12-FEB-2007 14:56:22:170] niotns: Calling address: (DESCRIPTION=(ADDRESS=(PROTOCOL=beq)(PROGRAM=/home/oracle/oracle/product/10.2.0/db_1/bin/oracle)(ARGV0=oracleorcl)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))')(DETACH=NO))(CONNECT_DATA=(CID=(PROGRAM=sqlplus)(HOST=accord.novaglobal.com.sg)(USER=oracle))))
[12-FEB-2007 14:56:22:170] nscall: connecting...
[12-FEB-2007 14:56:22:170] nsc2addr: (DESCRIPTION=(ADDRESS=(PROTOCOL=beq)(PROGRAM=/home/oracle/oracle/product/10.2.0/db_1/bin/oracle)(ARGV0=oracleorcl)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))')(DETACH=NO))(CONNECT_DATA=(CID=(PROGRAM=sqlplus)(HOST=accord.novaglobal.com.sg)(USER=oracle))))
[12-FEB-2007 14:56:22:170] nsopen: opening transport...
[12-FEB-2007 14:56:22:170] sntpcall: not detaching from parent with additional fork
[12-FEB-2007 14:56:22:171] sntpcall: hdl[IR]=11, hdl[IW]=10
[12-FEB-2007 14:56:22:196] sntpcall: result string is NTP0 4468
[12-FEB-2007 14:56:22:196] nsopen: transport is open
[12-FEB-2007 14:56:22:197] nsnainit: inf->nsinfflg[0]: 0x41 inf->nsinfflg[1]: 0x41
[12-FEB-2007 14:56:22:197] nsopen: global context check-in (to slot 0) complete
[12-FEB-2007 14:56:22:197] nscon: doing connect handshake...
[12-FEB-2007 14:56:22:197] nscon: sending NSPTCN packet
[12-FEB-2007 14:56:22:197] nscon: sending 267 bytes connect data
[12-FEB-2007 14:56:22:197] nsdo: 267 bytes to NS buffer
[12-FEB-2007 14:56:22:197] nscon: got NSPTAC packet
[12-FEB-2007 14:56:22:197] nscon: connect handshake is complete
[12-FEB-2007 14:56:22:197] nscon: nsctxinf[0]=0x41, [1]=0x41
[12-FEB-2007 14:56:22:197] nsnainconn: inf->nsinfflg[0]: 0x41 inf->nsinfflg[1]: 0x41
[12-FEB-2007 14:56:22:197] nsnasend: bytes to send: 158
[12-FEB-2007 14:56:22:197] nsdo: 158 bytes to NS buffer
[12-FEB-2007 14:56:22:197] nsnareceive: buffer address: 0xbfff516c bytes wanted: 2048
[12-FEB-2007 14:56:22:198] nsnareceive: calling NS to receive 2048 bytes into address 0xbfff516c
[12-FEB-2007 14:56:22:198] nsdo: 129 bytes from NS buffer
[12-FEB-2007 14:56:22:198] nsnareceive: received 129 bytes
[12-FEB-2007 14:56:22:198] nsnareceive: no more data to receive - returning
[12-FEB-2007 14:56:22:198] nsnareceive: total bytes received: 129
[12-FEB-2007 14:56:22:198] nszgwop: SQLNET.WALLET_OVERRIDE not found, using default.
[12-FEB-2007 14:56:52:981] nstimarmed: no timer allocated
[12-FEB-2007 14:56:52:981] nstoClearTimeout: ATO disabled for ctx=0x0x80d2f48
[12-FEB-2007 14:56:52:981] nstoClearTimeout: STO disabled for ctx=0x0x80d2f48
[12-FEB-2007 14:56:52:981] nstoClearTimeout: RTO disabled for ctx=0x0x80d2f48
[12-FEB-2007 14:56:52:981] nstoClearTimeout: PITO disabled for ctx=0x0x80d2f48
[12-FEB-2007 14:56:52:981] nstoUpdateActive: Active timeout is -1 (see nstotyp)
[12-FEB-2007 14:56:52:981] nsclose: closing transport
[12-FEB-2007 14:56:52:981] nsclose: global context check-out (from slot 0) complete
[12-FEB-2007 14:56:52:982] nttbnd2addr: ip=loopback specified
[12-FEB-2007 14:56:52:982] nttbnd2addr: using 127.0.0.1
[12-FEB-2007 14:56:52:982] nttbnd2addr: using host IP address: 127.0.0.1
[12-FEB-2007 14:56:52:982] nttcnp: getting sockname
[12-FEB-2007 14:56:52:982] nttcnp: getting peername
[12-FEB-2007 14:56:52:982] niotns: Not trying to enable dead connection detection.
[12-FEB-2007 14:56:52:982] niotns: Calling address: (DESCRIPTION=(ADDRESS=(PROTOCOL=beq)(PROGRAM=/home/oracle/oracle/product/10.2.0/db_1/bin/oracle)(ARGV0=oracleorcl)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))')(DETACH=NO))(CONNECT_DATA=(CID=(PROGRAM=sqlplus)(HOST=accord.novaglobal.com.sg)(USER=oracle))))
[12-FEB-2007 14:56:52:982] nscall: connecting...
[12-FEB-2007 14:56:52:982] nsc2addr: (DESCRIPTION=(ADDRESS=(PROTOCOL=beq)(PROGRAM=/home/oracle/oracle/product/10.2.0/db_1/bin/oracle)(ARGV0=oracleorcl)(ARGS='(DESCRIPTION=(LOCAL=YES)(ADDRESS=(PROTOCOL=beq)))')(DETACH=NO))(CONNECT_DATA=(CID=(PROGRAM=sqlplus)(HOST=accord.novaglobal.com.sg)(USER=oracle))))
[12-FEB-2007 14:56:52:982] nsopen: opening transport...
[12-FEB-2007 14:56:52:983] sntpcall: not detaching from parent with additional fork
[12-FEB-2007 14:56:52:983] sntpcall: hdl[IR]=11, hdl[IW]=10
[12-FEB-2007 14:56:53:009] sntpcall: result string is NTP0 4469
[12-FEB-2007 14:56:53:010] nsopen: transport is open
[12-FEB-2007 14:56:53:010] nsnainit: inf->nsinfflg[0]: 0x41 inf->nsinfflg[1]: 0x41
[12-FEB-2007 14:56:53:010] nsopen: global context check-in (to slot 0) complete
[12-FEB-2007 14:56:53:010] nscon: doing connect handshake...
[12-FEB-2007 14:56:53:010] nscon: sending NSPTCN packet
[12-FEB-2007 14:56:53:010] nscon: sending 267 bytes connect data
[12-FEB-2007 14:56:53:010] nsdo: 267 bytes to NS buffer
[12-FEB-2007 14:56:53:010] nscon: got NSPTAC packet
[12-FEB-2007 14:56:53:010] nscon: connect handshake is complete
[12-FEB-2007 14:56:53:010] nscon: nsctxinf[0]=0x41, [1]=0x41
[12-FEB-2007 14:56:53:010] nsnainconn: inf->nsinfflg[0]: 0x41 inf->nsinfflg[1]: 0x41
[12-FEB-2007 14:56:53:010] nsnasend: bytes to send: 158
[12-FEB-2007 14:56:53:010] nsdo: 158 bytes to NS buffer
[12-FEB-2007 14:56:53:010] nsnareceive: buffer address: 0xbfff516c bytes wanted: 2048
[12-FEB-2007 14:56:53:010] nsnareceive: calling NS to receive 2048 bytes into address 0xbfff516c
[12-FEB-2007 14:56:53:011] nsdo: 129 bytes from NS buffer
[12-FEB-2007 14:56:53:011] nsnareceive: received 129 bytes
[12-FEB-2007 14:56:53:011] nsnareceive: no more data to receive - returning
[12-FEB-2007 14:56:53:011] nsnareceive: total bytes received: 129
[12-FEB-2007 14:56:53:025] nszgclv: SQLNET.ALLOWED_LOGON_VERSION not found, using default.
[12-FEB-2007 14:57:30:008] nsrdr: got NSPTMK packet
[12-FEB-2007 14:57:30:008] nioqrc: Recieve: returning error: 3111
[12-FEB-2007 14:57:30:008] nsdo: sending NSPTMK packet
[12-FEB-2007 14:57:30:008] nsrdr: got NSPTMK packet
[12-FEB-2007 14:57:30:008] 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
[12-FEB-2007 14:57:30:008] 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
[12-FEB-2007 14:57:30:016] nstimarmed: no timer allocated
[12-FEB-2007 14:57:30:016] nstoClearTimeout: ATO disabled for ctx=0x0x80d2fe8
[12-FEB-2007 14:57:30:016] nstoClearTimeout: STO disabled for ctx=0x0x80d2fe8
[12-FEB-2007 14:57:30:016] nstoClearTimeout: RTO disabled for ctx=0x0x80d2fe8
[12-FEB-2007 14:57:30:016] nstoClearTimeout: PITO disabled for ctx=0x0x80d2fe8
[12-FEB-2007 14:57:30:016] nstoUpdateActive: Active timeout is -1 (see nstotyp)
[12-FEB-2007 14:57:30:016] nsclose: closing transport
[12-FEB-2007 14:57:30:016] nsclose: global context check-out (from slot 0) complete