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)...