Trace file /opt/oracle/diag/tnslsnr/kl3dbs/listener_2/trace/ora_16099_140251439438912.trc
2019-06-24 06:56:24.072 : nsglrespond:entry
2019-06-24 06:56:24.073 : nsdo:cid=5, opcode=67, *bl=82, *what=1, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:24.073 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:24.073 : nsdo:nsctx: state=8, flg=0x2100400c, mvd=0
2019-06-24 06:56:24.073 : nsdo:gtn=229, gtc=229, ptn=10, ptc=8111
2019-06-24 06:56:24.073 : nsdo:82 bytes to NS buffer
2019-06-24 06:56:24.073 : nsdo:nsctxrnk=0
2019-06-24 06:56:24.073 : nsglrespond:exit
2019-06-24 06:56:24.073 : nsgldissolve:entry
2019-06-24 06:56:24.073 : nsvntx_dei:entry
2019-06-24 06:56:24.073 : nsvntx_dei:exit
2019-06-24 06:56:24.073 : nstimarmed:no timer allocated
2019-06-24 06:56:24.073 : nsfull_cls:cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0
2019-06-24 06:56:24.073 : nsfull_cls:nsctx: state=8, flg=0x21004008, mvd=82
2019-06-24 06:56:24.074 : nsevunreg:cid=5, sgt=0, rdm=0
2019-06-24 06:56:24.074 : nsevunreg:3 registered connection(s)
2019-06-24 06:56:24.074 : nsdo:cid=5, opcode=67, *bl=0, *what=1, uflgs=0x0, cflgs=0x1
2019-06-24 06:56:24.074 : nsdo:nsctx: state=8, flg=0x1004008, mvd=82
2019-06-24 06:56:24.074 : nsdo:gtn=229, gtc=229, ptn=92, ptc=8111
2019-06-24 06:56:24.074 : nsdofls:DATA flags: 0x40
2019-06-24 06:56:24.074 : nsdofls:sending NSPTDA packet
2019-06-24 06:56:24.074 : nspsend:plen=92, type=6
2019-06-24 06:56:24.074 : ntuswrte:entry
2019-06-24 06:56:24.074 : ntuswrte:Wrote on 14 bytes 92
2019-06-24 06:56:24.074 : ntuswrte:exit
2019-06-24 06:56:24.074 : nspsend:92 bytes to transport
2019-06-24 06:56:24.074 : nsclose:closing transport
2019-06-24 06:56:24.074 : ntusdisc:entry
2019-06-24 06:56:24.074 : ntusdisc:Closed socket 14
2019-06-24 06:56:24.074 : ntusdisc:exit
2019-06-24 06:56:24.074 : nsclose:global context check-out (from slot 5) complete
2019-06-24 06:56:24.074 : nsvntx_dei:entry
2019-06-24 06:56:24.074 : nsvntx_dei:exit
2019-06-24 06:56:24.074 : nsmfr:3368 bytes at 0xceea80
2019-06-24 06:56:24.074 : nsmfr:1952 bytes at 0xd7c440
2019-06-24 06:56:24.074 : nsgldissolve:Deallocating cxd 0xd1ca50.
2019-06-24 06:56:24.074 : nsgldissolve:exit
2019-06-24 06:56:24.075 : nsevwait:3 registered connection(s)
2019-06-24 06:56:24.075 : nsevwait:0 pre-posted event(s)
2019-06-24 06:56:24.075 : nsevwait:waiting for transport event (1 thru 4)...
2019-06-24 06:56:31.167 : nsevwait:0 newly-posted event(s)
2019-06-24 06:56:31.167 : nsgetaddr:entry
2019-06-24 06:56:31.167 : nttaddr2bnd:entry
2019-06-24 06:56:31.167 : snlinGetNameInfo:entry
2019-06-24 06:56:31.167 : snlinGetNameInfo:exit
2019-06-24 06:56:31.167 : nttaddr2bnd:exit
2019-06-24 06:56:31.167 : nsevfnt:cxd: 0xccc6d0 cid=1 stage 0: NT events set:
	CONNECTION REQUEST
2019-06-24 06:56:31.167 : nsgetaddr:entry
2019-06-24 06:56:31.167 : nttaddr2bnd:entry
2019-06-24 06:56:31.167 : snlinGetNameInfo:entry
2019-06-24 06:56:31.167 : snlinGetNameInfo:exit
2019-06-24 06:56:31.167 : nttaddr2bnd:exit
2019-06-24 06:56:31.167 : nsevfnt:cxd: 0xccc6d0 cid=1 stage 0: NS events set:
	INCOMING CALL
2019-06-24 06:56:31.167 : nsevrec:event is 0x1, on 1
2019-06-24 06:56:31.167 : nsevwait:1 pre-posted event(s)
2019-06-24 06:56:31.167 : nsevrec:event is 0x1, on 1
2019-06-24 06:56:31.168 : nsevwait:1 posted event(s)
2019-06-24 06:56:31.168 : nsglhe:entry
2019-06-24 06:56:31.168 : nsmal:1952 bytes at 0xd7c440
2019-06-24 06:56:31.168 : nsmal:3368 bytes at 0xceea80
2019-06-24 06:56:31.168 : nstoSetupTimeout:ATO enabled for ctx=0x0xd7c440, val=60000(millisecs)
2019-06-24 06:56:31.168 : nstoUpdateActive:Active timeout is 0 (see nstotyp)
2019-06-24 06:56:31.168 : nsopen:opening transport...
2019-06-24 06:56:31.168 : nttcon:entry
2019-06-24 06:56:31.168 : nttcon:toc = 3
2019-06-24 06:56:31.168 : nttcnp:entry
2019-06-24 06:56:31.168 : nttcnp:getting sockname
2019-06-24 06:56:31.168 : nttcnp:getting peername
2019-06-24 06:56:31.168 : nttcnp:exit
2019-06-24 06:56:31.168 : nttcnr:entry
2019-06-24 06:56:31.168 : nttcnr:waiting to accept a connection.
2019-06-24 06:56:31.168 : nttcnr:getting sockname
2019-06-24 06:56:31.168 : snlinGetNameInfo:entry
2019-06-24 06:56:31.168 : snlinGetNameInfo:exit
2019-06-24 06:56:31.168 : nttcnr:connected on source ipaddr 192.168.110.74 port 2524
2019-06-24 06:56:31.168 : snlinGetNameInfo:entry
2019-06-24 06:56:31.168 : snlinGetNameInfo:exit
2019-06-24 06:56:31.168 : nttcnr:connected on destination ipaddr 192.168.110.74 port 36565
2019-06-24 06:56:31.168 : nttvlser:entry
2019-06-24 06:56:31.168 : nlvlsern:entry
2019-06-24 06:56:31.168 : snlinGetNameInfo:entry
2019-06-24 06:56:31.168 : snlinGetNameInfo:exit
2019-06-24 06:56:31.169 : nttvlser:valid node check on incoming node 192.168.110.74
2019-06-24 06:56:31.169 : nttvlser:Accepted Entry: 192.168.110.74
2019-06-24 06:56:31.169 : nttcnr:exit
2019-06-24 06:56:31.169 : nttctl:entry
2019-06-24 06:56:31.169 : nttctl:Setting connection into nodelay mode
2019-06-24 06:56:31.169 : nttctl:set TCP_NODELAY on 14
2019-06-24 06:56:31.169 : nttcon:exit
2019-06-24 06:56:31.169 : nsopen:transport is open
2019-06-24 06:56:31.169 : nsnainit:answer
2019-06-24 06:56:31.169 : nsnasvnainfo:NA disabled for this connection
2019-06-24 06:56:31.169 : nainit:entry
2019-06-24 06:56:31.169 : nagblini:entry
2019-06-24 06:56:31.169 : nau_gin:entry
2019-06-24 06:56:31.169 : nau_gparams:entry
2019-06-24 06:56:31.169 : nam_gbp:Reading parameter "sqlnet.authentication_required" from parameter file
2019-06-24 06:56:31.169 : nam_gbp:Parameter not found
2019-06-24 06:56:31.169 : nau_gparams:Using default value "FALSE"
2019-06-24 06:56:31.169 : nau_gslf:entry
2019-06-24 06:56:31.169 : nam_gic:entry
2019-06-24 06:56:31.169 : nam_gic:Counting # of items in "sqlnet.authentication_services" parameter
2019-06-24 06:56:31.169 : nam_gic:Parameter not found
2019-06-24 06:56:31.169 : nam_gic:Found 0 items
2019-06-24 06:56:31.169 : nam_gic:exit
2019-06-24 06:56:31.169 : nau_gslf:Using default value "all available adapters"
2019-06-24 06:56:31.169 : nauss_set_state:entry
2019-06-24 06:56:31.169 : nauss_set_state:exit
2019-06-24 06:56:31.169 : nau_gslf:exit
2019-06-24 06:56:31.169 : nau_gparams:exit
2019-06-24 06:56:31.169 : nau_gin:exit
2019-06-24 06:56:31.169 : nagblini:exit
2019-06-24 06:56:31.170 : na_saveprot:entry
2019-06-24 06:56:31.170 : na_saveprot:exit
2019-06-24 06:56:31.170 : nacomin:entry
2019-06-24 06:56:31.170 : nas_init:entry
2019-06-24 06:56:31.170 : nas_init:exit
2019-06-24 06:56:31.170 : nau_ini:entry
2019-06-24 06:56:31.170 : naugcp_get_connect_parameters:entry
2019-06-24 06:56:31.170 : nauss_set_state:entry
2019-06-24 06:56:31.170 : nauss_set_state:exit
2019-06-24 06:56:31.170 : naugcp_get_connect_parameters:exit
2019-06-24 06:56:31.170 : nau_gfauth:entry
2019-06-24 06:56:31.170 : nam_gbp:Reading parameter "sqlnet.fallback_authentication" from parameter file
2019-06-24 06:56:31.170 : nam_gbp:Parameter not found
2019-06-24 06:56:31.170 : nau_gfauth:Using default value "FALSE"
2019-06-24 06:56:31.170 : nau_gfauth:exit
2019-06-24 06:56:31.170 : nau_gettab:entry
2019-06-24 06:56:31.170 : nau_gettab:exit
2019-06-24 06:56:31.170 : nau_sini:entry
2019-06-24 06:56:31.170 : nau_sini:exit
2019-06-24 06:56:31.170 : nau_ini:exit
2019-06-24 06:56:31.170 : naeeinit:entry
2019-06-24 06:56:31.170 : nam_gbp:Reading parameter "SQLNET.FIPS_140" from parameter file
2019-06-24 06:56:31.170 : nam_gbp:Parameter not found
2019-06-24 06:56:31.170 : naeconp:entry
2019-06-24 06:56:31.170 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:56:31.170 : nam_gnsp:Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter file
2019-06-24 06:56:31.170 : nam_gnsp:Parameter not found
2019-06-24 06:56:31.170 : naequad:Using default value "ACCEPTED"
2019-06-24 06:56:31.170 : nae_get_boolean_param_from_conn_string:entry
2019-06-24 06:56:31.170 : nae_get_boolean_param_from_conn_string:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:56:31.170 : nam_gbp:Reading parameter "SQLNET.IGNORE_ANO_ENCRYPTION_FOR_TCPS" from parameter file
2019-06-24 06:56:31.170 : nam_gbp:Parameter not found
2019-06-24 06:56:31.170 : nam_gbp:Reading parameter "SQLNET._ENHANCED_LOGON_PROTECTION" from parameter file
2019-06-24 06:56:31.170 : nam_gbp:Parameter not found
2019-06-24 06:56:31.170 : nam_gic:entry
2019-06-24 06:56:31.171 : nam_gic:Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER" parameter
2019-06-24 06:56:31.171 : nam_gic:Parameter not found
2019-06-24 06:56:31.171 : nam_gic:exit
2019-06-24 06:56:31.171 : naesno:Using default value "all available algorithms"
2019-06-24 06:56:31.171 : naeshow:entry
2019-06-24 06:56:31.171 : naeshow:exit
2019-06-24 06:56:31.171 : naeeinit:exit
2019-06-24 06:56:31.171 : naecinit:entry
2019-06-24 06:56:31.171 : naeconp:entry
2019-06-24 06:56:31.171 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:56:31.171 : nam_gnsp:Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from parameter file
2019-06-24 06:56:31.171 : nam_gnsp:Parameter not found
2019-06-24 06:56:31.171 : naequad:Using default value "ACCEPTED"
2019-06-24 06:56:31.171 : nam_gic:entry
2019-06-24 06:56:31.171 : nam_gic:Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER" parameter
2019-06-24 06:56:31.171 : nam_gic:Parameter not found
2019-06-24 06:56:31.171 : nam_gic:exit
2019-06-24 06:56:31.171 : naesno:Using default value "all available algorithms"
2019-06-24 06:56:31.171 : naeshow:entry
2019-06-24 06:56:31.171 : naeshow:exit
2019-06-24 06:56:31.171 : naecinit:exit
2019-06-24 06:56:31.171 : nadisc:entry
2019-06-24 06:56:31.171 : nacomtm:entry
2019-06-24 06:56:31.171 : nacompd:entry
2019-06-24 06:56:31.171 : nacompd:exit
2019-06-24 06:56:31.171 : nacompd:entry
2019-06-24 06:56:31.171 : nacompd:exit
2019-06-24 06:56:31.171 : nacomtm:exit
2019-06-24 06:56:31.171 : nas_dis:entry
2019-06-24 06:56:31.171 : nas_dis:exit
2019-06-24 06:56:31.171 : nau_dis:entry
2019-06-24 06:56:31.171 : nau_dis:exit
2019-06-24 06:56:31.171 : naeetrm:entry
2019-06-24 06:56:31.171 : naeetrm:exit
2019-06-24 06:56:31.171 : naectrm:entry
2019-06-24 06:56:31.171 : naectrm:exit
2019-06-24 06:56:31.172 : nagbltrm:entry
2019-06-24 06:56:31.172 : nau_gtm:entry
2019-06-24 06:56:31.172 : nau_gtm:exit
2019-06-24 06:56:31.172 : nagbltrm:exit
2019-06-24 06:56:31.172 : nadisc:exit
2019-06-24 06:56:31.172 : nainit:exit
2019-06-24 06:56:31.172 : nsnainit:NS Connection version: 318
2019-06-24 06:56:31.172 : nsnainit:inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd
2019-06-24 06:56:31.172 : 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

2019-06-24 06:56:31.172 : 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

2019-06-24 06:56:31.172 : nsopen:global context check-in (to slot 5) complete
2019-06-24 06:56:31.172 : nsopen:lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x7ebf, gbl[1]=0x2001, tdu=2097152, sdu=8192
2019-06-24 06:56:31.172 : nsfull_opn:cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0
2019-06-24 06:56:31.172 : nsfull_opn:nsctx: state=7, flg=0x4000, mvd=0
2019-06-24 06:56:31.172 : nsanswer:deferring connect attempt; at stage 5
2019-06-24 06:56:31.172 : nsgetaddr:entry
2019-06-24 06:56:31.172 : nttaddr2bnd:entry
2019-06-24 06:56:31.172 : snlinGetNameInfo:entry
2019-06-24 06:56:31.172 : snlinGetNameInfo:exit
2019-06-24 06:56:31.172 : nttaddr2bnd:exit
2019-06-24 06:56:31.172 : nsevreg:begin registration process for 5
2019-06-24 06:56:31.172 : nsevreg:sgt=0, evn=1, evt[2]=0x0
2019-06-24 06:56:31.172 : nsevreg:begin notification process for 5
2019-06-24 06:56:31.172 : nsevreg:rdm=0, sgt=0, evt[0]=0x20, [1]=0x20, [2]=0x0, nrg=0
2019-06-24 06:56:31.172 : nsevreg:registering for 0x20
2019-06-24 06:56:31.172 : nsglhe:exit
2019-06-24 06:56:31.172 : nsevwait:4 registered connection(s)
2019-06-24 06:56:31.172 : nsevwait:0 pre-posted event(s)
2019-06-24 06:56:31.172 : nsevwait:waiting for transport event (1 thru 5)...
2019-06-24 06:56:31.172 : nsevwait:0 newly-posted event(s)
2019-06-24 06:56:31.172 : nsgetaddr:entry
2019-06-24 06:56:31.172 : nttaddr2bnd:entry
2019-06-24 06:56:31.173 : snlinGetNameInfo:entry
2019-06-24 06:56:31.173 : snlinGetNameInfo:exit
2019-06-24 06:56:31.173 : nttaddr2bnd:exit
2019-06-24 06:56:31.173 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 5: NT events set:
	READ
2019-06-24 06:56:31.173 : nsgetaddr:entry
2019-06-24 06:56:31.173 : nttaddr2bnd:entry
2019-06-24 06:56:31.173 : snlinGetNameInfo:entry
2019-06-24 06:56:31.173 : snlinGetNameInfo:exit
2019-06-24 06:56:31.173 : nttaddr2bnd:exit
2019-06-24 06:56:31.173 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 5: NS events set:
	OUTGOING CALL COMPLETE
	(ADDRESS=(PROTOCOL=tcp)(HOST=192.168.110.74)(PORT=36565))
2019-06-24 06:56:31.173 : nsevdansw:at STAGE 5
2019-06-24 06:56:31.173 : nsdo:cid=5, opcode=68, *bl=2040, *what=8, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:31.173 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.173 : nsdo:nsctx: state=14, flg=0x20004004, mvd=0
2019-06-24 06:56:31.173 : nsdo:gtn=10, gtc=10, ptn=10, ptc=8111
2019-06-24 06:56:31.173 : nscon:doing connect handshake...
2019-06-24 06:56:31.173 : nscon:recving a packet
2019-06-24 06:56:31.173 : nsprecv:reading from transport...
2019-06-24 06:56:31.173 : nttrd:entry
2019-06-24 06:56:31.173 : nttrd:socket 14 had bytes read=214
2019-06-24 06:56:31.173 : nttrd:exit
2019-06-24 06:56:31.173 : nsprecv:214 bytes from transport
2019-06-24 06:56:31.173 : nsprecv:tlen=214, plen=214, type=1
2019-06-24 06:56:31.173 : nscon:got NSPTCN packet
2019-06-24 06:56:31.173 : nsconneg:vsn=318, lov=300, opt=0x81, sdu=8192, tdu=65535, ntc=0x7f08
2019-06-24 06:56:31.173 : nsconneg:Large sdu=8192,tdu=2097152,compression flg=0
2019-06-24 06:56:31.173 : nsconneg:after negotiation compression flag=0 
2019-06-24 06:56:31.173 : nsconneg:vsn=318, gbl=0x81, sdu=8192, tdu=2097152
2019-06-24 06:56:31.173 : nscon:connect id = 0x7b76
2019-06-24 06:56:31.173 : nscon:got 140 bytes connect data
2019-06-24 06:56:31.173 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.173 : nsevdansw:exit
2019-06-24 06:56:31.173 : nttctl:entry
2019-06-24 06:56:31.173 : nttctl:Clearing non-blocking mode
2019-06-24 06:56:31.174 : nsevrec:event is 0x20, on 5
2019-06-24 06:56:31.174 : nsevwait:1 posted event(s)
2019-06-24 06:56:31.174 : nsglhe:entry
2019-06-24 06:56:31.174 : nsglfc:command = status
2019-06-24 06:56:31.174 : nsglauthorized:entry
2019-06-24 06:56:31.174 : nttctl:entry
2019-06-24 06:56:31.174 : nscontrol:cmd=7, lcl=0x0
2019-06-24 06:56:31.174 : nsglrespond:entry
2019-06-24 06:56:31.174 : nsdo:cid=5, opcode=67, *bl=52, *what=11, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:31.174 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.174 : nsdo:nsctx: state=2, flg=0x20004004, mvd=0
2019-06-24 06:56:31.174 : nsdo:gtn=214, gtc=214, ptn=10, ptc=8111
2019-06-24 06:56:31.174 : nscon:sending NSPTRD packet
2019-06-24 06:56:31.174 : nspsend:plen=62, type=5
2019-06-24 06:56:31.174 : nttwr:entry
2019-06-24 06:56:31.174 : nttwr:socket 14 had bytes written=62
2019-06-24 06:56:31.175 : nttwr:exit
2019-06-24 06:56:31.175 : nspsend:62 bytes to transport
2019-06-24 06:56:31.175 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.175 : nsvntx_dei:entry
2019-06-24 06:56:31.175 : nsvntx_dei:exit
2019-06-24 06:56:31.175 : nstimarmed:no timer allocated
2019-06-24 06:56:31.175 : nstoClearTimeout:ATO disabled for ctx=0x0xd7c440
2019-06-24 06:56:31.175 : nstoClearTimeout:STO disabled for ctx=0x0xd7c440
2019-06-24 06:56:31.175 : nstoClearTimeout:RTO disabled for ctx=0x0xd7c440
2019-06-24 06:56:31.175 : nstoClearTimeout:PITO disabled for ctx=0x0xd7c440
2019-06-24 06:56:31.175 : nstoUpdateActive:Active timeout is -1 (see nstotyp)
2019-06-24 06:56:31.175 : nttctl:entry
2019-06-24 06:56:31.175 : nttctl:entry
2019-06-24 06:56:31.175 : nsfull_cls:cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0
2019-06-24 06:56:31.175 : nsfull_cls:nsctx: state=2, flg=0x20004000, mvd=0
2019-06-24 06:56:31.175 : nsevunreg:cid=5, sgt=0, rdm=0
2019-06-24 06:56:31.175 : nsevunreg:3 registered connection(s)
2019-06-24 06:56:31.175 : nsclose:closing transport
2019-06-24 06:56:31.175 : nttdisc:entry
2019-06-24 06:56:31.175 : nttdisc:Closed socket 14
2019-06-24 06:56:31.175 : nttdisc:exit
2019-06-24 06:56:31.175 : nsclose:global context check-out (from slot 5) complete
2019-06-24 06:56:31.175 : nsvntx_dei:entry
2019-06-24 06:56:31.175 : nsvntx_dei:exit
2019-06-24 06:56:31.175 : nsmfr:3368 bytes at 0xceea80
2019-06-24 06:56:31.175 : nsmfr:1952 bytes at 0xd7c440
2019-06-24 06:56:31.175 : nsgldissolve:entry
2019-06-24 06:56:31.175 : nsgldissolve:Deallocating cxd 0xd1ca50.
2019-06-24 06:56:31.175 : nsgldissolve:exit
2019-06-24 06:56:31.176 : nsglrespond:exit
2019-06-24 06:56:31.176 : nsglhe:exit
2019-06-24 06:56:31.176 : nsevwait:3 registered connection(s)
2019-06-24 06:56:31.176 : nsevwait:0 pre-posted event(s)
2019-06-24 06:56:31.176 : nsevwait:waiting for transport event (1 thru 4)...
2019-06-24 06:56:31.176 : nsevwait:0 newly-posted event(s)
2019-06-24 06:56:31.176 : nsgetaddr:entry
2019-06-24 06:56:31.176 : ntusad2b:entry
2019-06-24 06:56:31.176 : ntusad2b:exit
2019-06-24 06:56:31.176 : nsevfnt:cxd: 0xce1fc0 cid=4 stage 0: NT events set:
	CONNECTION REQUEST
2019-06-24 06:56:31.176 : nsgetaddr:entry
2019-06-24 06:56:31.176 : ntusad2b:entry
2019-06-24 06:56:31.176 : ntusad2b:exit
2019-06-24 06:56:31.176 : nsevfnt:cxd: 0xce1fc0 cid=4 stage 0: NS events set:
	INCOMING CALL
2019-06-24 06:56:31.176 : nsevrec:event is 0x1, on 4
2019-06-24 06:56:31.176 : nsevwait:1 pre-posted event(s)
2019-06-24 06:56:31.176 : nsevrec:event is 0x1, on 4
2019-06-24 06:56:31.176 : nsevwait:1 posted event(s)
2019-06-24 06:56:31.176 : nsglhe:entry
2019-06-24 06:56:31.176 : nsmal:1952 bytes at 0xd7c440
2019-06-24 06:56:31.176 : nsmal:3368 bytes at 0xceea80
2019-06-24 06:56:31.176 : nstoSetupTimeout:ATO enabled for ctx=0x0xd7c440, val=60000(millisecs)
2019-06-24 06:56:31.176 : nstoUpdateActive:Active timeout is 0 (see nstotyp)
2019-06-24 06:56:31.176 : nsopen:opening transport...
2019-06-24 06:56:31.176 : ntusconn:entry
2019-06-24 06:56:31.176 : ntusconn:connect: operation  3
2019-06-24 06:56:31.176 : ntuscnp:entry
2019-06-24 06:56:31.176 : ntuscnp:exit
2019-06-24 06:56:31.176 : ntuscnr:entry
2019-06-24 06:56:31.176 : ntuscnr:accept new socket 14
2019-06-24 06:56:31.176 : ntuscnr:exit
2019-06-24 06:56:31.176 : ntusconn:exit
2019-06-24 06:56:31.177 : nsopen:transport is open
2019-06-24 06:56:31.177 : nsnainit:answer
2019-06-24 06:56:31.177 : nsnasvnainfo:NA disabled for this connection
2019-06-24 06:56:31.177 : nainit:entry
2019-06-24 06:56:31.177 : nagblini:entry
2019-06-24 06:56:31.177 : nau_gin:entry
2019-06-24 06:56:31.177 : nau_gparams:entry
2019-06-24 06:56:31.177 : nam_gbp:Reading parameter "sqlnet.authentication_required" from parameter file
2019-06-24 06:56:31.177 : nam_gbp:Parameter not found
2019-06-24 06:56:31.177 : nau_gparams:Using default value "FALSE"
2019-06-24 06:56:31.177 : nau_gslf:entry
2019-06-24 06:56:31.177 : nam_gic:entry
2019-06-24 06:56:31.177 : nam_gic:Counting # of items in "sqlnet.authentication_services" parameter
2019-06-24 06:56:31.177 : nam_gic:Parameter not found
2019-06-24 06:56:31.177 : nam_gic:Found 0 items
2019-06-24 06:56:31.177 : nam_gic:exit
2019-06-24 06:56:31.177 : nau_gslf:Using default value "all available adapters"
2019-06-24 06:56:31.177 : nauss_set_state:entry
2019-06-24 06:56:31.177 : nauss_set_state:exit
2019-06-24 06:56:31.177 : nau_gslf:exit
2019-06-24 06:56:31.177 : nau_gparams:exit
2019-06-24 06:56:31.177 : nau_gin:exit
2019-06-24 06:56:31.177 : nagblini:exit
2019-06-24 06:56:31.177 : na_saveprot:entry
2019-06-24 06:56:31.177 : na_saveprot:exit
2019-06-24 06:56:31.177 : nacomin:entry
2019-06-24 06:56:31.177 : nas_init:entry
2019-06-24 06:56:31.177 : nas_init:exit
2019-06-24 06:56:31.177 : nau_ini:entry
2019-06-24 06:56:31.177 : naugcp_get_connect_parameters:entry
2019-06-24 06:56:31.177 : nauss_set_state:entry
2019-06-24 06:56:31.177 : nauss_set_state:exit
2019-06-24 06:56:31.177 : naugcp_get_connect_parameters:exit
2019-06-24 06:56:31.177 : nau_gfauth:entry
2019-06-24 06:56:31.178 : nam_gbp:Reading parameter "sqlnet.fallback_authentication" from parameter file
2019-06-24 06:56:31.178 : nam_gbp:Parameter not found
2019-06-24 06:56:31.178 : nau_gfauth:Using default value "FALSE"
2019-06-24 06:56:31.178 : nau_gfauth:exit
2019-06-24 06:56:31.178 : nau_gettab:entry
2019-06-24 06:56:31.178 : nau_gettab:exit
2019-06-24 06:56:31.178 : nau_sini:entry
2019-06-24 06:56:31.178 : nau_sini:exit
2019-06-24 06:56:31.178 : nau_ini:exit
2019-06-24 06:56:31.178 : naeeinit:entry
2019-06-24 06:56:31.178 : nam_gbp:Reading parameter "SQLNET.FIPS_140" from parameter file
2019-06-24 06:56:31.178 : nam_gbp:Parameter not found
2019-06-24 06:56:31.178 : naeconp:entry
2019-06-24 06:56:31.178 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:56:31.178 : nam_gnsp:Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter file
2019-06-24 06:56:31.178 : nam_gnsp:Parameter not found
2019-06-24 06:56:31.178 : naequad:Using default value "ACCEPTED"
2019-06-24 06:56:31.178 : nae_get_boolean_param_from_conn_string:entry
2019-06-24 06:56:31.178 : nae_get_boolean_param_from_conn_string:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:56:31.178 : nam_gbp:Reading parameter "SQLNET.IGNORE_ANO_ENCRYPTION_FOR_TCPS" from parameter file
2019-06-24 06:56:31.178 : nam_gbp:Parameter not found
2019-06-24 06:56:31.178 : nam_gbp:Reading parameter "SQLNET._ENHANCED_LOGON_PROTECTION" from parameter file
2019-06-24 06:56:31.178 : nam_gbp:Parameter not found
2019-06-24 06:56:31.178 : nam_gic:entry
2019-06-24 06:56:31.178 : nam_gic:Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER" parameter
2019-06-24 06:56:31.178 : nam_gic:Parameter not found
2019-06-24 06:56:31.178 : nam_gic:exit
2019-06-24 06:56:31.178 : naesno:Using default value "all available algorithms"
2019-06-24 06:56:31.178 : naeshow:entry
2019-06-24 06:56:31.178 : naeshow:exit
2019-06-24 06:56:31.178 : naeeinit:exit
2019-06-24 06:56:31.178 : naecinit:entry
2019-06-24 06:56:31.178 : naeconp:entry
2019-06-24 06:56:31.178 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:56:31.178 : nam_gnsp:Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from parameter file
2019-06-24 06:56:31.178 : nam_gnsp:Parameter not found
2019-06-24 06:56:31.178 : naequad:Using default value "ACCEPTED"
2019-06-24 06:56:31.179 : nam_gic:entry
2019-06-24 06:56:31.179 : nam_gic:Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER" parameter
2019-06-24 06:56:31.179 : nam_gic:Parameter not found
2019-06-24 06:56:31.179 : nam_gic:exit
2019-06-24 06:56:31.179 : naesno:Using default value "all available algorithms"
2019-06-24 06:56:31.179 : naeshow:entry
2019-06-24 06:56:31.179 : naeshow:exit
2019-06-24 06:56:31.179 : naecinit:exit
2019-06-24 06:56:31.179 : nadisc:entry
2019-06-24 06:56:31.179 : nacomtm:entry
2019-06-24 06:56:31.179 : nacompd:entry
2019-06-24 06:56:31.179 : nacompd:exit
2019-06-24 06:56:31.179 : nacompd:entry
2019-06-24 06:56:31.179 : nacompd:exit
2019-06-24 06:56:31.179 : nacomtm:exit
2019-06-24 06:56:31.179 : nas_dis:entry
2019-06-24 06:56:31.179 : nas_dis:exit
2019-06-24 06:56:31.179 : nau_dis:entry
2019-06-24 06:56:31.179 : nau_dis:exit
2019-06-24 06:56:31.179 : naeetrm:entry
2019-06-24 06:56:31.179 : naeetrm:exit
2019-06-24 06:56:31.179 : naectrm:entry
2019-06-24 06:56:31.179 : naectrm:exit
2019-06-24 06:56:31.179 : nagbltrm:entry
2019-06-24 06:56:31.179 : nau_gtm:entry
2019-06-24 06:56:31.179 : nau_gtm:exit
2019-06-24 06:56:31.179 : nagbltrm:exit
2019-06-24 06:56:31.179 : nadisc:exit
2019-06-24 06:56:31.179 : nainit:exit
2019-06-24 06:56:31.179 : nsnainit:NS Connection version: 318
2019-06-24 06:56:31.179 : nsnainit:inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd
2019-06-24 06:56:31.179 : 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

2019-06-24 06:56:31.179 : 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

2019-06-24 06:56:31.179 : nsopen:global context check-in (to slot 5) complete
2019-06-24 06:56:31.179 : nsopen:lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001, tdu=32767, sdu=8192
2019-06-24 06:56:31.179 : nsfull_opn:cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0
2019-06-24 06:56:31.179 : nsfull_opn:nsctx: state=7, flg=0x4000, mvd=0
2019-06-24 06:56:31.179 : nsanswer:deferring connect attempt; at stage 5
2019-06-24 06:56:31.179 : nsgetaddr:entry
2019-06-24 06:56:31.179 : ntusad2b:entry
2019-06-24 06:56:31.179 : ntusad2b:exit
2019-06-24 06:56:31.179 : nsevreg:begin registration process for 5
2019-06-24 06:56:31.179 : nsevreg:sgt=0, evn=1, evt[2]=0x0
2019-06-24 06:56:31.179 : nsevreg:begin notification process for 5
2019-06-24 06:56:31.179 : nsevreg:rdm=0, sgt=0, evt[0]=0x20, [1]=0x20, [2]=0x0, nrg=0
2019-06-24 06:56:31.179 : nsevreg:registering for 0x20
2019-06-24 06:56:31.179 : nsglhe:exit
2019-06-24 06:56:31.179 : nsevwait:4 registered connection(s)
2019-06-24 06:56:31.179 : nsevwait:0 pre-posted event(s)
2019-06-24 06:56:31.179 : nsevwait:waiting for transport event (1 thru 5)...
2019-06-24 06:56:31.179 : nsevwait:0 newly-posted event(s)
2019-06-24 06:56:31.179 : nsgetaddr:entry
2019-06-24 06:56:31.179 : ntusad2b:entry
2019-06-24 06:56:31.179 : ntusad2b:exit
2019-06-24 06:56:31.179 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 5: NT events set:
	READ
2019-06-24 06:56:31.179 : nsgetaddr:entry
2019-06-24 06:56:31.179 : ntusad2b:entry
2019-06-24 06:56:31.179 : ntusad2b:exit
2019-06-24 06:56:31.179 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 5: NS events set:
	OUTGOING CALL COMPLETE
	(ADDRESS=(PROTOCOL=ipc)(KEY=#16099.2))
2019-06-24 06:56:31.179 : nsevdansw:at STAGE 5
2019-06-24 06:56:31.179 : nsdo:cid=5, opcode=68, *bl=2040, *what=8, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:31.179 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.179 : nsdo:nsctx: state=14, flg=0x20004004, mvd=0
2019-06-24 06:56:31.179 : nsdo:gtn=10, gtc=10, ptn=10, ptc=8111
2019-06-24 06:56:31.179 : nscon:doing connect handshake...
2019-06-24 06:56:31.179 : nscon:recving a packet
2019-06-24 06:56:31.179 : nsprecv:reading from transport...
2019-06-24 06:56:31.179 : ntusread:entry
2019-06-24 06:56:31.179 : ntusread:Read bytes 214
2019-06-24 06:56:31.179 : ntusread:exit
2019-06-24 06:56:31.179 : nsprecv:214 bytes from transport
2019-06-24 06:56:31.179 : nsprecv:tlen=214, plen=214, type=1
2019-06-24 06:56:31.179 : nscon:got NSPTCN packet
2019-06-24 06:56:31.179 : nsconneg:vsn=318, lov=300, opt=0x81, sdu=8192, tdu=32767, ntc=0x7308
2019-06-24 06:56:31.179 : nsconneg:Large sdu=8192,tdu=32767,compression flg=0
2019-06-24 06:56:31.179 : nsconneg:after negotiation compression flag=0 
2019-06-24 06:56:31.179 : nsconneg:vsn=318, gbl=0x81, sdu=8192, tdu=32767
2019-06-24 06:56:31.179 : nscon:connect id = 0x7b76
2019-06-24 06:56:31.179 : nscon:got 140 bytes connect data
2019-06-24 06:56:31.179 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.179 : nsevdansw:exit
2019-06-24 06:56:31.179 : nsevrec:event is 0x20, on 5
2019-06-24 06:56:31.180 : nsevwait:1 posted event(s)
2019-06-24 06:56:31.180 : nsglhe:entry
2019-06-24 06:56:31.180 : nsglfc:command = status
2019-06-24 06:56:31.180 : nsglauthorized:entry
2019-06-24 06:56:31.180 : nscontrol:cmd=7, lcl=0x0
2019-06-24 06:56:31.180 : nszgcoscreds:entry
2019-06-24 06:56:31.180 : nszntcontrol:entry
2019-06-24 06:56:31.180 : nszntcontrol:exit
2019-06-24 06:56:31.180 : nszgcoscreds:exit
2019-06-24 06:56:31.180 : nsglrespond:entry
2019-06-24 06:56:31.180 : nsdo:cid=5, opcode=67, *bl=45, *what=9, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:31.180 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.180 : nsdo:nsctx: state=2, flg=0x21004004, mvd=0
2019-06-24 06:56:31.180 : nsdo:gtn=214, gtc=214, ptn=10, ptc=8111
2019-06-24 06:56:31.180 : nscon:sending NSPTAC packet
2019-06-24 06:56:31.180 : nspsend:plen=90, type=2
2019-06-24 06:56:31.180 : ntuswrte:entry
2019-06-24 06:56:31.180 : ntuswrte:Wrote on 14 bytes 90
2019-06-24 06:56:31.180 : ntuswrte:exit
2019-06-24 06:56:31.180 : nspsend:90 bytes to transport
2019-06-24 06:56:31.180 : nscon:connect handshake is complete
2019-06-24 06:56:31.180 : nscon:nsctxinf[0]=0xd, [1]=0xc
2019-06-24 06:56:31.180 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.180 : nsglrespond:exit
2019-06-24 06:56:31.180 : nsglhe:exit
2019-06-24 06:56:31.180 : nsevwait:4 registered connection(s)
2019-06-24 06:56:31.180 : nsevwait:0 pre-posted event(s)
2019-06-24 06:56:31.180 : nsevwait:waiting for transport event (1 thru 5)...
2019-06-24 06:56:31.180 : nsevwait:0 newly-posted event(s)
2019-06-24 06:56:31.180 : nsgetaddr:entry
2019-06-24 06:56:31.180 : ntusad2b:entry
2019-06-24 06:56:31.180 : ntusad2b:exit
2019-06-24 06:56:31.180 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 7: NT events set:
	READ
2019-06-24 06:56:31.180 : nsgetaddr:entry
2019-06-24 06:56:31.180 : ntusad2b:entry
2019-06-24 06:56:31.180 : ntusad2b:exit
2019-06-24 06:56:31.180 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 7: NS events set:
	OUTGOING CALL COMPLETE
	(ADDRESS=(PROTOCOL=ipc)(KEY=#16099.2))
2019-06-24 06:56:31.180 : nsevdansw:at STAGE 7
2019-06-24 06:56:31.180 : nsevdansw:no NA services in use
2019-06-24 06:56:31.180 : nsevunreg:cid=5, sgt=0, rdm=0
2019-06-24 06:56:31.180 : nsevunreg:3 registered connection(s)
2019-06-24 06:56:31.180 : nsevdansw:exit
2019-06-24 06:56:31.180 : nsevrec:event is 0x20, on 5
2019-06-24 06:56:31.180 : nsevwait:1 posted event(s)
2019-06-24 06:56:31.180 : nsglhe:entry
2019-06-24 06:56:31.180 : nsevunreg:cid=5, sgt=0, rdm=0
2019-06-24 06:56:31.180 : nsevreg:begin registration process for 5
2019-06-24 06:56:31.180 : nsevreg:sgt=0, evn=1, evt[2]=0x0
2019-06-24 06:56:31.180 : nsevreg:begin notification process for 5
2019-06-24 06:56:31.180 : nsevreg:rdm=0, sgt=0, evt[0]=0x2, [1]=0x2, [2]=0x0, nrg=0
2019-06-24 06:56:31.180 : nsevreg:registering for 0x2
2019-06-24 06:56:31.180 : nsglauthorized:entry
2019-06-24 06:56:31.180 : nsglhe:exit
2019-06-24 06:56:31.180 : nsevwait:4 registered connection(s)
2019-06-24 06:56:31.180 : nsevwait:0 pre-posted event(s)
2019-06-24 06:56:31.180 : nsevwait:waiting for transport event (1 thru 5)...
2019-06-24 06:56:31.180 : nsevwait:0 newly-posted event(s)
2019-06-24 06:56:31.180 : nsgetaddr:entry
2019-06-24 06:56:31.180 : ntusad2b:entry
2019-06-24 06:56:31.180 : ntusad2b:exit
2019-06-24 06:56:31.180 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 0: NT events set:
	READ
2019-06-24 06:56:31.180 : nsgetaddr:entry
2019-06-24 06:56:31.180 : ntusad2b:entry
2019-06-24 06:56:31.180 : ntusad2b:exit
2019-06-24 06:56:31.180 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 0: NS events set:
	INCOMING SEND
2019-06-24 06:56:31.180 : nsevrec:event is 0x2, on 5
2019-06-24 06:56:31.180 : nsevwait:1 pre-posted event(s)
2019-06-24 06:56:31.180 : nsevrec:event is 0x2, on 5
2019-06-24 06:56:31.180 : nsevwait:1 posted event(s)
2019-06-24 06:56:31.180 : nsglhe:entry
2019-06-24 06:56:31.180 : nsdo:cid=5, opcode=68, *bl=1, *what=0, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:31.180 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.180 : nsdo:nsctx: state=8, flg=0x2100400c, mvd=0
2019-06-24 06:56:31.180 : nsdo:gtn=214, gtc=214, ptn=10, ptc=8111
2019-06-24 06:56:31.180 : nsrdr:recving a packet
2019-06-24 06:56:31.180 : nsprecv:reading from transport...
2019-06-24 06:56:31.180 : ntusread:entry
2019-06-24 06:56:31.180 : ntusread:Read bytes 11
2019-06-24 06:56:31.180 : ntusread:exit
2019-06-24 06:56:31.180 : nsprecv:11 bytes from transport
2019-06-24 06:56:31.180 : nsprecv:tlen=11, plen=11, type=12
2019-06-24 06:56:31.180 : nsrdr:got NSPTMK packet
2019-06-24 06:56:31.180 : nsdo:*what=17, *bl=1
2019-06-24 06:56:31.180 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.180 : nsglauthorized:entry
2019-06-24 06:56:31.180 : nsglauthorized:Authenticated user: 54321
2019-06-24 06:56:31.180 : nsglecmd:entry
2019-06-24 06:56:31.180 : nstoClearTimeout:ATO disabled for ctx=0x0xd7c440
2019-06-24 06:56:31.180 : nstoUpdateActive:Active timeout is -1 (see nstotyp)
2019-06-24 06:56:31.180 : nstoControlATO:ATO disabled for ctx=0x0xd7c440
2019-06-24 06:56:31.180 : nscontrol:cmd=26, lcl=0x0
2019-06-24 06:56:31.180 : nsgcsss:ons_subscriber_status=1
2019-06-24 06:56:31.181 : nsglrespond:entry
2019-06-24 06:56:31.181 : nsdo:cid=5, opcode=67, *bl=516, *what=1, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:31.181 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.181 : nsdo:nsctx: state=8, flg=0x2100400c, mvd=0
2019-06-24 06:56:31.181 : nsdo:gtn=214, gtc=214, ptn=10, ptc=8111
2019-06-24 06:56:31.181 : nsdo:516 bytes to NS buffer
2019-06-24 06:56:31.181 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.181 : nsglrespond:exit
2019-06-24 06:56:31.181 : nsdo:cid=5, opcode=67, *bl=581, *what=1, uflgs=0x0, cflgs=0x3
2019-06-24 06:56:31.181 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.181 : nsdo:nsctx: state=8, flg=0x2100400c, mvd=0
2019-06-24 06:56:31.181 : nsdo:gtn=214, gtc=214, ptn=526, ptc=8111
2019-06-24 06:56:31.181 : nsdo:581 bytes to NS buffer
2019-06-24 06:56:31.181 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.181 : nsdo:cid=5, opcode=67, *bl=0, *what=1, uflgs=0x2, cflgs=0x3
2019-06-24 06:56:31.181 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:56:31.181 : nsdo:nsctx: state=8, flg=0x2100400c, mvd=0
2019-06-24 06:56:31.181 : nsdo:gtn=214, gtc=214, ptn=1107, ptc=8111
2019-06-24 06:56:31.181 : nsdofls:DATA flags: 0x0
2019-06-24 06:56:31.181 : nsdofls:sending NSPTDA packet
2019-06-24 06:56:31.181 : nspsend:plen=1107, type=6
2019-06-24 06:56:31.181 : ntuswrte:entry
2019-06-24 06:56:31.181 : ntuswrte:Wrote on 14 bytes 1107
2019-06-24 06:56:31.181 : ntuswrte:exit
2019-06-24 06:56:31.181 : nspsend:1107 bytes to transport
2019-06-24 06:56:31.181 : nsdoacts:flushing transport
2019-06-24 06:56:31.181 : nsdo:nsctxrnk=0
2019-06-24 06:56:31.181 : nsgldissolve:entry
2019-06-24 06:56:31.181 : nsvntx_dei:entry
2019-06-24 06:56:31.181 : nsvntx_dei:exit
2019-06-24 06:56:31.181 : nstimarmed:no timer allocated
2019-06-24 06:56:31.181 : nsfull_cls:cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0
2019-06-24 06:56:31.181 : nsfull_cls:nsctx: state=8, flg=0x21004008, mvd=0
2019-06-24 06:56:31.181 : nsevunreg:cid=5, sgt=0, rdm=0
2019-06-24 06:56:31.181 : nsevunreg:3 registered connection(s)
2019-06-24 06:56:31.181 : nsdo:cid=5, opcode=67, *bl=0, *what=1, uflgs=0x0, cflgs=0x1
2019-06-24 06:56:31.181 : nsdo:nsctx: state=8, flg=0x1004008, mvd=0
2019-06-24 06:56:31.181 : nsdo:gtn=214, gtc=214, ptn=10, ptc=8111
2019-06-24 06:56:31.181 : nsdofls:DATA flags: 0x40
2019-06-24 06:56:31.181 : nsdofls:sending NSPTDA packet
2019-06-24 06:56:31.181 : nspsend:plen=10, type=6
2019-06-24 06:56:31.181 : ntuswrte:entry
2019-06-24 06:56:31.181 : ntuswrte:Wrote on 14 bytes 10
2019-06-24 06:56:31.181 : ntuswrte:exit
2019-06-24 06:56:31.181 : nspsend:10 bytes to transport
2019-06-24 06:56:31.181 : nsclose:closing transport
2019-06-24 06:56:31.181 : ntusdisc:entry
2019-06-24 06:56:31.181 : ntusdisc:Closed socket 14
2019-06-24 06:56:31.181 : ntusdisc:exit
2019-06-24 06:56:31.181 : nsclose:global context check-out (from slot 5) complete
2019-06-24 06:56:31.181 : nsvntx_dei:entry
2019-06-24 06:56:31.181 : nsvntx_dei:exit
2019-06-24 06:56:31.181 : nsmfr:3368 bytes at 0xceea80
2019-06-24 06:56:31.181 : nsmfr:1952 bytes at 0xd7c440
2019-06-24 06:56:31.181 : nsgldissolve:Deallocating cxd 0xd1ca50.
2019-06-24 06:56:31.181 : nsgldissolve:exit
2019-06-24 06:56:31.181 : nsglecmd:exit
2019-06-24 06:56:31.182 : nsglhe:exit
2019-06-24 06:56:31.182 : nsevwait:3 registered connection(s)
2019-06-24 06:56:31.182 : nsevwait:0 pre-posted event(s)
2019-06-24 06:56:31.182 : nsevwait:waiting for transport event (1 thru 4)...
2019-06-24 06:57:12.664 : nsevwait:0 newly-posted event(s)
2019-06-24 06:57:12.664 : nsgetaddr:entry
2019-06-24 06:57:12.664 : nttaddr2bnd:entry
2019-06-24 06:57:12.664 : snlinGetNameInfo:entry
2019-06-24 06:57:12.664 : snlinGetNameInfo:exit
2019-06-24 06:57:12.664 : nttaddr2bnd:exit
2019-06-24 06:57:12.664 : nsevfnt:cxd: 0xccc6d0 cid=1 stage 0: NT events set:
	CONNECTION REQUEST
2019-06-24 06:57:12.664 : nsgetaddr:entry
2019-06-24 06:57:12.665 : nttaddr2bnd:entry
2019-06-24 06:57:12.665 : snlinGetNameInfo:entry
2019-06-24 06:57:12.665 : snlinGetNameInfo:exit
2019-06-24 06:57:12.665 : nttaddr2bnd:exit
2019-06-24 06:57:12.665 : nsevfnt:cxd: 0xccc6d0 cid=1 stage 0: NS events set:
	INCOMING CALL
2019-06-24 06:57:12.665 : nsevrec:event is 0x1, on 1
2019-06-24 06:57:12.665 : nsevwait:1 pre-posted event(s)
2019-06-24 06:57:12.665 : nsevrec:event is 0x1, on 1
2019-06-24 06:57:12.665 : nsevwait:1 posted event(s)
2019-06-24 06:57:12.665 : nsglhe:entry
2019-06-24 06:57:12.665 : nsmal:1952 bytes at 0xd7c440
2019-06-24 06:57:12.665 : nsmal:3368 bytes at 0xceea80
2019-06-24 06:57:12.665 : nstoSetupTimeout:ATO enabled for ctx=0x0xd7c440, val=60000(millisecs)
2019-06-24 06:57:12.665 : nstoUpdateActive:Active timeout is 0 (see nstotyp)
2019-06-24 06:57:12.665 : nsopen:opening transport...
2019-06-24 06:57:12.665 : nttcon:entry
2019-06-24 06:57:12.665 : nttcon:toc = 3
2019-06-24 06:57:12.665 : nttcnp:entry
2019-06-24 06:57:12.665 : nttcnp:getting sockname
2019-06-24 06:57:12.665 : nttcnp:getting peername
2019-06-24 06:57:12.665 : nttcnp:exit
2019-06-24 06:57:12.665 : nttcnr:entry
2019-06-24 06:57:12.665 : nttcnr:waiting to accept a connection.
2019-06-24 06:57:12.665 : nttcnr:getting sockname
2019-06-24 06:57:12.665 : snlinGetNameInfo:entry
2019-06-24 06:57:12.666 : snlinGetNameInfo:exit
2019-06-24 06:57:12.666 : nttcnr:connected on source ipaddr 192.168.110.74 port 2524
2019-06-24 06:57:12.666 : snlinGetNameInfo:entry
2019-06-24 06:57:12.666 : snlinGetNameInfo:exit
2019-06-24 06:57:12.666 : nttcnr:connected on destination ipaddr 192.168.110.74 port 36587
2019-06-24 06:57:12.666 : nttvlser:entry
2019-06-24 06:57:12.666 : nlvlsern:entry
2019-06-24 06:57:12.666 : snlinGetNameInfo:entry
2019-06-24 06:57:12.666 : snlinGetNameInfo:exit
2019-06-24 06:57:12.666 : nttvlser:valid node check on incoming node 192.168.110.74
2019-06-24 06:57:12.666 : nttvlser:Accepted Entry: 192.168.110.74
2019-06-24 06:57:12.666 : nttcnr:exit
2019-06-24 06:57:12.666 : nttctl:entry
2019-06-24 06:57:12.666 : nttctl:Setting connection into nodelay mode
2019-06-24 06:57:12.666 : nttctl:set TCP_NODELAY on 14
2019-06-24 06:57:12.666 : nttcon:exit
2019-06-24 06:57:12.666 : nsopen:transport is open
2019-06-24 06:57:12.666 : nsnainit:answer
2019-06-24 06:57:12.666 : nsnasvnainfo:NA disabled for this connection
2019-06-24 06:57:12.666 : nainit:entry
2019-06-24 06:57:12.666 : nagblini:entry
2019-06-24 06:57:12.666 : nau_gin:entry
2019-06-24 06:57:12.666 : nau_gparams:entry
2019-06-24 06:57:12.666 : nam_gbp:Reading parameter "sqlnet.authentication_required" from parameter file
2019-06-24 06:57:12.666 : nam_gbp:Parameter not found
2019-06-24 06:57:12.666 : nau_gparams:Using default value "FALSE"
2019-06-24 06:57:12.666 : nau_gslf:entry
2019-06-24 06:57:12.666 : nam_gic:entry
2019-06-24 06:57:12.666 : nam_gic:Counting # of items in "sqlnet.authentication_services" parameter
2019-06-24 06:57:12.666 : nam_gic:Parameter not found
2019-06-24 06:57:12.666 : nam_gic:Found 0 items
2019-06-24 06:57:12.666 : nam_gic:exit
2019-06-24 06:57:12.666 : nau_gslf:Using default value "all available adapters"
2019-06-24 06:57:12.666 : nauss_set_state:entry
2019-06-24 06:57:12.666 : nauss_set_state:exit
2019-06-24 06:57:12.666 : nau_gslf:exit
2019-06-24 06:57:12.666 : nau_gparams:exit
2019-06-24 06:57:12.666 : nau_gin:exit
2019-06-24 06:57:12.666 : nagblini:exit
2019-06-24 06:57:12.666 : na_saveprot:entry
2019-06-24 06:57:12.666 : na_saveprot:exit
2019-06-24 06:57:12.666 : nacomin:entry
2019-06-24 06:57:12.667 : nas_init:entry
2019-06-24 06:57:12.667 : nas_init:exit
2019-06-24 06:57:12.667 : nau_ini:entry
2019-06-24 06:57:12.667 : naugcp_get_connect_parameters:entry
2019-06-24 06:57:12.667 : nauss_set_state:entry
2019-06-24 06:57:12.667 : nauss_set_state:exit
2019-06-24 06:57:12.667 : naugcp_get_connect_parameters:exit
2019-06-24 06:57:12.667 : nau_gfauth:entry
2019-06-24 06:57:12.667 : nam_gbp:Reading parameter "sqlnet.fallback_authentication" from parameter file
2019-06-24 06:57:12.667 : nam_gbp:Parameter not found
2019-06-24 06:57:12.667 : nau_gfauth:Using default value "FALSE"
2019-06-24 06:57:12.667 : nau_gfauth:exit
2019-06-24 06:57:12.667 : nau_gettab:entry
2019-06-24 06:57:12.667 : nau_gettab:exit
2019-06-24 06:57:12.667 : nau_sini:entry
2019-06-24 06:57:12.667 : nau_sini:exit
2019-06-24 06:57:12.667 : nau_ini:exit
2019-06-24 06:57:12.667 : naeeinit:entry
2019-06-24 06:57:12.667 : nam_gbp:Reading parameter "SQLNET.FIPS_140" from parameter file
2019-06-24 06:57:12.667 : nam_gbp:Parameter not found
2019-06-24 06:57:12.667 : naeconp:entry
2019-06-24 06:57:12.667 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:57:12.667 : nam_gnsp:Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter file
2019-06-24 06:57:12.667 : nam_gnsp:Parameter not found
2019-06-24 06:57:12.667 : naequad:Using default value "ACCEPTED"
2019-06-24 06:57:12.667 : nae_get_boolean_param_from_conn_string:entry
2019-06-24 06:57:12.667 : nae_get_boolean_param_from_conn_string:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:57:12.667 : nam_gbp:Reading parameter "SQLNET.IGNORE_ANO_ENCRYPTION_FOR_TCPS" from parameter file
2019-06-24 06:57:12.667 : nam_gbp:Parameter not found
2019-06-24 06:57:12.667 : nam_gbp:Reading parameter "SQLNET._ENHANCED_LOGON_PROTECTION" from parameter file
2019-06-24 06:57:12.667 : nam_gbp:Parameter not found
2019-06-24 06:57:12.667 : nam_gic:entry
2019-06-24 06:57:12.667 : nam_gic:Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER" parameter
2019-06-24 06:57:12.667 : nam_gic:Parameter not found
2019-06-24 06:57:12.667 : nam_gic:exit
2019-06-24 06:57:12.667 : naesno:Using default value "all available algorithms"
2019-06-24 06:57:12.667 : naeshow:entry
2019-06-24 06:57:12.667 : naeshow:exit
2019-06-24 06:57:12.667 : naeeinit:exit
2019-06-24 06:57:12.667 : naecinit:entry
2019-06-24 06:57:12.667 : naeconp:entry
2019-06-24 06:57:12.667 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string.
2019-06-24 06:57:12.667 : nam_gnsp:Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from parameter file
2019-06-24 06:57:12.667 : nam_gnsp:Parameter not found
2019-06-24 06:57:12.667 : naequad:Using default value "ACCEPTED"
2019-06-24 06:57:12.667 : nam_gic:entry
2019-06-24 06:57:12.667 : nam_gic:Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER" parameter
2019-06-24 06:57:12.667 : nam_gic:Parameter not found
2019-06-24 06:57:12.667 : nam_gic:exit
2019-06-24 06:57:12.667 : naesno:Using default value "all available algorithms"
2019-06-24 06:57:12.667 : naeshow:entry
2019-06-24 06:57:12.667 : naeshow:exit
2019-06-24 06:57:12.667 : naecinit:exit
2019-06-24 06:57:12.667 : nadisc:entry
2019-06-24 06:57:12.667 : nacomtm:entry
2019-06-24 06:57:12.667 : nacompd:entry
2019-06-24 06:57:12.667 : nacompd:exit
2019-06-24 06:57:12.667 : nacompd:entry
2019-06-24 06:57:12.667 : nacompd:exit
2019-06-24 06:57:12.667 : nacomtm:exit
2019-06-24 06:57:12.667 : nas_dis:entry
2019-06-24 06:57:12.667 : nas_dis:exit
2019-06-24 06:57:12.667 : nau_dis:entry
2019-06-24 06:57:12.667 : nau_dis:exit
2019-06-24 06:57:12.667 : naeetrm:entry
2019-06-24 06:57:12.667 : naeetrm:exit
2019-06-24 06:57:12.667 : naectrm:entry
2019-06-24 06:57:12.667 : naectrm:exit
2019-06-24 06:57:12.667 : nagbltrm:entry
2019-06-24 06:57:12.667 : nau_gtm:entry
2019-06-24 06:57:12.667 : nau_gtm:exit
2019-06-24 06:57:12.667 : nagbltrm:exit
2019-06-24 06:57:12.668 : nadisc:exit
2019-06-24 06:57:12.668 : nainit:exit
2019-06-24 06:57:12.668 : nsnainit:NS Connection version: 318
2019-06-24 06:57:12.668 : nsnainit:inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd
2019-06-24 06:57:12.668 : 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

2019-06-24 06:57:12.668 : 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

2019-06-24 06:57:12.668 : nsopen:global context check-in (to slot 5) complete
2019-06-24 06:57:12.668 : nsopen:lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x7ebf, gbl[1]=0x2001, tdu=2097152, sdu=8192
2019-06-24 06:57:12.668 : nsfull_opn:cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0
2019-06-24 06:57:12.668 : nsfull_opn:nsctx: state=7, flg=0x4000, mvd=0
2019-06-24 06:57:12.668 : nsanswer:deferring connect attempt; at stage 5
2019-06-24 06:57:12.668 : nsgetaddr:entry
2019-06-24 06:57:12.668 : nttaddr2bnd:entry
2019-06-24 06:57:12.668 : snlinGetNameInfo:entry
2019-06-24 06:57:12.668 : snlinGetNameInfo:exit
2019-06-24 06:57:12.668 : nttaddr2bnd:exit
2019-06-24 06:57:12.668 : nsevreg:begin registration process for 5
2019-06-24 06:57:12.668 : nsevreg:sgt=0, evn=1, evt[2]=0x0
2019-06-24 06:57:12.668 : nsevreg:begin notification process for 5
2019-06-24 06:57:12.668 : nsevreg:rdm=0, sgt=0, evt[0]=0x20, [1]=0x20, [2]=0x0, nrg=0
2019-06-24 06:57:12.668 : nsevreg:registering for 0x20
2019-06-24 06:57:12.668 : nsglhe:exit
2019-06-24 06:57:12.668 : nsevwait:4 registered connection(s)
2019-06-24 06:57:12.668 : nsevwait:0 pre-posted event(s)
2019-06-24 06:57:12.668 : nsevwait:waiting for transport event (1 thru 5)...
2019-06-24 06:57:12.668 : nsevwait:0 newly-posted event(s)
2019-06-24 06:57:12.668 : nsgetaddr:entry
2019-06-24 06:57:12.668 : nttaddr2bnd:entry
2019-06-24 06:57:12.668 : snlinGetNameInfo:entry
2019-06-24 06:57:12.668 : snlinGetNameInfo:exit
2019-06-24 06:57:12.668 : nttaddr2bnd:exit
2019-06-24 06:57:12.668 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 5: NT events set:
	READ
2019-06-24 06:57:12.668 : nsgetaddr:entry
2019-06-24 06:57:12.668 : nttaddr2bnd:entry
2019-06-24 06:57:12.668 : snlinGetNameInfo:entry
2019-06-24 06:57:12.668 : snlinGetNameInfo:exit
2019-06-24 06:57:12.668 : nttaddr2bnd:exit
2019-06-24 06:57:12.668 : nsevfnt:cxd: 0xd1ca50 cid=5 stage 5: NS events set:
	OUTGOING CALL COMPLETE
	(ADDRESS=(PROTOCOL=tcp)(HOST=192.168.110.74)(PORT=36587))
2019-06-24 06:57:12.668 : nsevdansw:at STAGE 5
2019-06-24 06:57:12.668 : nsdo:cid=5, opcode=68, *bl=2040, *what=8, uflgs=0x0, cflgs=0x3
2019-06-24 06:57:12.668 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:57:12.668 : nsdo:nsctx: state=14, flg=0x20004004, mvd=0
2019-06-24 06:57:12.668 : nsdo:gtn=10, gtc=10, ptn=10, ptc=8111
2019-06-24 06:57:12.668 : nscon:doing connect handshake...
2019-06-24 06:57:12.668 : nscon:recving a packet
2019-06-24 06:57:12.668 : nsprecv:reading from transport...
2019-06-24 06:57:12.668 : nttrd:entry
2019-06-24 06:57:12.668 : nttrd:socket 14 had bytes read=284
2019-06-24 06:57:12.668 : nttrd:exit
2019-06-24 06:57:12.668 : nsprecv:284 bytes from transport
2019-06-24 06:57:12.668 : nsprecv:tlen=284, plen=284, type=1
2019-06-24 06:57:12.668 : nscon:got NSPTCN packet
2019-06-24 06:57:12.668 : nsconneg:vsn=318, lov=300, opt=0xc41, sdu=8192, tdu=65535, ntc=0x7f08
2019-06-24 06:57:12.668 : nsconneg:Large sdu=8192,tdu=2097152,compression flg=0
2019-06-24 06:57:12.668 : nsconneg:after negotiation compression flag=0 
2019-06-24 06:57:12.668 : nsconneg:vsn=318, gbl=0xc01, sdu=8192, tdu=2097152
2019-06-24 06:57:12.668 : nscon:connect id = 0xc577
2019-06-24 06:57:12.668 : nscon:got 210 bytes connect data
2019-06-24 06:57:12.668 : nsdo:nsctxrnk=0
2019-06-24 06:57:12.668 : nsevdansw:exit
2019-06-24 06:57:12.668 : nttctl:entry
2019-06-24 06:57:12.668 : nttctl:Clearing non-blocking mode
2019-06-24 06:57:12.668 : nsevrec:event is 0x20, on 5
2019-06-24 06:57:12.668 : nsevwait:1 posted event(s)
2019-06-24 06:57:12.669 : nsglhe:entry
2019-06-24 06:57:12.669 : nsglfc:Assume establishing a connection...
2019-06-24 06:57:12.669 : nsglfc:command = establish
2019-06-24 06:57:12.669 : nsglauthorized:entry
2019-06-24 06:57:12.669 : nsglecmd:entry
2019-06-24 06:57:12.669 : nstoClearTimeout:ATO disabled for ctx=0x0xd7c440
2019-06-24 06:57:12.669 : nstoUpdateActive:Active timeout is -1 (see nstotyp)
2019-06-24 06:57:12.669 : nstoControlATO:ATO disabled for ctx=0x0xd7c440
2019-06-24 06:57:12.669 : nscontrol:cmd=26, lcl=0x0
2019-06-24 06:57:12.669 : nsglbgetRSPidx:entry
2019-06-24 06:57:12.669 : nsglbgetRSPidx:returning ecode=0
2019-06-24 06:57:12.669 : nsglbgetRSPidx:exit
2019-06-24 06:57:12.669 : nsgettrans_bycxdwattr:entry
2019-06-24 06:57:12.669 : ntgettrans:entry
2019-06-24 06:57:12.669 : snlinGetNameInfo:entry
2019-06-24 06:57:12.669 : snlinGetNameInfo:exit
2019-06-24 06:57:12.669 : snlinGetAddrInfo:entry
2019-06-24 06:57:12.669 : snlinGetAddrInfo:exit
2019-06-24 06:57:12.669 : snlinFreeAddrInfo:entry
2019-06-24 06:57:12.669 : snlinFreeAddrInfo:exit
2019-06-24 06:57:12.669 : ntgettrans:exit
2019-06-24 06:57:12.669 : nsgettrans_bycxdwattr:exit
2019-06-24 06:57:12.669 : nsglbgetSdPidx:secondary protocol=4
2019-06-24 06:57:12.669 : nsglb:entry
2019-06-24 06:57:12.669 : nsglb:exit
2019-06-24 06:57:12.669 : nsgettrans_bycxd:entry
2019-06-24 06:57:12.669 : ntgettrans:entry
2019-06-24 06:57:12.669 : ntgettrans:exit
2019-06-24 06:57:12.669 : nsgettrans_bycxd:exit
2019-06-24 06:57:12.669 : nsc2addr:(ADDRESS=(PROTOCOL=beq)(PROGRAM=/opt/oracle/product/19c/dbhome_1//bin/oracle)(ENVS='ORACLE_HOME=/opt/oracle/product/19c/dbhome_1/,ORACLE_SID=kl3db')(ARGV0=oraclekl3db)(ARGS='(LOCAL=NO)'))
2019-06-24 06:57:12.669 : nlpcaini:entry
2019-06-24 06:57:12.669 : nlpcaini:prg = /opt/oracle/product/19c/dbhome_1//bin/oracle
2019-06-24 06:57:12.669 : nlpcaini:arg[0] = oraclekl3db
2019-06-24 06:57:12.669 : nlpcaini:arg[1] = (LOCAL=NO)
2019-06-24 06:57:12.669 : nlpcaini:env[0] = XDG_SESSION_ID=405
2019-06-24 06:57:12.669 : nlpcaini:env[1] = HOSTNAME=kl3dbs
2019-06-24 06:57:12.669 : nlpcaini:env[2] = SHELL=/bin/bash
2019-06-24 06:57:12.669 : nlpcaini:env[3] = TERM=xterm-256color
2019-06-24 06:57:12.669 : nlpcaini:env[4] = HISTSIZE=1000
2019-06-24 06:57:12.669 : nlpcaini:env[5] = USER=oracle
2019-06-24 06:57:12.669 : nlpcaini:env[6] = LS_COLORS=rs=0:di=38;5;27:ln=38;5;51:mh=44;38;5;15:pi=40;38;5;11:so=38;5;13:do=38;5;5:bd=48;5;232;38;5;11:cd=48;5;232;38;5;3:or=48;5;232;38;5;9:mi=05;48;5;232;38;5;15:su=48;5;196;38;5;15:sg=48;5;11;38;5;16:ca=48;5;196;38;5;226:tw=48;5;10;38;5;16:ow=48;5;10;38;5;21:st=48;5;21;38;5;15:ex=38;5;34:*.tar=38;5;9:*.tgz=38;5;9:*.arc=38;5;9:*.arj=38;5;9:*.taz=38;5;9:*.lha=38;5;9:*.lz4=38;5;9:*.lzh=38;5;9:*.lzma=38;5;9:*.tlz=38;5;9:*.txz=38;5;9:*.tzo=38;5;9:*.t7z=38;5;9:*.zip=38;5;9:*.z=38;5;9:*.Z=38;5;9:*.dz=38;5;9:*.gz=38;5;9:*.lrz=38;5;9:*.lz=38;5;9:*.lzo=38;5;9:*.xz=38;5;9:*.bz2=38;5;9:*.bz=38;5;9:*.tbz=38;5;9:*.tbz2=38;5;9:*.tz=38;5;9:*.deb=38;5;9:*.rpm=38;5;9:*.jar=38;5;9:*.war=38;5;9:*.ear=38;5;9:*.sar=38;5;9:*.rar=38;5;9:*.alz=38;5;9:*.ace=38;5;9:*.zoo=38;5;9:*.cpio=38;5;9:*.7z=38;5;9:*.rz=38;5;9:*.cab=38;5;9:*.jpg=38;5;13:*.jpeg=38;5;13:*.gif=38;5;13:*.bmp=38;5;13:*.pbm=38;5;13:*.pgm=38;5;13:*.ppm=38;5;13:*.tga=38;5;13:*.xbm=38;5;13:*.xpm=38;5;13:*.tif=38;5;13:*.tiff=38;5;13:*.png=38;5;13:*.svg=38;5;13:*.svgz=38;5;13:*.mng=38;5;13:*.pcx=38;5;13:*.mov=38;5;13:*.mpg=38;5;13:*.mpeg=38;5;13:*.m2v=38;5;13:*.mkv=38;5;13:*.webm=38;5;13:*.ogm=38;5;13:*.mp4=38;5;13:*.m4v=38;5;13:*.mp4v=38;5;13:*.vob=38;5;13:*.qt=38;5;13:*.nuv=38;5;13:*.wmv=38;5;13:*.asf=38;5;13:*.rm=38;5;13:*.rmvb=38;5;13:*.flc=38;5;13:*.avi=38;5;13:*.fli=38;5;13:*.flv=38;5;13:*.gl=38;5;13:*.dl=38;5;13:*.xcf=38;5;13:*.xwd=38;5;13:*.yuv=38;5;13:*.cgm=38;5;13:*.emf=38;5;13:*.axv=38;5;13:*.anx=38;5;13:*.ogv=38;5;13:*.ogx=38;5;13:*.aac=38;5;45:*.au=38;5;45:*.flac=38;5;45:*.mid=38;5;45:*.midi=38;5;45:*.mka=38;5;45:*.mp3=38;5;45:*.mpc=38;5;45:*.ogg=38;5;45:*.ra=38;5;45:*.wav=38;5;45:*.axa=38;5;45:*.oga=38;5;45:*.spx=38;5;45:*.xspf=38;5;45:
2019-06-24 06:57:12.669 : nlpcaini:env[7] = ORACLE_SID=kl3db
2019-06-24 06:57:12.669 : nlpcaini:env[8] = MAIL=/var/spool/mail/oracle
2019-06-24 06:57:12.669 : nlpcaini:env[9] = PATH=/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/opt/oracle/product/19c/dbhome_1/bin:/home/oracle/.local/bin:/home/oracle/bin
2019-06-24 06:57:12.669 : nlpcaini:env[10] = PWD=/home/oracle
2019-06-24 06:57:12.669 : nlpcaini:env[11] = LANG=de_DE.UTF-8
2019-06-24 06:57:12.669 : nlpcaini:env[12] = HISTCONTROL=ignoredups
2019-06-24 06:57:12.669 : nlpcaini:env[13] = SHLVL=1
2019-06-24 06:57:12.669 : nlpcaini:env[14] = HOME=/home/oracle
2019-06-24 06:57:12.669 : nlpcaini:env[15] = LOGNAME=oracle
2019-06-24 06:57:12.669 : nlpcaini:env[16] = XDG_DATA_DIRS=/home/oracle/.local/share/flatpak/exports/share:/var/lib/flatpak/exports/share:/usr/local/share:/usr/share
2019-06-24 06:57:12.669 : nlpcaini:env[17] = LESSOPEN=||/usr/bin/lesspipe.sh %s
2019-06-24 06:57:12.669 : nlpcaini:env[18] = ORACLE_HOME=/opt/oracle/product/19c/dbhome_1/
2019-06-24 06:57:12.669 : nlpcaini:env[19] = _=/opt/oracle/product/19c/dbhome_1/bin/lsnrctl
2019-06-24 06:57:12.669 : nlpcaini:env[20] = ORA_NET2_DESC=6,9
2019-06-24 06:57:12.669 : nlpcaini:exit
2019-06-24 06:57:12.670 : nsbeqaddr:connecting...
2019-06-24 06:57:12.670 : nsmal:1952 bytes at 0xcef7b0
2019-06-24 06:57:12.670 : nsmal:3368 bytes at 0xcf0330
2019-06-24 06:57:12.670 : nsmal:168 bytes at 0xce7ba0
2019-06-24 06:57:12.670 : ntpcon:entry
2019-06-24 06:57:12.670 : ntpcon:toc = 6
2019-06-24 06:57:12.670 : ntpcon:exit
2019-06-24 06:57:12.670 : nsopen:opening transport...
2019-06-24 06:57:12.670 : ntpcon:entry
2019-06-24 06:57:12.670 : ntpcon:toc = 1
2019-06-24 06:57:12.670 : sntpcall:entry
2019-06-24 06:57:12.670 : sntpcall:detaching from parent with additional fork
2019-06-24 06:57:12.671 : sntpcall:hdl[IR]=20, hdl[IW]=19
2019-06-24 06:57:12.671 : ntpcon:exit
2019-06-24 06:57:12.671 : nsoptions:lcl[0]=0x0, lcl[1]=0x2006, gbl[0]=0x0, gbl[1]=0x0, cha=0x0
2019-06-24 06:57:12.671 : nsoptions:lcl[0]=0xf4ffe9ff, lcl[1]=0x6016, gbl[0]=0xe881, gbl[1]=0x0
2019-06-24 06:57:12.671 : nsopen:global context check-in (to slot 6) complete
2019-06-24 06:57:12.671 : nsopen:lcl[0]=0xf4ffe9ff, lcl[1]=0x6016, gbl[0]=0xe881, gbl[1]=0x0, tdu=4096, sdu=8192
2019-06-24 06:57:12.671 : nsfull_opn:cid=6, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0
2019-06-24 06:57:12.671 : nsfull_opn:nsctx: state=7, flg=0x4001, mvd=0
2019-06-24 06:57:12.671 : nsevreg:begin registration process for 6
2019-06-24 06:57:12.671 : nsevreg:sgt=0, evn=1, evt[2]=0x0
2019-06-24 06:57:12.671 : nsevreg:begin notification process for 6
2019-06-24 06:57:12.671 : nsevreg:rdm=0, sgt=0, evt[0]=0x800, [1]=0x800, [2]=0x0, nrg=0
2019-06-24 06:57:12.672 : nsevreg:registering for 0x800
2019-06-24 06:57:12.672 : ntpctl:entry
2019-06-24 06:57:12.672 : ntpctl:exit
2019-06-24 06:57:12.672 : nsglhe:exit
2019-06-24 06:57:12.672 : nsevwait:5 registered connection(s)
2019-06-24 06:57:12.672 : nsevwait:0 pre-posted event(s)
2019-06-24 06:57:12.672 : nsevwait:waiting for transport event (1 thru 6)...
2019-06-24 06:57:12.685 : nsevwait:0 newly-posted event(s)
2019-06-24 06:57:12.685 : nsgetaddr:entry
2019-06-24 06:57:12.685 : nserror:nsres: id=6, op=76, ns=12534, ns2=12560; nt[0]=504, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
2019-06-24 06:57:12.685 : nsevfnt:cxd: 0xd7c460 cid=6 stage 0: NT events set:
	READ
2019-06-24 06:57:12.685 : nsgetaddr:entry
2019-06-24 06:57:12.685 : nserror:nsres: id=6, op=76, ns=12534, ns2=12560; nt[0]=504, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
2019-06-24 06:57:12.685 : nsevfnt:cxd: 0xd7c460 cid=6 stage 0: NS events set:
2019-06-24 06:57:12.685 : nsevunreg:cid=6, sgt=0, rdm=0
2019-06-24 06:57:12.685 : nsevunreg:4 registered connection(s)
2019-06-24 06:57:12.685 : ntpctl:entry
2019-06-24 06:57:12.685 : sntpcall:result string is NTP0 16232
2019-06-24 06:57:12.685 : sntpcall:exit
2019-06-24 06:57:12.685 : ntpctl:exit
2019-06-24 06:57:12.685 : nsgetaddr:entry
2019-06-24 06:57:12.685 : nttaddr2bnd:entry
2019-06-24 06:57:12.685 : snlinGetNameInfo:entry
2019-06-24 06:57:12.685 : snlinGetNameInfo:exit
2019-06-24 06:57:12.685 : nttaddr2bnd:exit
2019-06-24 06:57:12.685 : nsbequeath_stg2:doing connect handshake...
2019-06-24 06:57:12.685 : nsgettrans_bycxd:entry
2019-06-24 06:57:12.685 : ntgettrans:entry
2019-06-24 06:57:12.685 : ntgettrans:exit
2019-06-24 06:57:12.685 : nsgettrans_bycxd:exit
2019-06-24 06:57:12.685 : nsbequeath:doing connect handshake...
2019-06-24 06:57:12.685 : ntpwr:entry
2019-06-24 06:57:12.685 : ntpwr:exit
2019-06-24 06:57:12.685 : ntpwr:entry
2019-06-24 06:57:12.685 : ntpwr:exit
2019-06-24 06:57:12.685 : ntpwr:entry
2019-06-24 06:57:12.685 : ntpwr:exit
2019-06-24 06:57:12.685 : ntprd:entry
2019-06-24 06:57:12.686 : ntprd:exit
2019-06-24 06:57:12.686 : ntprd:entry
2019-06-24 06:57:12.686 : ntprd:exit
2019-06-24 06:57:12.686 : nsbequeath:NSE=12586
2019-06-24 06:57:12.686 : nsbequeath:error reading REDIR/NSE msg
2019-06-24 06:57:12.686 : nserror:nsres: id=5, op=72, ns=12586, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0
2019-06-24 06:57:12.686 : nsdo:cid=5, opcode=67, *bl=0, *what=13, uflgs=0x0, cflgs=0x3
2019-06-24 06:57:12.686 : nsdo:rank=64, nsctxrnk=0
2019-06-24 06:57:12.686 : nsdo:nsctx: state=2, flg=0xa0004004, mvd=0
2019-06-24 06:57:12.686 : nsdo:gtn=284, gtc=284, ptn=10, ptc=8111
2019-06-24 06:57:12.686 : nscon:sending NSPTRS packet
2019-06-24 06:57:12.686 : nspsend:plen=8, type=11
2019-06-24 06:57:12.686 : nttwr:entry
2019-06-24 06:57:12.686 : nttwr:socket 14 had bytes written=8
2019-06-24 06:57:12.686 : nttwr:exit
2019-06-24 06:57:12.686 : nspsend:8 bytes to transport
2019-06-24 06:57:12.686 : nsdo:nsctxrnk=0
2019-06-24 06:57:12.686 : nsvntx_dei:entry
2019-06-24 06:57:12.686 : nsvntx_dei:exit
2019-06-24 06:57:12.686 : nstimarmed:no timer allocated
2019-06-24 06:57:12.686 : nstoClearTimeout:ATO disabled for ctx=0x0xcef7b0
2019-06-24 06:57:12.686 : nstoClearTimeout:STO disabled for ctx=0x0xcef7b0
2019-06-24 06:57:12.686 : nstoClearTimeout:RTO disabled for ctx=0x0xcef7b0
2019-06-24 06:57:12.686 : nstoClearTimeout:PITO disabled for ctx=0x0xcef7b0
2019-06-24 06:57:12.686 : nstoUpdateActive:Active timeout is -1 (see nstotyp)
2019-06-24 06:57:12.686 : ntpctl:entry
2019-06-24 06:57:12.686 : ntpctl:exit
2019-06-24 06:57:12.686 : ntpctl:entry
2019-06-24 06:57:12.686 : ntpctl:exit
2019-06-24 06:57:12.686 : nsfull_cls:cid=6, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x40
2019-06-24 06:57:12.686 : nsfull_cls:nsctx: state=8, flg=0x4001, mvd=0
2019-06-24 06:57:12.686 : nsclose:closing transport
2019-06-24 06:57:12.686 : ntpdisc:entry
2019-06-24 06:57:12.686 : ntpdisc:exit
2019-06-24 06:57:12.686 : nsclose:global context check-out (from slot 6) complete
2019-06-24 06:57:12.686 : nsvntx_dei:entry
2019-06-24 06:57:12.686 : nsvntx_dei:exit
2019-06-24 06:57:12.686 : nsmfr:3368 bytes at 0xcf0330
2019-06-24 06:57:12.686 : nsmfr:1952 bytes at 0xcef7b0
2019-06-24 06:57:12.686 : nsvntx_dei:entry
2019-06-24 06:57:12.686 : nsvntx_dei:exit
2019-06-24 06:57:12.686 : nstimarmed:no timer allocated
2019-06-24 06:57:12.686 : nsfull_cls:cid=5, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x40
2019-06-24 06:57:12.686 : nsfull_cls:nsctx: state=2, flg=0xa0004000, mvd=0
2019-06-24 06:57:12.686 : nsevunreg:cid=5, sgt=0, rdm=0
2019-06-24 06:57:12.686 : nsevunreg:3 registered connection(s)
2019-06-24 06:57:12.686 : nsclose:closing transport
2019-06-24 06:57:12.686 : nttdisc:entry
2019-06-24 06:57:12.686 : nttdisc:Closed socket 14
2019-06-24 06:57:12.686 : nttdisc:exit
2019-06-24 06:57:12.686 : nsclose:global context check-out (from slot 5) complete
2019-06-24 06:57:12.686 : nsvntx_dei:entry
2019-06-24 06:57:12.686 : nsvntx_dei:exit
2019-06-24 06:57:12.686 : nsmfr:3368 bytes at 0xceea80
2019-06-24 06:57:12.686 : nsmfr:1952 bytes at 0xd7c440
2019-06-24 06:57:12.686 : nsevrec:event is 0x20, on -1
2019-06-24 06:57:12.686 : nsevwait:1 posted event(s)
2019-06-24 06:57:12.686 : nsglhe:entry
2019-06-24 06:57:12.686 : nsgldissolve:entry
2019-06-24 06:57:12.686 : nsgldissolve:Deallocating cxd 0xd1ca50.
2019-06-24 06:57:12.686 : nsgldissolve:exit
2019-06-24 06:57:12.687 : nsglhe:exit
2019-06-24 06:57:12.687 : nsevwait:3 registered connection(s)
2019-06-24 06:57:12.687 : nsevwait:0 pre-posted event(s)
2019-06-24 06:57:12.687 : nsevwait:waiting for transport event (1 thru 4)...