Re: SQLPlus Hangs

On Dec 26, 9:59 am, raghu <raghu_v..._at_yahoo.com> wrote:
> On Dec 26, 7:31 pm, "fitzjarr..._at_cox.net" <fitzjarr..._at_cox.net> wrote:
>
> > On Dec 26, 5:38 am, raghu <raghu_v..._at_yahoo.com> wrote:
>
> > > When I enter the credentials in SQL Plus, it hangs. This  happens both
> > > in the server machine (Oracle 10.2.0) and the clients. But I am able
> > > to login using the command prompt in the server. Tnsping works
> > > correctly. Nothing is logged in the alert log for the login attempt.
> > > Any ideas?
>
> > > Here is an excerpt of the alert log:
>
> > > Starting background process EMN0
> > > EMN0 started with pid=134, OS id=3648
> > > Wed Dec 26 11:56:33 2007
> > > Shutting down instance: further logons disabled
> > > Wed Dec 26 11:56:33 2007
> > > Stopping background process QMNC
> > > Wed Dec 26 11:56:34 2007
> > > Stopping background process CJQ0
> > > Wed Dec 26 11:56:34 2007
> > > Background process QMNC not dead after 150 seconds
> > > Wed Dec 26 11:56:34 2007
> > > Killing background process QMNC
> > > Wed Dec 26 11:56:34 2007
> > > Stopping background process MMNL
> > > Wed Dec 26 11:56:36 2007
> > > Background process MMNL not dead after 10 seconds
> > > Wed Dec 26 11:56:36 2007
> > > Killing background process MMNL
> > > Wed Dec 26 11:56:36 2007
> > > Stopping background process MMON
> > > Wed Dec 26 11:56:37 2007
> > > Background process MMON not dead after 30 seconds
> > > Wed Dec 26 11:56:37 2007
> > > Killing background process MMON
> > > Wed Dec 26 11:56:37 2007
> > > Shutting down instance (immediate)
> > > License high water mark = 5
> > > Wed Dec 26 11:56:37 2007
> > > Stopping Job queue slave processes
> > > Wed Dec 26 11:56:37 2007
> > > Job queue slave processes stopped
> > > All dispatchers and shared servers shutdown
> > > Wed Dec 26 11:56:44 2007
> > > ALTER DATABASE CLOSE NORMAL
> > > Wed Dec 26 11:56:44 2007
> > > SMON: disabling tx recovery
> > > SMON: disabling cache recovery
> > > Wed Dec 26 11:56:44 2007
> > > SUCCESS: diskgroup ORAREDO was dismounted
> > > Shutting down archive processes
> > > Archiving is disabled
> > > Wed Dec 26 11:56:49 2007
> > > ARCH shutting down
> > > ARC1: Archival stopped
> > > Wed Dec 26 11:56:54 2007
> > > ARCH shutting down
> > > ARC0: Archival stopped
> > > Wed Dec 26 11:56:55 2007
> > > Thread 1 closed at log sequence 15521
> > > Successful close of redo thread 1
> > > Wed Dec 26 11:56:59 2007
> > > Completed: ALTER DATABASE CLOSE NORMAL
> > > Wed Dec 26 11:56:59 2007
> > > ALTER DATABASE DISMOUNT
> > > Wed Dec 26 11:56:59 2007
> > > SUCCESS: diskgroup ORADATA was dismounted
> > > SUCCESS: diskgroup ORABACKUP was dismounted
> > > Wed Dec 26 11:56:59 2007
> > > Completed: ALTER DATABASE DISMOUNT
> > > ARCH: Archival disabled due to shutdown: 1089
> > > Shutting down archive processes
> > > Archiving is disabled
> > > Archive process shutdown avoided: 0 active
> > > ARCH: Archival disabled due to shutdown: 1089
> > > Shutting down archive processes
> > > Archiving is disabled
> > > Archive process shutdown avoided: 0 active
> > > Wed Dec 26 11:57:05 2007
> > > freeing rdom 0
> > > Wed Dec 26 11:58:31 2007
> > > Starting ORACLE instance (normal)
> > > LICENSE_MAX_SESSION = 0
> > > LICENSE_SESSIONS_WARNING = 0
> > > Interface type 1 Private x.168.70.0 configured from OCR for use as a
> > > cluster interconnect
> > > Interface type 1 Public x.45.5.0 configured from OCR for use as  a
> > > public interface
> > > Picked latch-free SCN scheme 3
> > > Autotune of undo retention is turned on.
> > > LICENSE_MAX_USERS = 0
> > > SYS auditing is disabled
> > > ksdpec: called for event 13740 prior to event group initialization
> > > Starting up ORACLE RDBMS Version: 10.2.0.2.0.
> > > System parameters with non-default values:
> > >   processes                = 500
> > >   sessions                 = 555
> > >   __shared_pool_size       = 1711276032
> > >   __large_pool_size        = 0
> > >   __java_pool_size         = 16777216
> > >   __streams_pool_size      = 0
> > >   trace_enabled            = FALSE
> > >   nls_language             = AMERICAN
> > >   nls_territory            = UNITED KINGDOM
> > >   sga_target               = 12884901888
> > >   control_files            = +ORADATA/edwprd/controlfile/current.
> > > 256.620240201, +ORABACKUP/edwprd/controlfile/current.256.620240201
> > >   db_block_size            = 16384
> > >   __db_cache_size          = 11140071424
> > >   db_writer_processes      = 8
> > >   compatible               = 10.2.0.2.0
> > >   log_archive_dest_1       = LOCATION=+ORABACKUP/EDWPRD/
> > >   log_archive_format       = ARC%S_%R.%T
> > >   db_file_multiblock_read_count= 16
> > >   cluster_database         = TRUE
> > >   cluster_database_instances= 2
> > >   db_create_file_dest      = +ORADATA
> > >   db_recovery_file_dest    = +ORABACKUP
> > >   db_recovery_file_dest_size= 10737418240
> > >   thread                   = 1
> > >   instance_number          = 1
> > >   undo_management          = AUTO
> > >   undo_tablespace          = UNDOTBS1
> > >   remote_login_passwordfile= EXCLUSIVE
> > >   db_domain                =
> > >   dispatchers              = (PROTOCOL=TCP) (SERVICE=EDWPRDXDB)
> > >   remote_listener          = LISTENERS_EDWPRD
> > >   job_queue_processes      = 10
> > >   audit_file_dest          = D:\ORACLE\PRODUCT\10.2.0\ADMIN\EDWPRD
> > > \ADUMP
> > >   background_dump_dest     = D:\ORACLE\PRODUCT\10.2.0\ADMIN\EDWPRD
> > > \BDUMP
> > >   user_dump_dest           = D:\ORACLE\PRODUCT\10.2.0\ADMIN\EDWPRD
> > > \UDUMP
> > >   max_dump_file_size       = 1024
> > >   core_dump_dest           = D:\ORACLE\PRODUCT\10.2.0\ADMIN\EDWPRD
> > > \CDUMP
> > >   db_name                  = EDWPRD
> > >   open_cursors             = 300
> > >   optimizer_mode           = choose
> > >   pga_aggregate_target     = 1707081728
> > > Cluster communication is configured to use the following interface(s)
> > > for this instance
> > >   192.168.70.11
> > > Wed Dec 26 11:58:37 2007
> > > cluster interconnect IPC version:Oracle 9i Winsock2 TCP/IP IPC
> > > IPC Vendor 0 proto 0
> > >   Version 0.0
> > > PMON started with pid=2, OS id=7116
> > > DIAG started with pid=6, OS id=6880
> > > PSP0 started with pid=10, OS id=6476
> > > LMON started with pid=14, OS id=6856
> > > Wed Dec 26 11:58:39 2007
> > > ORA-00130: invalid listener address '(ADDRESS=(PROTOCOL=TCP)
> > > (HOST=BRNORA62-vip)(PORT=1521))'
>
> > > LMD0 started with pid=18, OS id=7016
> > > LMS0 started with pid=22, OS id=6728
> > > LMS1 started with pid=26, OS id=4036
> > > MMAN started with pid=30, OS id=6204
> > > DBW0 started with pid=34, OS id=448
> > > DBW1 started with pid=38, OS id=5492
> > > DBW2 started with pid=42, OS id=4436
> > > DBW3 started with pid=46, OS id=5960
> > > DBW4 started with pid=50, OS id=6324
> > > DBW5 started with pid=54, OS id=5700
> > > DBW6 started with pid=58, OS id=2828
> > > DBW7 started with pid=62, OS id=4612
> > > LGWR started with pid=66, OS id=3952
> > > CKPT started with pid=70, OS id=1572
> > > SMON started with pid=74, OS id=6288
> > > RECO started with pid=78, OS id=5860
> > > CJQ0 started with pid=82, OS id=6672
> > > MMON started with pid=86, OS id=5288
> > > MMNL started with pid=90, OS id=6976
> > > Wed Dec 26 11:58:39 2007
> > > starting up 1 dispatcher(s) for network address '(ADDRESS=(PARTIAL=YES)
> > > (PROTOCOL=TCP))'...
> > > starting up 1 shared server(s) ...
> > > Wed Dec 26 11:58:39 2007
> > > lmon registered with NM - instance id 1 (internal mem no 0)
> > > Wed Dec 26 11:58:41 2007
> > > Reconfiguration started (old inc 0, new inc 2)
> > > List of nodes:
> > >  0
> > >  Global Resource Directory frozen
> > > * allocate domain 0, invalid = TRUE
> > >  Communication channels reestablished
> > >  Master broadcasted resource hash value bitmaps
> > >  Non-local Process blocks cleaned out
> > > Wed Dec 26 11:58:41 2007
> > >  LMS 1: 0 GCS shadows cancelled, 0 closed
> > > Wed Dec 26 11:58:42 2007
> > >  LMS 0: 0 GCS shadows cancelled, 0 closed
> > >  Set master node info
> > >  Submitted all remote-enqueue requests
> > >  Dwn-cvts replayed, VALBLKs dubious
> > >  All grantable enqueues granted
> > >  Post SMON to start 1st pass IR
> > > Wed Dec 26 11:58:42 2007
> > >  LMS 0: 0 GCS shadows traversed, 0 replayed
> > > Wed Dec 26 11:58:42 2007
> > >  LMS 1: 0 GCS shadows traversed, 0 replayed
> > > Wed Dec 26 11:58:42 2007
> > >  Submitted all GCS remote-cache requests
> > >  Post SMON to start 1st pass IR
> > >  Fix write in gcs resources
> > > Reconfiguration complete
> > > LCK0 started with pid=102, OS id=1876
> > > Wed Dec 26 11:58:43 2007
> > > ALTER DATABASE   MOUNT
> > > Wed Dec 26 11:58:43 2007
> > > This instance was first to mount
> > > Wed Dec 26 11:58:43 2007
> > > Starting background process ASMB
> > > ASMB started with pid=110, OS id=5428
> > > Starting background process RBAL
> > > RBAL started with pid=114, OS id=4344
> > > Wed Dec 26 11:58:48 2007
> > > SUCCESS: diskgroup ORADATA was mounted
> > > SUCCESS: diskgroup ORABACKUP was mounted
> > > Wed Dec 26 11:58:52 2007
> > > Setting recovery target incarnation to 1
> > > Wed Dec 26 11:58:52 2007
> > > Successful mount of redo thread 1, with mount id 606843251
> > > Wed Dec 26 11:58:52 2007
> > > Database mounted in Shared Mode (CLUSTER_DATABASE=TRUE)
> > > Completed: ALTER DATABASE   MOUNT
> > > Wed Dec 26 11:58:52 2007
> > > ALTER DATABASE OPEN
> > > This instance was first to open
> > > Picked broadcast on commit scheme to generate SCNs
> > > Wed Dec 26 11:58:53 2007
> > > LGWR: STARTING ARCH PROCESSES
> > > ARC0 started with pid=134, OS id=4724
> > > Wed Dec 26 11:58:53 2007
> > > ARC0: Archival started
> > > ARC1 started with pid=138, OS id=4700
> > > Wed Dec 26 11:58:53 2007
> > > ARC1: Archival started
> > > LGWR: STARTING ARCH PROCESSES COMPLETE
> > > SUCCESS: diskgroup ORAREDO was mounted
> > > Thread 1 opened at log sequence 15521
> > >   Current log# 6 seq# 15521 mem# 0: +ORAREDO/edwprd/onlinelog/
> > > group_6.257.630862051
> > >   Current log# 6 seq# 15521 mem# 1: +ORAREDO/edwprd/onlinelog/
> > > group_6.256.630862053
> > > Successful open of redo thread 1
> > > Wed Dec 26 11:58:53 2007
> > > MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
> > > Wed Dec 26 11:58:53 2007
> > > ARC1: STARTING ARCH PROCESSES
> > > Wed Dec 26 11:58:53 2007
> > > ARC0: Becoming the 'no FAL' ARCH
> > > ARC0: Becoming the 'no SRL' ARCH
> > > Wed Dec 26 11:58:53 2007
> > > ARC2: Archival started
> > > ARC2 started with pid=142, OS id=4256
> > > Wed Dec 26 11:58:53 2007
> > > SMON: enabling cache recovery
> > > Wed Dec 26 11:58:53 2007
> > > ARC1: STARTING ARCH PROCESSES COMPLETE
> > > ARC1: Becoming the heartbeat ARCH
> > > Wed Dec 26 11:58:54 2007
> > > Successfully onlined Undo Tablespace 1.
> > > Wed Dec 26 11:58:54 2007
> > > SMON: enabling tx recovery
> > > Wed Dec 26 11:58:54 2007
> > > Database Characterset is WE8MSWIN1252
> > > Wed Dec 26 11:58:54 2007
> > > Instance recovery: looking
>
> > ...
>
> > read more »- Hide quoted text -
>
> > - Show quoted text -
>
> Here are the listener and sqlnet trace files. I cannot seem to make
> much sense out of it. Sqlnet tracing is not happening inspite of
> turning on the tracing.
>
> sqlnet trace
> --------------------
> Fatal NI connect error 12560, connecting to:
>  (LOCAL=NO)
>
>   VERSION INFORMATION:
>         TNS for 64-bit Windows: Version 10.2.0.2.0 - Production
>         Oracle Bequeath NT Protocol Adapter for 64-bit Windows: Version
> 10.2.0.2.0 - Production
>         Windows NT TCP/IP NT Protocol Adapter for 64-bit Windows: Version
> 10.2.0.2.0 - Production
>   Time: 26-DEC-2007 16:43:48
>   Tracing not turned on.
>   Tns error struct:
>     ns main err code: 12560
>     TNS-12560: TNS:protocol adapter error
>     ns secondary err code: 0
>     nt main err code: 0
>     nt secondary err code: 0
>     nt OS err code: 0
> ---------------------------------------------------------------------------­--------------------------
>
> listener trace
> -----------------
>
> nsglrespond: entry
> nsdo: cid=7, opcode=67, *bl=82, *what=1, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x100400c, mvd=0
> nsdo: gtn=207, gtc=207, ptn=10, ptc=2011
> nsdo: 82 bytes to NS buffer
> nsdo: nsctxrnk=0
> nsglrespond: exit
> nsgldissolve: entry
> nstimarmed: no timer allocated
> nsdo: cid=7, opcode=66, *bl=0, *what=0, uflgs=0x0, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x1004008, mvd=0
> nsevunreg: cid=7, sgt=0, rdm=2
> nsevunreg: 5 registered connection(s)
> nsdo: cid=7, opcode=67, *bl=0, *what=1, uflgs=0x0, cflgs=0x1
> nsdo: nsctx: state=8, flg=0x1004008, mvd=0
> nsdo: gtn=207, gtc=207, ptn=92, ptc=2011
> nsdofls: DATA flags: 0x40
> nsdofls: sending NSPTDA packet
> nspsend: plen=92, type=6
> ntnwr: failed to write 92 bytes
> sntn2err: entry
> sntn2err: error: handle=480, op=6, nt[0]=524, nt[1]=997
> sntn2err: exit
> nspsend: 92 bytes to transport
> nsdo: nsctxrnk=0
> nsclose: closing transport
> ntndisc: entry
> sntndisc: Closed pipe afer FlushFileBuffers480
> ntndisc: exit
> nsclose: global context check-out (from slot 7) complete
> nsmfr: 2176 bytes at 0x3682a00
> nsmfr: 1112 bytes at 0x22961c0
> nsgldissolve: Deallocating cxd 0x22db310.
> nsgldissolve: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> nsevwait: 0 newly-posted event(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: 0 posted event(s)
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 318, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 318, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22daf20 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22daf20 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 5
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 6
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 318 bytes from leftover
> nsprecv: tlen=318, plen=318, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 316 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 308 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:0 mld1:800 ld2:5 mld2:555
> nsglgrDoRegister: exit
> nsdo: cid=5, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 316 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 318 bytes from leftover
> nsprecv: tlen=318, plen=318, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 308 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:0 mld1:800 ld2:5 mld2:555
> nsglgrDoRegister: exit
> nsdo: cid=6, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 350, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 350, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22daf20 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22daf20 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 5
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 6
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 350 bytes from leftover
> nsprecv: tlen=350, plen=350, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 316 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 340 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: instance flags - req:256 cur:272
> nsglgrDoRegister: inst loads: ld1:1 mld1:800 ld2:5 mld2:555
> nsglgrDoRegister: exit
> nsdo: cid=5, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 316 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 350 bytes from leftover
> nsprecv: tlen=350, plen=350, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 340 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: instance flags - req:256 cur:272
> nsglgrDoRegister: inst loads: ld1:1 mld1:800 ld2:5 mld2:555
> nsglgrDoRegister: exit
> nsdo: cid=6, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 318, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22dab30 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22dab30 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 4
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=4, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=214, gtc=214, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 318 bytes from leftover
> nsprecv: tlen=318, plen=318, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 308 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 308 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:1 mld2:49
> nsglgrDoRegister: exit
> nsdo: cid=4, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 308 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 318, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22dab30 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22dab30 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 4
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=4, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 318 bytes from leftover
> nsprecv: tlen=318, plen=318, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 308 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 308 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:1 mld2:49
> nsglgrDoRegister: exit
> nsdo: cid=4, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 308 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 10, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22daf20 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22daf20 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 5
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 10 bytes from leftover
> nsprecv: tlen=10, plen=10, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 316 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x40
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x1
> nsdo: nsctx: state=6, flg=0x600c, mvd=0
> nsdo: gtn=10, gtc=10, ptn=10, ptc=8155
> nsdo: nsctxrnk=0
> nsglhfre: entry
> nsglhrem: entry
> nsglhrem: exit
> nsglhfre: exit
> nsglhrem: entry
> nsglhrem: exit
> nsglhfre: entry
> nsglhrem: entry
> nstimarmed: no timer allocated
> nttctl: entry
> nttctl: entry
> nsdo: cid=5, opcode=98, *bl=0, *what=0, uflgs=0x40, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=3, flg=0x4008, mvd=0
> nsevunreg: cid=5, sgt=0, rdm=2
> nsevunreg: 4 registered connection(s)
> nsdo: nsctxrnk=0
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 316 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 316 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 316 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 316 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> nsclose: closing transport
> nttdisc: entry
> nttdisc: Closed socket 316
> nttdisc: exit
> nsclose: global context check-out (from slot 5) complete
> nsmfr: 2176 bytes at 0x36818e0
> nsmfr: 1112 bytes at 0x2295d30
> nsglhfre: Deallocating cxd 0x22daf20.
> nsglhfre: exit
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 4 registered connection(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 10, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevwait: 1 pre-posted event(s)
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 6
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 10 bytes from leftover
> nsprecv: tlen=10, plen=10, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x40
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x1
> nsdo: nsctx: state=6, flg=0x600c, mvd=0
> nsdo: gtn=10, gtc=10, ptn=10, ptc=8155
> nsdo: nsctxrnk=0
> nsglhfre: entry
> nsglhrem: entry
> nsglhrem: exit
> nsglhfre: exit
> nsglhfre: entry
> nsglhrem: entry
> nsglhrem: exit
> nsc2addr: (ADDRESS=(PROTOCOL=tcp)(HOST=brnora61.bestcorp.net)
> (PORT=2697))
> nttbnd2addr: entry
> snlinGetAddrInfo: entry
> snlinGetAddrInfo: Invalid IP address string brnora61.bestcorp.net
> snlinFreeAddrInfo: entry
> snlinFreeAddrInfo: exit
> snlinGetAddrInfo: exit
> nttbnd2addr: looking up IP addr for host: brnora61.bestcorp.net
> snlinGetAddrInfo: entry
> snlinGetAddrInfo: exit
> snlinFreeAddrInfo: entry
> snlinFreeAddrInfo: exit
> nttbnd2addr: exit
> nsglhfre: exit
> nsglhrem: entry
> nsglhrem: exit
> nsglhfre: entry
> nsglhrem: entry
> nstimarmed: no timer allocated
> nttctl: entry
> nttctl: entry
> nsdo: cid=6, opcode=98, *bl=0, *what=0, uflgs=0x40, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=3, flg=0x4008, mvd=0
> nsevunreg: cid=6, sgt=0, rdm=2
> nsevunreg: 3 registered connection(s)
> nsdo: nsctxrnk=0
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 0, err = 995
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=530, ntresnt[1]=995,
> ntresnt[2]=0
> ntt2err: exit
> snttcallback: exit
> nsclose: closing transport
> nttdisc: entry
> nttdisc: Closed socket 328
> nttdisc: exit
> nsclose: global context check-out (from slot 6) complete
> nsmfr: 2176 bytes at 0x3682170
> nsmfr: 1112 bytes at 0x22958a0
> nsglhfre: Deallocating cxd 0x22db1c0.
> nsglhfre: exit
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 3 registered connection(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 4)...
> nsevwait: 0 newly-posted event(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: 0 posted event(s)
> nsevwait: 3 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 4)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 318, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22dab30 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22dab30 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 4
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=4, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 318 bytes from leftover
> nsprecv: tlen=318, plen=318, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 308 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 308 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:1 mld2:49
> nsglgrDoRegister: exit
> nsdo: cid=4, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=318, gtc=318, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 308 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 3 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 4)...
> nsevwait: 1 newly-posted event(s)
> nsevfnt: cxd: 0x22da740 stage 0: NT events set:
>         CONNECTION REQUEST
> nsevfnt: cxd: 0x22da740 stage 0: NS events set:
>         INCOMING CALL
> nsevwait: event is 0x1, on 1
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsmal: 1112 bytes at 0x22958a0
> nsmal: 2176 bytes at 0x36818e0
> nsopen: opening transport...
> nttcon: entry
> nttcon: toc = 3
> nttcnp: entry
> nttcnp: getting sockname
> nttcnp: getting peername
> nttcnp: exit
> nttcnr: entry
> nttcnr: waiting to accept a connection.
> nttcnr: getting sockname
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address x.x.5.20
> snlinGetNameInfo: exit
> nttcnr: connected on ipaddr x.x.5.20
> nttvlser: entry
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address x.x.5.20
> snlinGetNameInfo: exit
> nttvlser: valid node check on incoming node x.x.5.20
> nttvlser: Accepted Entry: x.x.5.20
> nttcnr: exit
> nttcon: set TCP_NODELAY on 328
> nttcon: exit
> nsopen: transport is open
> nsnainit: answer
> nsnasvnainfo: NA disabled for this connection
> nainit: entry
> nagblini: entry
> nau_gin: entry
> nau_gparams: entry
> nam_gbp: Reading parameter "sqlnet.authentication_required" from
> parameter file
> nam_gbp: Parameter not found
> nau_gparams: Using default value "FALSE"
> nau_gslf: entry
> nam_gic: entry
> nam_gic: Counting # of items in "sqlnet.authentication_services"
> parameter
> nam_gic: Parameter not found
> nam_gic: Found 0 items
> nam_gic: exit
> nau_gslf: Using default value "all available adapters"
> nauss_set_state: entry
> nauss_set_state: exit
> nau_gslf: exit
> nau_gparams: exit
> nau_gin: exit
> nagblini: exit
> na_saveprot: entry
> na_saveprot: exit
> nacomin: entry
> nas_init: entry
> nas_init: exit
> nau_ini: entry
> naugcp_get_connect_parameters: entry
> nauss_set_state: entry
> nauss_set_state: exit
> naugcp_get_connect_parameters: exit
> nau_gettab: entry
> nau_gettab: exit
> nau_sini: entry
> nau_sini: exit
> nau_ini: exit
> naeeinit: entry
> nam_gbp: Reading parameter "SQLNET.FIPS_140" from parameter file
> nam_gbp: Parameter not found
> nam_gnsp: Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter
> file
> nam_gnsp: Parameter not found
> naequad: Using default value "ACCEPTED"
> nam_gic: entry
> nam_gic: Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER"
> parameter
> nam_gic: Parameter not found
> nam_gic: exit
> naesno: Using default value "all available algorithms"
> naeshow: entry
> naeshow: exit
> naeeinit: exit
> naecinit: entry
> nam_gnsp: Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from
> parameter file
> nam_gnsp: Parameter not found
> naequad: Using default value "ACCEPTED"
> nam_gic: entry
> nam_gic: Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER"
> parameter
> nam_gic: Parameter not found
> nam_gic: exit
> naesno: Using default value "all available algorithms"
> naeshow: entry
> naeshow: exit
> naecinit: exit
> nadisc: entry
> nacomtm: entry
> nacompd: entry
> nacompd: exit
> nacompd: entry
> nacompd: exit
> nacomtm: exit
> nas_dis: entry
> nas_dis: exit
> nau_dis: entry
> nau_dis: exit
> naeetrm: entry
> naeetrm: exit
> naectrm: entry
> naectrm: exit
> nagbltrm: entry
> nau_gtm: entry
> nau_gtm: exit
> nagbltrm: exit
> nadisc: exit
> nainit: exit
> nsnainit: NS Connection version: 313
> nsnainit: inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd
> nsnainit: "or" info flags: 0xd        Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
>
> nsnainit: "or" info flags: 0xd        Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
> "and" info flags: 0xd Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
>
> nsopen: global context check-in (to slot 5) complete
> nsopen: lcl[0]=0xf4ffefff, lcl[1]=0x12003, gbl[0]=0x7abf,
> gbl[1]=0x2001, tdu=32767, sdu=8192
> nsdo: cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=7, flg=0x4000, mvd=0
> nsdo: nsctxrnk=0
> nsanswer: deferring connect attempt; at stage 5
> nsevreg: begin registration process for 5
> nsevreg: sgt=0, evn=4, evt[2]=0x0
> nsevreg: begin notification process for 5
> nsrah: setting transport read mode (2)
> nttctl: entry
> nttctl: Clearing non-blocking mode
> nttctl: entry
> nttctl: Setting connection into async mode
> nsevreg: rdm=2, sgt=0, evt[0]=0x20, [1]=0x20, [2]=0x0, nrg=0
> nsevreg: registering for 0x20
> nttmrd: entry
> nttrd: entry
> snttcallback: entry
> snttcallback: exit
> nttrd: socket 328 had bytes read=104
> nttrd: exit
> nsglhe: exit
> nsevwait: 4 registered connection(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 5)...
> nsevwait: 1 newly-posted event(s)
> nsevwait: 1 pre-posted event(s)
> nsevdansw: at STAGE 5
> nsdo: cid=5, opcode=68, *bl=2040, *what=8, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=14, flg=0x4004, mvd=0
> nsdo: gtn=10, gtc=10, ptn=10, ptc=8155
> nscon: doing connect handshake...
> nscon: recving a packet
> nsprecv: 104 bytes from leftover
> nsprecv: tlen=104, plen=104, type=1
> nscon: got NSPTCN packet
> nsconneg: vsn=313, lov=300, opt=0x0, sdu=8192, tdu=32767, ntc=0xc60e
> nsconneg: vsn=313, gbl=0x1, sdu=8192, tdu=32767
> nscon: got 46 bytes connect data
> nsdo: nsctxrnk=0
> nsevdansw: exit
> nsevwait: event is 0x20, on 5
> nsevfnt: cxd: 0x22da740 stage 0: NT events set:
>         CONNECTION REQUEST
> nsevfnt: cxd: 0x22da740 stage 0: NS events set:
>         INCOMING CALL
> nsevwait: event is 0x1, on 1
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglfc: command = service_register_NSGR
> nsglauthorized: entry
> nsglrespond: entry
> nsdo: cid=5, opcode=67, *bl=20, *what=9, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=2, flg=0x4004, mvd=0
> nsdo: gtn=104, gtc=104, ptn=10, ptc=8155
> nscon: sending NSPTAC packet
> nspsend: plen=52, type=2
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=52
> nttwr: exit
> nspsend: 52 bytes to transport
> nscon: connect handshake is complete
> nscon: nsctxinf[0]=0x6d, [1]=0x9
> nsconbrok: asking transport to enable NTOBROKEN
> nttctl: entry
> nsdo: nsctxrnk=0
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsglrespond: exit
> nsglhe: exit
> nsglhe: entry
> nsmal: 1112 bytes at 0x2295d30
> nsmal: 2176 bytes at 0x3682170
> nsopen: opening transport...
> nttcon: entry
> nttcon: toc = 3
> nttcnp: entry
> nttcnp: getting sockname
> nttcnp: getting peername
> nttcnp: exit
> nttcnr: entry
> nttcnr: waiting to accept a connection.
> nttcnr: getting sockname
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address x.x.5.10
> snlinGetNameInfo: exit
> nttcnr: connected on ipaddr x.x.5.10
> nttvlser: entry
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address x.x.5.10
> snlinGetNameInfo: exit
> nttvlser: valid node check on incoming node x.x.5.10
> nttvlser: Accepted Entry: x.x.5.10
> nttcnr: exit
> nttcon: set TCP_NODELAY on 320
> nttcon: exit
> nsopen: transport is open
> nsnainit: answer
> nsnasvnainfo: NA disabled for this connection
> nainit: entry
> nagblini: entry
> nau_gin: entry
> nau_gparams: entry
> nam_gbp: Reading parameter "sqlnet.authentication_required" from
> parameter file
> nam_gbp: Parameter not found
> nau_gparams: Using default value "FALSE"
> nau_gslf: entry
> nam_gic: entry
> nam_gic: Counting # of items in "sqlnet.authentication_services"
> parameter
> nam_gic: Parameter not found
> nam_gic: Found 0 items
> nam_gic: exit
> nau_gslf: Using default value "all available adapters"
> nauss_set_state: entry
> nauss_set_state: exit
> nau_gslf: exit
> nau_gparams: exit
> nau_gin: exit
> nagblini: exit
> na_saveprot: entry
> na_saveprot: exit
> nacomin: entry
> nas_init: entry
> nas_init: exit
> nau_ini: entry
> naugcp_get_connect_parameters: entry
> nauss_set_state: entry
> nauss_set_state: exit
> naugcp_get_connect_parameters: exit
> nau_gettab: entry
> nau_gettab: exit
> nau_sini: entry
> nau_sini: exit
> nau_ini: exit
> naeeinit: entry
> nam_gbp: Reading parameter "SQLNET.FIPS_140" from parameter file
> nam_gbp: Parameter not found
> nam_gnsp: Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter
> file
> nam_gnsp: Parameter not found
> naequad: Using default value "ACCEPTED"
> nam_gic: entry
> nam_gic: Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER"
> parameter
> nam_gic: Parameter not found
> nam_gic: exit
> naesno: Using default value "all available algorithms"
> naeshow: entry
> naeshow: exit
> naeeinit: exit
> naecinit: entry
> nam_gnsp: Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from
> parameter file
> nam_gnsp: Parameter not found
> naequad: Using default value "ACCEPTED"
> nam_gic: entry
> nam_gic: Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER"
> parameter
> nam_gic: Parameter not found
> nam_gic: exit
> naesno: Using default value "all available algorithms"
> naeshow: entry
> naeshow: exit
> naecinit: exit
> nadisc: entry
> nacomtm: entry
> nacompd: entry
> nacompd: exit
> nacompd: entry
> nacompd: exit
> nacomtm: exit
> nas_dis: entry
> nas_dis: exit
> nau_dis: entry
> nau_dis: exit
> naeetrm: entry
> naeetrm: exit
> naectrm: entry
> naectrm: exit
> nagbltrm: entry
> nau_gtm: entry
> nau_gtm: exit
> nagbltrm: exit
> nadisc: exit
> nainit: exit
> nsnainit: NS Connection version: 313
> nsnainit: inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd
> nsnainit: "or" info flags: 0xd        Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
>
> nsnainit: "or" info flags: 0xd        Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
> "and" info flags: 0xd Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
>
> nsopen: global context check-in (to slot 6) complete
> nsopen: lcl[0]=0xf4ffefff, lcl[1]=0x12003, gbl[0]=0x7abf,
> gbl[1]=0x2001, tdu=32767, sdu=8192
> nsdo: cid=6, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=7, flg=0x4000, mvd=0
> nsdo: nsctxrnk=0
> nsanswer: deferring connect attempt; at stage 5
> nsevreg: begin registration process for 6
> nsevreg: sgt=0, evn=4, evt[2]=0x0
> nsevreg: begin notification process for 6
> nsrah: setting transport read mode (2)
> nttctl: entry
> nttctl: Clearing non-blocking mode
> nttctl: entry
> nttctl: Setting connection into async mode
> nsevreg: rdm=2, sgt=0, evt[0]=0x20, [1]=0x20, [2]=0x0, nrg=0
> nsevreg: registering for 0x20
> nttmrd: entry
> nttrd: entry
> snttcallback: entry
> snttcallback: op = 5, bytes = 910, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: exit
> nttrd: socket 320 had bytes read=104
> nttrd: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 2 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> nsevwait: 0 newly-posted event(s)
> nsevwait: 3 pre-posted event(s)
> nsevdansw: at STAGE 5
> nsdo: cid=6, opcode=68, *bl=2040, *what=8, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=14, flg=0x4004, mvd=0
> nsdo: gtn=10, gtc=10, ptn=10, ptc=8155
> nscon: doing connect handshake...
> nscon: recving a packet
> nsprecv: 104 bytes from leftover
> nsprecv: tlen=104, plen=104, type=1
> nscon: got NSPTCN packet
> nsconneg: vsn=313, lov=300, opt=0x0, sdu=8192, tdu=32767, ntc=0xc60e
> nsconneg: vsn=313, gbl=0x1, sdu=8192, tdu=32767
> nscon: got 46 bytes connect data
> nsdo: nsctxrnk=0
> nsevdansw: exit
> nsevwait: event is 0x20, on 6
> nsevfnt: cxd: 0x22db1c0 stage 7: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 7: NS events set:
>         OUTGOING CALL COMPLETE
> nsevdansw: at STAGE 7
> nsevdansw: no NA services in use
> nsevunreg: cid=5, sgt=0, rdm=2
> nsrah: re-setting transport read mode
> nttctl: entry
> nttctl: Clearing async mode
> nsevunreg: 4 registered connection(s)
> nsevdansw: exit
> nsevwait: event is 0x20, on 5
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglfc: command = service_register_NSGR
> nsglauthorized: entry
> nsglrespond: entry
> nsdo: cid=6, opcode=67, *bl=20, *what=9, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=2, flg=0x4004, mvd=0
> nsdo: gtn=104, gtc=104, ptn=10, ptc=8155
> nscon: sending NSPTAC packet
> nspsend: plen=52, type=2
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 320 had bytes written=52
> nttwr: exit
> nspsend: 52 bytes to transport
> nscon: connect handshake is complete
> nscon: nsctxinf[0]=0x6d, [1]=0x9
> nsconbrok: asking transport to enable NTOBROKEN
> nttctl: entry
> nsdo: nsctxrnk=0
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsglrespond: exit
> nsglhe: exit
> nsglhe: entry
> nsevunreg: cid=5, sgt=0, rdm=0
> nsevreg: begin registration process for 5
> nsevregPrePost: NSVDATA already available
> nsevreg: sgt=0, evn=4, evt[2]=0x2
> nsevreg: begin notification process for 5
> nsrah: setting transport read mode (2)
> nttctl: entry
> nttctl: Setting connection into async mode
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsevreg: rdm=2, sgt=0, evt[0]=0x12, [1]=0x10, [2]=0x2, nrg=0
> nsevreg: registering for 0x12
> nsglauthorized: entry
> nsglecmd: entry
> nsglhcrt: entry
> nsglhcrt: exit
> nsglhins: entry
> nsglhins: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> nsevwait: 1 newly-posted event(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: event is 0x12, on 5
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         WRITE
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         READY FOR SEND
> nsevwait: event is 0x12, on 5
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsevmute: cid=5
> nsglroinit: entry
> nsglroinit: exit
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=104, gtc=104, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 910 bytes from leftover
> nsprecv: tlen=910, plen=910, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 900 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: Creating new instance: "edwprd1".
> nttctl: entry
> nscontrol: cmd=7, lcl=0x0
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:0 mld2:555
> nsglgrDoRegister: instance flags - req:256 cur:272
> nsglgrDoRegister: Creating new service: "EDWPRD_XPT".
> nsglgrDoRegister: service:EDWPRD_XPT flag:2 goodness:0 delta:0
> nsglgrDoRegister: Creating new service: "EDWPRD".
> nsglgrDoRegister: service:EDWPRD flag:2 goodness:0 delta:0
> nsglgrDoRegister: Creating new handler: "DEDICATED".
> nsglhcrt: entry
> nsglhcrt: exit
> nsglbgetRSPidx: entry
> nladini: entry
> nladini: exit
> nladget: entry
> nladget: exit
> nsglbn2i: entry
> nsglbn2i: exit
> nladtrm: entry
> nladtrm: exit
> nsglbgetRSPidx: returning ecode=0
> nsglbgetRSPidx: exit
> nsglhins: entry
> nsglhins: exit
> nsglgrDoRegister: exit
> nsdo: cid=5, opcode=67, *bl=336, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=910, gtc=910, ptn=10, ptc=8155
> nsdo: 336 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=346, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: op = 5, bytes = 974, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=346
> nttwr: exit
> nspsend: 346 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> nsevwait: 0 newly-posted event(s)
> nsevwait: 1 pre-posted event(s)
> nsevfnt: cxd: 0x22dadd0 stage 7: NT events set:
>         READ
> nsevfnt: cxd: 0x22dadd0 stage 7: NS events set:
>         OUTGOING CALL COMPLETE
> nsevdansw: at STAGE 7
> nsevdansw: no NA services in use
> nsevunreg: cid=6, sgt=0, rdm=2
> nsrah: re-setting transport read mode
> nttctl: entry
> nttctl: Clearing async mode
> nsevunreg: 4 registered connection(s)
> nsevdansw: exit
> nsevwait: event is 0x20, on 6
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsevunreg: cid=6, sgt=0, rdm=0
> nsevreg: begin registration process for 6
> nsevregPrePost: NSVDATA already available
> nsevreg: sgt=0, evn=4, evt[2]=0x2
> nsevreg: begin notification process for 6
> nsrah: setting transport read mode (2)
> nttctl: entry
> nttctl: Setting connection into async mode
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsevreg: rdm=2, sgt=0, evt[0]=0x12, [1]=0x10, [2]=0x2, nrg=0
> nsevreg: registering for 0x12
> nsglauthorized: entry
> nsglecmd: entry
> nsglhcrt: entry
> nsglhcrt: exit
> nsglhins: entry
> nsglhins: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> nsevwait: 1 newly-posted event(s)
> nsevwait: 1 pre-posted event(s)
> nsevwait: event is 0x12, on 6
> nsevfnt: cxd: 0x22dadd0 stage 0: NT events set:
>         WRITE
> nsevfnt: cxd: 0x22dadd0 stage 0: NS events set:
>         READY FOR SEND
> nsevwait: event is 0x12, on 6
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsevmute: cid=6
> nsglroinit: entry
> nsglroinit: exit
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=104, gtc=104, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 974 bytes from leftover
> nsprecv: tlen=974, plen=974, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 964 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:0 mld2:555
> nsglgrDoRegister: instance flags - req:256 cur:272
> nsglgrDoRegister: service:EDWPRD_XPT flag:2 goodness:0 delta:0
> nsglgrDoRegister: service:EDWPRD flag:2 goodness:0 delta:0
> nsglgrDoRegister: Creating new handler: "DEDICATED".
> nsglhcrt: entry
> nsglhcrt: exit
> nsglbgetRSPidx: entry
> nladini: entry
> nladini: exit
> nladget: entry
> nladget: exit
> nsglbn2i: entry
> nsglbn2i: exit
> nladtrm: entry
> nladtrm: exit
> nsglbgetRSPidx: returning ecode=0
> nsglbgetRSPidx: exit
> nsglhins: entry
> nsglhins: exit
> nsglgrDoRegister: exit
> nsdo: cid=6, opcode=67, *bl=336, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=974, gtc=974, ptn=10, ptc=8155
> nsdo: 336 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=346, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 320 had bytes written=346
> nttwr: exit
> nspsend: 346 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 350, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 350, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 5
> nsevfnt: cxd: 0x22dadd0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22dadd0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 6
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=910, gtc=910, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 350 bytes from leftover
> nsprecv: tlen=350, plen=350, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 340 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: instance flags - req:0 cur:16
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:4 mld2:555
> nsglgrDoRegister: exit
> nsdo: cid=5, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=974, gtc=974, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 350 bytes from leftover
> nsprecv: tlen=350, plen=350, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 340 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: instance flags - req:0 cur:16
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:4 mld2:555
> nsglgrDoRegister: exit
> nsdo: cid=6, opcode=67, *bl=168, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsdo: 168 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=178, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 320 had bytes written=178
> nttwr: exit
> nspsend: 178 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 310, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 310, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 5
> nsevfnt: cxd: 0x22dadd0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22dadd0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 6
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 310 bytes from leftover
> nsprecv: tlen=310, plen=310, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 300 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: service:EDWPRD what:4 value:100
> nsglgrDoRegister: exit
> nsdo: cid=5, opcode=67, *bl=160, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=310, gtc=310, ptn=10, ptc=8155
> nsdo: 160 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=170, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=170
> nttwr: exit
> nspsend: 170 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=350, gtc=350, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 310 bytes from leftover
> nsprecv: tlen=310, plen=310, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 300 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: service:EDWPRD what:4 value:100
> nsglgrDoRegister: exit
> nsdo: cid=6, opcode=67, *bl=160, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=310, gtc=310, ptn=10, ptc=8155
> nsdo: 160 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=170, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 320 had bytes written=170
> nttwr: exit
> nspsend: 170 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 574, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 574, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 5
> nsevfnt: cxd: 0x22dadd0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22dadd0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 6
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=310, gtc=310, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 574 bytes from leftover
> nsprecv: tlen=574, plen=574, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 564 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: Creating new service: "EDWPRDXDB".
> nsglgrDoRegister: service:EDWPRDXDB flag:3 goodness:0 delta:100
> nsglgrDoRegister: Creating new handler: "D000".
> nsglhcrt: entry
> nsglhcrt: exit
> nsglbgetRSPidx: entry
> nladini: entry
> nladini: exit
> nladget: entry
> nladget: exit
> nsglbn2i: entry
> nsglbn2i: exit
> nladtrm: entry
> nladtrm: exit
> nsglbgetRSPidx: returning ecode=0
> nsglbgetRSPidx: exit
> nsmal: 1112 bytes at 0x22961c0
> nsmal: 2176 bytes at 0x3682a00
> nsopen: opening transport...
> nttcon: entry
> nttcon: toc = 6
> nttcnp: entry
> nttcnp: exit
> snlinGetAddrInfo: entry
> snlinGetAddrInfo: exit
> snlinFreeAddrInfo: entry
> snlinFreeAddrInfo: exit
> nttcon: exit
> nsopen: transport is open
> nsoptions: lcl[0]=0x0, lcl[1]=0x0, gbl[0]=0x0, gbl[1]=0x0, cha=0x0
> nsoptions: lcl[0]=0xf4ffefff, lcl[1]=0x0, gbl[0]=0x7abf, gbl[1]=0x0
> nsopen: lcl[0]=0xf4ffefff, lcl[1]=0x0, gbl[0]=0x7abf, gbl[1]=0x0,
> tdu=32767, sdu=2048
> nsmfr: 2176 bytes at 0x3682a00
> nsmfr: 1112 bytes at 0x22961c0
> nsglgrDoRegister: will do TPSWITCH for this hdlr
> nsglhins: entry
> nsglhins: exit
> nsglgrDoRegister: exit
> nsdo: cid=5, opcode=67, *bl=164, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=574, gtc=574, ptn=10, ptc=8155
> nsdo: 164 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=174, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=174
> nttwr: exit
> nspsend: 174 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=310, gtc=310, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 574 bytes from leftover
> nsprecv: tlen=574, plen=574, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 564 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: service:EDWPRDXDB flag:3 goodness:0 delta:100
> nsglgrDoRegister: exit
> nsdo: cid=6, opcode=67, *bl=164, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=574, gtc=574, ptn=10, ptc=8155
> nsdo: 164 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=174, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 320 had bytes written=174
> nttwr: exit
> nspsend: 174 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 514, err = 0
> snttcallback: exit
> snttcallback: entry
> snttcallback: op = 5, bytes = 514, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22db1c0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22db1c0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 5
> nsevfnt: cxd: 0x22dadd0 stage 0: NT events set:
>         READ
> nsevfnt: cxd: 0x22dadd0 stage 0: NS events set:
>         INCOMING SEND
> nsevwait: event is 0x2, on 6
> nsevwait: 2 posted event(s)
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=574, gtc=574, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 514 bytes from leftover
> nsprecv: tlen=514, plen=514, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 328 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 504 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:5 mld2:555
> nsglgrDoRegister: service:EDWPRDXDB what:4 value:1
> nsglgrDoRegister: service:EDWPRD what:4 value:1
> nsglgrDoRegister: exit
> nsdo: cid=5, opcode=67, *bl=300, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=514, gtc=514, ptn=10, ptc=8155
> nsdo: 300 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=310, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 328 had bytes written=310
> nttwr: exit
> nspsend: 310 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsglhe: entry
> nsglrohe: entry
> nsdo: cid=6, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=574, gtc=574, ptn=10, ptc=8155
> nsrdr: recving a packet
> nsprecv: 514 bytes from leftover
> nsprecv: tlen=514, plen=514, type=6
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 320 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsrdr: got NSPTDA packet
> nsrdr: NSPTDA flags: 0x0
> nsdo: *what=1, *bl=8196
> nsdo: 504 bytes from NS buffer
> nsdo: nsctxrnk=0
> nsglgrDoRegister: inst loads: ld1:10 mld1:800 ld2:5 mld2:555
> nsglgrDoRegister: service:EDWPRDXDB what:4 value:1
> nsglgrDoRegister: service:EDWPRD what:4 value:1
> nsglgrDoRegister: exit
> nsdo: cid=6, opcode=67, *bl=300, *what=1, uflgs=0x2, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x400c, mvd=0
> nsdo: gtn=514, gtc=514, ptn=10, ptc=8155
> nsdo: 300 bytes to NS buffer
> nsdofls: DATA flags: 0x0
> nsdofls: sending NSPTDA packet
> nspsend: plen=310, type=6
> nttmwr: entry
> nttwr: entry
> snttcallback: entry
> snttcallback: exit
> nttwr: socket 320 had bytes written=310
> nttwr: exit
> nspsend: 310 bytes to transport
> nsdoacts: flushing transport
> nttctl: entry
> nsdo: nsctxrnk=0
> nsglroHandleError: exit
> nsglrohe: exit
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...
> nsevwait: 1 newly-posted event(s)
> nsevfnt: cxd: 0x22da740 stage 0: NT events set:
>         CONNECTION REQUEST
> nsevfnt: cxd: 0x22da740 stage 0: NS events set:
>         INCOMING CALL
> nsevwait: event is 0x1, on 1
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsmal: 1112 bytes at 0x22961c0
> nsmal: 2176 bytes at 0x3682a00
> nsopen: opening transport...
> nttcon: entry
> nttcon: toc = 3
> nttcnp: entry
> nttcnp: getting sockname
> nttcnp: getting peername
> nttcnp: exit
> nttcnr: entry
> nttcnr: waiting to accept a connection.
> nttcnr: getting sockname
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address 127.0.0.1
> snlinGetNameInfo: exit
> nttcnr: connected on ipaddr 127.0.0.1
> nttvlser: entry
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address 127.0.0.1
> snlinGetNameInfo: exit
> nttvlser: valid node check on incoming node 127.0.0.1
> nttvlser: Accepted Entry: 127.0.0.1
> nttcnr: exit
> nttcon: set TCP_NODELAY on 480
> nttcon: exit
> nsopen: transport is open
> nsnainit: answer
> nsnasvnainfo: NA disabled for this connection
> nainit: entry
> nagblini: entry
> nau_gin: entry
> nau_gparams: entry
> nam_gbp: Reading parameter "sqlnet.authentication_required" from
> parameter file
> nam_gbp: Parameter not found
> nau_gparams: Using default value "FALSE"
> nau_gslf: entry
> nam_gic: entry
> nam_gic: Counting # of items in "sqlnet.authentication_services"
> parameter
> nam_gic: Parameter not found
> nam_gic: Found 0 items
> nam_gic: exit
> nau_gslf: Using default value "all available adapters"
> nauss_set_state: entry
> nauss_set_state: exit
> nau_gslf: exit
> nau_gparams: exit
> nau_gin: exit
> nagblini: exit
> na_saveprot: entry
> na_saveprot: exit
> nacomin: entry
> nas_init: entry
> nas_init: exit
> nau_ini: entry
> naugcp_get_connect_parameters: entry
> nauss_set_state: entry
> nauss_set_state: exit
> naugcp_get_connect_parameters: exit
> nau_gettab: entry
> nau_gettab: exit
> nau_sini: entry
> nau_sini: exit
> nau_ini: exit
> naeeinit: entry
> nam_gbp: Reading parameter "SQLNET.FIPS_140" from parameter file
> nam_gbp: Parameter not found
> nam_gnsp: Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter
> file
> nam_gnsp: Parameter not found
> naequad: Using default value "ACCEPTED"
> nam_gic: entry
> nam_gic: Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER"
> parameter
> nam_gic: Parameter not found
> nam_gic: exit
> naesno: Using default value "all available algorithms"
> naeshow: entry
> naeshow: exit
> naeeinit: exit
> naecinit: entry
> nam_gnsp: Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from
> parameter file
> nam_gnsp: Parameter not found
> naequad: Using default value "ACCEPTED"
> nam_gic: entry
> nam_gic: Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER"
> parameter
> nam_gic: Parameter not found
> nam_gic: exit
> naesno: Using default value "all available algorithms"
> naeshow: entry
> naeshow: exit
> naecinit: exit
> nadisc: entry
> nacomtm: entry
> nacompd: entry
> nacompd: exit
> nacompd: entry
> nacompd: exit
> nacomtm: exit
> nas_dis: entry
> nas_dis: exit
> nau_dis: entry
> nau_dis: exit
> naeetrm: entry
> naeetrm: exit
> naectrm: entry
> naectrm: exit
> nagbltrm: entry
> nau_gtm: entry
> nau_gtm: exit
> nagbltrm: exit
> nadisc: exit
> nainit: exit
> nsnainit: NS Connection version: 313
> nsnainit: inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd
> nsnainit: "or" info flags: 0xd        Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
>
> nsnainit: "or" info flags: 0xd        Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
> "and" info flags: 0xd Translations follow:
>         native service(s) is (are) wanted
>         NA disabled remotely for this connection
>         NA services unavailable on both processes - negotiation not needed
>
> nsopen: global context check-in (to slot 7) complete
> nsopen: lcl[0]=0xf4ffefff, lcl[1]=0x12003, gbl[0]=0x7abf,
> gbl[1]=0x2001, tdu=32767, sdu=8192
> nsdo: cid=7, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=7, flg=0x4000, mvd=0
> nsdo: nsctxrnk=0
> nsanswer: deferring connect attempt; at stage 5
> nsevreg: begin registration process for 7
> nsevreg: sgt=0, evn=4, evt[2]=0x0
> nsevreg: begin notification process for 7
> nsrah: setting transport read mode (2)
> nttctl: entry
> nttctl: Clearing non-blocking mode
> nttctl: entry
> nttctl: Setting connection into async mode
> nsevreg: rdm=2, sgt=0, evt[0]=0x20, [1]=0x20, [2]=0x0, nrg=0
> nsevreg: registering for 0x20
> nttmrd: entry
> nttrd: entry
> ntt2err: entry
> ntt2err: soc 480 error - operation=5, ntresnt[0]=524, ntresnt[1]=997,
> ntresnt[2]=0
> ntt2err: exit
> nttrd: exit
> nsglhe: exit
> nsevwait: 6 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 7)...
> snttcallback: entry
> snttcallback: op = 5, bytes = 173, err = 0
> snttcallback: exit
> nsevwait: 0 newly-posted event(s)
> nsevfnt: cxd: 0x22db310 stage 5: NT events set:
>         READ
> nsevfnt: cxd: 0x22db310 stage 5: NS events set:
>         OUTGOING CALL COMPLETE
> nsevdansw: at STAGE 5
> nsdo: cid=7, opcode=68, *bl=2040, *what=8, uflgs=0x0, cflgs=0x3
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=14, flg=0x4004, mvd=0
> nsdo: gtn=10, gtc=10, ptn=10, ptc=8155
> nscon: doing connect handshake...
> nscon: recving a packet
> nsprecv: 173 bytes from leftover
> nsprecv: tlen=173, plen=173, type=1
> nscon: got NSPTCN packet
> nsconneg: vsn=308, lov=300, opt=0x0, sdu=2048, tdu=32767, ntc=0x4f98
> nsconneg: vsn=308, gbl=0x1, sdu=2048, tdu=32767
> nscon: got 139 bytes connect data
> nsdo: nsctxrnk=0
> nsevdansw: exit
> nsevwait: event is 0x20, on 7
> nsevwait: 1 posted event(s)
> nsglhe: entry
> nsglfc: Assume establishing a connection...
> nsglfc: command = establish
> nsglauthorized: entry
> nsglecmd: entry
> nttaddr2bnd: entry
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address 127.0.0.1
> snlinGetNameInfo: exit
> nttaddr2bnd: exit
> nsglbgetRSPidx: entry
> nsglbgetRSPidx: returning ecode=0
> nsglbgetRSPidx: exit
> nsglb: entry
> nsglb: instance:edwprd1 new lbscore:1
> nsglb: exit
> nsc2addr: (ADDRESS=(PROTOCOL=BEQ)(PROGRAM=D:\oracle\product
> \10.2.0\db_1\bin\oracle.exe)(ARGV0=oracleedwprd1)(ARGS='(LOCAL=NO)'))
> nlpcaini: entry
> nlpcaini: prg = D:\oracle\product\10.2.0\db_1\bin\oracle.exe
> nlpcaini: arg[0] = oracleedwprd1
> nlpcaini: arg[1] = (LOCAL=NO)
> nlpcaini: env[0] = ALLUSERSPROFILE=C:\Documents and Settings\All Users
> nlpcaini: exit
> nsbeqaddr: connecting...
> nsmal: 1112 bytes at 0x2296650
> nsmal: 2176 bytes at 0x3683290
> ntpcon: entry
> ntpcon: toc = 6
> ntpcon: exit
> nsopen: opening transport...
> ntpcon: entry
> ntpcon: toc = 1
> sntpcall: entry
> snlpcss: entry
> snlpcss: Spawn Oracle completed oracle        (LOCAL=NO) edwprd1.
> snlpcss: exit
> sntpcall: Attempting to open pipe \\.\PIPE\ORANTP1090.5C8
> sntpcall: Successfully established pipe 500 to child with 0 retries.
> sntpcall: Attempting to open pipe \\.\PIPE\ORANTP1090.5C8.w
> sntpcall: Successfully established pipe 524 to child with 0 retries.
> sntpcall: exit
> ntpcon: NT layer IPC connection has been established.
> ntpcon: exit
> nsopen: transport is open
> nsoptions: lcl[0]=0x0, lcl[1]=0x2006, gbl[0]=0x0, gbl[1]=0x0, cha=0x0
> nsoptions: lcl[0]=0xf4ffe9ff, lcl[1]=0x6016, gbl[0]=0xe881, gbl[1]=0x0
> nsopen: global context check-in (to slot 8) complete
> nsopen: lcl[0]=0xf4ffe9ff, lcl[1]=0x6016, gbl[0]=0xe881, gbl[1]=0x0,
> tdu=4096, sdu=2048
> nsdo: cid=8, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=7, flg=0x4001, mvd=0
> nsdo: nsctxrnk=0
> nttaddr2bnd: entry
> snlinGetNameInfo: entry
> snlinGetNameInfo: Using numeric form of host's address 127.0.0.1
> snlinGetNameInfo: exit
> nttaddr2bnd: exit
> nsbequeath_stg2: doing connect handshake...
> nsbequeath: doing connect handshake...
> ntpwr: entry
> sntpwrite: entry
> sntpwrite: Attempting to write 4 bytes to handle 500
> sntpwrite: WriteFile returned 4 bytes
> sntpwrite: exit
> ntpwr: exit
> ntpwr: entry
> sntpwrite: entry
> sntpwrite: Attempting to write 60 bytes to handle 500
> sntpwrite: WriteFile returned 60 bytes
> sntpwrite: exit
> ntpwr: exit
> ntpwr: entry
> sntpwrite: entry
> sntpwrite: Attempting to write 8 bytes to handle 500
> sntpwrite: WriteFile returned 8 bytes
> sntpwrite: exit
> ntpwr: exit
> ntprd: entry
> sntpread: entry
> sntpread: Attempting to read 4 bytes from handle 524
> sntpread: ReadFile returned 4 bytes
> sntpread: rc = 0, ntresnt[0] = 0
> sntpread: exit
> ntprd: exit
> ntprd: entry
> sntpread: entry
> sntpread: Attempting to read 4 bytes from handle 524
> sntpread: ReadFile returned 4 bytes
> sntpread: rc = 0, ntresnt[0] = 0
> sntpread: exit
> ntprd: exit
> nsbequeath: NSE=0
> nstimarmed: no timer allocated
> nstoClearTimeout: ATO disabled for ctx=0x0000000002296650
> nstoClearTimeout: STO disabled for ctx=0x0000000002296650
> nstoClearTimeout: RTO disabled for ctx=0x0000000002296650
> nstoClearTimeout: PITO disabled for ctx=0x0000000002296650
> nstoUpdateActive: Active timeout is -1 (see nstotyp)
> ntpctl: entry
> ntpctl: exit
> ntpctl: entry
> ntpctl: exit
> nsdo: cid=8, opcode=98, *bl=0, *what=0, uflgs=0x40, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=8, flg=0x4001, mvd=0
> nsdo: nsctxrnk=0
> nsclose: closing transport
> ntpdisc: entry
> sntpclose: entry
> sntpclose: Closing pipe 524
> sntpclose: Closing pipe 500
> sntpclose: exit
> ntpdisc: exit
> nsclose: global context check-out (from slot 8) complete
> nsmfr: 2176 bytes at 0x3683290
> nsmfr: 1112 bytes at 0x2296650
> nstimarmed: no timer allocated
> nttctl: entry
> nttctl: entry
> nsdo: cid=7, opcode=98, *bl=0, *what=0, uflgs=0x40, cflgs=0x2
> nsdo: rank=64, nsctxrnk=0
> nsdo: nsctx: state=2, flg=0x4000, mvd=0
> nsevunreg: cid=7, sgt=0, rdm=2
> nsrah: re-setting transport read mode
> nttctl: entry
> nttctl: Clearing async mode
> nsevunreg: 5 registered connection(s)
> nsdo: nsctxrnk=0
> nsclose: closing transport
> nttdisc: entry
> nttdisc: Closed socket 480
> nttdisc: exit
> nsclose: global context check-out (from slot 7) complete
> nsmfr: 2176 bytes at 0x3682a00
> nsmfr: 1112 bytes at 0x22961c0
> nsbeqaddr: connect handshake is complete
> nsglhe: exit
> nsevwait: 5 registered connection(s)
> nsevwait: 0 pre-posted event(s)
> nsevwait: waiting for transport event (1 thru 6)...

Did you configure a tnsnames.ora file? Can you successfully use tnsping to verify the listener status at the destination? The ORA-12560 error is a generic error indicating a configuration problem with the tnsnames.ora file (protocol adapter error). My guess is you either do not have a tnsnames.ora file created or it's created improperly.

David Fitzjarrell Received on Wed Dec 26 2007 - 10:05:04 CST