Trace file /opt/oracle/diag/tnslsnr/kl3dbs/listener/trace/ora_2751_140073855573056.trc 2019-06-24 07:03:24.424 : nsglrespond:entry 2019-06-24 07:03:24.425 : nsdo:cid=7, opcode=67, *bl=82, *what=1, uflgs=0x0, cflgs=0x3 2019-06-24 07:03:24.425 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:03:24.425 : nsdo:nsctx: state=8, flg=0x2100400c, mvd=0 2019-06-24 07:03:24.425 : nsdo:gtn=227, gtc=227, ptn=10, ptc=8111 2019-06-24 07:03:24.425 : nsdo:82 bytes to NS buffer 2019-06-24 07:03:24.426 : nsdo:nsctxrnk=0 2019-06-24 07:03:24.426 : nsglrespond:exit 2019-06-24 07:03:24.426 : nsgldissolve:entry 2019-06-24 07:03:24.426 : nsvntx_dei:entry 2019-06-24 07:03:24.426 : nsvntx_dei:exit 2019-06-24 07:03:24.426 : nstimarmed:no timer allocated 2019-06-24 07:03:24.426 : nsfull_cls:cid=7, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0 2019-06-24 07:03:24.426 : nsfull_cls:nsctx: state=8, flg=0x21004008, mvd=82 2019-06-24 07:03:24.426 : nsevunreg:cid=7, sgt=0, rdm=0 2019-06-24 07:03:24.426 : nsevunreg:5 registered connection(s) 2019-06-24 07:03:24.426 : nsdo:cid=7, opcode=67, *bl=0, *what=1, uflgs=0x0, cflgs=0x1 2019-06-24 07:03:24.426 : nsdo:nsctx: state=8, flg=0x1004008, mvd=82 2019-06-24 07:03:24.426 : nsdo:gtn=227, gtc=227, ptn=92, ptc=8111 2019-06-24 07:03:24.426 : nsdofls:DATA flags: 0x40 2019-06-24 07:03:24.426 : nsdofls:sending NSPTDA packet 2019-06-24 07:03:24.426 : nspsend:plen=92, type=6 2019-06-24 07:03:24.426 : ntuswrte:entry 2019-06-24 07:03:24.426 : ntuswrte:Wrote on 17 bytes 92 2019-06-24 07:03:24.426 : ntuswrte:exit 2019-06-24 07:03:24.426 : nspsend:92 bytes to transport 2019-06-24 07:03:24.426 : nsclose:closing transport 2019-06-24 07:03:24.426 : ntusdisc:entry 2019-06-24 07:03:24.426 : ntusdisc:Closed socket 17 2019-06-24 07:03:24.426 : ntusdisc:exit 2019-06-24 07:03:24.426 : nsclose:global context check-out (from slot 7) complete 2019-06-24 07:03:24.426 : nsvntx_dei:entry 2019-06-24 07:03:24.426 : nsvntx_dei:exit 2019-06-24 07:03:24.426 : nsmfr:3368 bytes at 0x9d00f0 2019-06-24 07:03:24.426 : nsmfr:1952 bytes at 0x9d5cc0 2019-06-24 07:03:24.426 : nsgldissolve:Deallocating cxd 0x917ad0. 2019-06-24 07:03:24.426 : nsgldissolve:exit 2019-06-24 07:03:24.427 : nsevwait:5 registered connection(s) 2019-06-24 07:03:24.427 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:24.427 : nsevwait:waiting for transport event (1 thru 6)... 2019-06-24 07:03:47.957 : nsevwait:0 newly-posted event(s) 2019-06-24 07:03:47.958 : nsgetaddr:entry 2019-06-24 07:03:47.958 : nttaddr2bnd:entry 2019-06-24 07:03:47.958 : snlinGetNameInfo:entry 2019-06-24 07:03:47.958 : snlinGetNameInfo:exit 2019-06-24 07:03:47.958 : nttaddr2bnd:exit 2019-06-24 07:03:47.958 : nsevfnt:cxd: 0x8ab680 cid=1 stage 0: NT events set: CONNECTION REQUEST 2019-06-24 07:03:47.958 : nsgetaddr:entry 2019-06-24 07:03:47.958 : nttaddr2bnd:entry 2019-06-24 07:03:47.958 : snlinGetNameInfo:entry 2019-06-24 07:03:47.958 : snlinGetNameInfo:exit 2019-06-24 07:03:47.958 : nttaddr2bnd:exit 2019-06-24 07:03:47.958 : nsevfnt:cxd: 0x8ab680 cid=1 stage 0: NS events set: INCOMING CALL 2019-06-24 07:03:47.958 : nsevrec:event is 0x1, on 1 2019-06-24 07:03:47.958 : nsevwait:1 pre-posted event(s) 2019-06-24 07:03:47.958 : nsevrec:event is 0x1, on 1 2019-06-24 07:03:47.958 : nsevwait:1 posted event(s) 2019-06-24 07:03:47.958 : nsglhe:entry 2019-06-24 07:03:47.958 : nsmal:1952 bytes at 0x9d5cc0 2019-06-24 07:03:47.958 : nsmal:3368 bytes at 0x9d00f0 2019-06-24 07:03:47.958 : nstoSetupTimeout:ATO enabled for ctx=0x0x9d5cc0, val=60000(millisecs) 2019-06-24 07:03:47.958 : nstoUpdateActive:Active timeout is 0 (see nstotyp) 2019-06-24 07:03:47.958 : nsopen:opening transport... 2019-06-24 07:03:47.958 : nttcon:entry 2019-06-24 07:03:47.958 : nttcon:toc = 3 2019-06-24 07:03:47.959 : nttcnp:entry 2019-06-24 07:03:47.959 : nttcnp:getting sockname 2019-06-24 07:03:47.959 : nttcnp:getting peername 2019-06-24 07:03:47.959 : nttcnp:exit 2019-06-24 07:03:47.959 : nttcnr:entry 2019-06-24 07:03:47.959 : nttcnr:waiting to accept a connection. 2019-06-24 07:03:47.959 : nttcnr:getting sockname 2019-06-24 07:03:47.959 : snlinGetNameInfo:entry 2019-06-24 07:03:47.959 : snlinGetNameInfo:exit 2019-06-24 07:03:47.959 : nttcnr:connected on source ipaddr 192.168.110.74 port 1524 2019-06-24 07:03:47.959 : snlinGetNameInfo:entry 2019-06-24 07:03:47.959 : snlinGetNameInfo:exit 2019-06-24 07:03:47.959 : nttcnr:connected on destination ipaddr 192.168.110.74 port 36328 2019-06-24 07:03:47.959 : nttvlser:entry 2019-06-24 07:03:47.959 : nlvlsern:entry 2019-06-24 07:03:47.959 : snlinGetNameInfo:entry 2019-06-24 07:03:47.959 : snlinGetNameInfo:exit 2019-06-24 07:03:47.959 : nttvlser:valid node check on incoming node 192.168.110.74 2019-06-24 07:03:47.959 : nttvlser:Accepted Entry: 192.168.110.74 2019-06-24 07:03:47.959 : nttcnr:exit 2019-06-24 07:03:47.959 : nttctl:entry 2019-06-24 07:03:47.959 : nttctl:Setting connection into nodelay mode 2019-06-24 07:03:47.959 : nttctl:set TCP_NODELAY on 17 2019-06-24 07:03:47.959 : nttcon:exit 2019-06-24 07:03:47.959 : nsopen:transport is open 2019-06-24 07:03:47.959 : nsnainit:answer 2019-06-24 07:03:47.959 : nsnasvnainfo:NA disabled for this connection 2019-06-24 07:03:47.960 : nainit:entry 2019-06-24 07:03:47.960 : nagblini:entry 2019-06-24 07:03:47.960 : nau_gin:entry 2019-06-24 07:03:47.960 : nau_gparams:entry 2019-06-24 07:03:47.960 : nam_gbp:Reading parameter "sqlnet.authentication_required" from parameter file 2019-06-24 07:03:47.960 : nam_gbp:Parameter not found 2019-06-24 07:03:47.960 : nau_gparams:Using default value "FALSE" 2019-06-24 07:03:47.960 : nau_gslf:entry 2019-06-24 07:03:47.960 : nam_gic:entry 2019-06-24 07:03:47.960 : nam_gic:Counting # of items in "sqlnet.authentication_services" parameter 2019-06-24 07:03:47.960 : nam_gic:Parameter not found 2019-06-24 07:03:47.960 : nam_gic:Found 0 items 2019-06-24 07:03:47.960 : nam_gic:exit 2019-06-24 07:03:47.960 : nau_gslf:Using default value "all available adapters" 2019-06-24 07:03:47.960 : nauss_set_state:entry 2019-06-24 07:03:47.960 : nauss_set_state:exit 2019-06-24 07:03:47.960 : nau_gslf:exit 2019-06-24 07:03:47.960 : nau_gparams:exit 2019-06-24 07:03:47.960 : nau_gin:exit 2019-06-24 07:03:47.960 : nagblini:exit 2019-06-24 07:03:47.960 : na_saveprot:entry 2019-06-24 07:03:47.960 : na_saveprot:exit 2019-06-24 07:03:47.960 : nacomin:entry 2019-06-24 07:03:47.960 : nas_init:entry 2019-06-24 07:03:47.960 : nas_init:exit 2019-06-24 07:03:47.960 : nau_ini:entry 2019-06-24 07:03:47.960 : naugcp_get_connect_parameters:entry 2019-06-24 07:03:47.960 : nauss_set_state:entry 2019-06-24 07:03:47.960 : nauss_set_state:exit 2019-06-24 07:03:47.960 : naugcp_get_connect_parameters:exit 2019-06-24 07:03:47.960 : nau_gfauth:entry 2019-06-24 07:03:47.961 : nam_gbp:Reading parameter "sqlnet.fallback_authentication" from parameter file 2019-06-24 07:03:47.961 : nam_gbp:Parameter not found 2019-06-24 07:03:47.961 : nau_gfauth:Using default value "FALSE" 2019-06-24 07:03:47.961 : nau_gfauth:exit 2019-06-24 07:03:47.961 : nau_gettab:entry 2019-06-24 07:03:47.961 : nau_gettab:exit 2019-06-24 07:03:47.961 : nau_sini:entry 2019-06-24 07:03:47.961 : nau_sini:exit 2019-06-24 07:03:47.961 : nau_ini:exit 2019-06-24 07:03:47.961 : naeeinit:entry 2019-06-24 07:03:47.961 : nam_gbp:Reading parameter "SQLNET.FIPS_140" from parameter file 2019-06-24 07:03:47.961 : nam_gbp:Parameter not found 2019-06-24 07:03:47.961 : naeconp:entry 2019-06-24 07:03:47.961 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string. 2019-06-24 07:03:47.961 : nam_gnsp:Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter file 2019-06-24 07:03:47.961 : nam_gnsp:Parameter not found 2019-06-24 07:03:47.961 : naequad:Using default value "ACCEPTED" 2019-06-24 07:03:47.961 : nae_get_boolean_param_from_conn_string:entry 2019-06-24 07:03:47.961 : nae_get_boolean_param_from_conn_string:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string. 2019-06-24 07:03:47.961 : nam_gbp:Reading parameter "SQLNET.IGNORE_ANO_ENCRYPTION_FOR_TCPS" from parameter file 2019-06-24 07:03:47.961 : nam_gbp:Parameter not found 2019-06-24 07:03:47.961 : nam_gbp:Reading parameter "SQLNET._ENHANCED_LOGON_PROTECTION" from parameter file 2019-06-24 07:03:47.961 : nam_gbp:Parameter not found 2019-06-24 07:03:47.961 : nam_gic:entry 2019-06-24 07:03:47.961 : nam_gic:Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER" parameter 2019-06-24 07:03:47.961 : nam_gic:Parameter not found 2019-06-24 07:03:47.961 : nam_gic:exit 2019-06-24 07:03:47.961 : naesno:Using default value "all available algorithms" 2019-06-24 07:03:47.961 : naeshow:entry 2019-06-24 07:03:47.962 : naeshow:exit 2019-06-24 07:03:47.962 : naeeinit:exit 2019-06-24 07:03:47.962 : naecinit:entry 2019-06-24 07:03:47.962 : naeconp:entry 2019-06-24 07:03:47.962 : naeconp:Reading ENCRYPTION_CLIENT or CRYPTO_CHECKSUM_CLIENT parameter from connection string. 2019-06-24 07:03:47.962 : nam_gnsp:Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from parameter file 2019-06-24 07:03:47.962 : nam_gnsp:Parameter not found 2019-06-24 07:03:47.962 : naequad:Using default value "ACCEPTED" 2019-06-24 07:03:47.962 : nam_gic:entry 2019-06-24 07:03:47.962 : nam_gic:Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER" parameter 2019-06-24 07:03:47.962 : nam_gic:Parameter not found 2019-06-24 07:03:47.962 : nam_gic:exit 2019-06-24 07:03:47.962 : naesno:Using default value "all available algorithms" 2019-06-24 07:03:47.962 : naeshow:entry 2019-06-24 07:03:47.962 : naeshow:exit 2019-06-24 07:03:47.962 : naecinit:exit 2019-06-24 07:03:47.962 : nadisc:entry 2019-06-24 07:03:47.962 : nacomtm:entry 2019-06-24 07:03:47.962 : nacompd:entry 2019-06-24 07:03:47.962 : nacompd:exit 2019-06-24 07:03:47.963 : nacompd:entry 2019-06-24 07:03:47.963 : nacompd:exit 2019-06-24 07:03:47.963 : nacomtm:exit 2019-06-24 07:03:47.963 : nas_dis:entry 2019-06-24 07:03:47.963 : nas_dis:exit 2019-06-24 07:03:47.963 : nau_dis:entry 2019-06-24 07:03:47.963 : nau_dis:exit 2019-06-24 07:03:47.963 : naeetrm:entry 2019-06-24 07:03:47.963 : naeetrm:exit 2019-06-24 07:03:47.963 : naectrm:entry 2019-06-24 07:03:47.963 : naectrm:exit 2019-06-24 07:03:47.963 : nagbltrm:entry 2019-06-24 07:03:47.963 : nau_gtm:entry 2019-06-24 07:03:47.963 : nau_gtm:exit 2019-06-24 07:03:47.963 : nagbltrm:exit 2019-06-24 07:03:47.963 : nadisc:exit 2019-06-24 07:03:47.963 : nainit:exit 2019-06-24 07:03:47.963 : nsnainit:NS Connection version: 318 2019-06-24 07:03:47.963 : nsnainit:inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd 2019-06-24 07:03:47.963 : 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 07:03:47.963 : 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 07:03:47.963 : nsopen:global context check-in (to slot 7) complete 2019-06-24 07:03:47.963 : nsopen:lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x7ebf, gbl[1]=0x2001, tdu=2097152, sdu=8192 2019-06-24 07:03:47.963 : nsfull_opn:cid=7, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0 2019-06-24 07:03:47.963 : nsfull_opn:nsctx: state=7, flg=0x4000, mvd=0 2019-06-24 07:03:47.963 : nsanswer:deferring connect attempt; at stage 5 2019-06-24 07:03:47.963 : nsgetaddr:entry 2019-06-24 07:03:47.963 : nttaddr2bnd:entry 2019-06-24 07:03:47.963 : snlinGetNameInfo:entry 2019-06-24 07:03:47.963 : snlinGetNameInfo:exit 2019-06-24 07:03:47.964 : nttaddr2bnd:exit 2019-06-24 07:03:47.964 : nsevreg:begin registration process for 7 2019-06-24 07:03:47.964 : nsevreg:sgt=0, evn=1, evt[2]=0x0 2019-06-24 07:03:47.964 : nsevreg:begin notification process for 7 2019-06-24 07:03:47.964 : nsevreg:rdm=0, sgt=0, evt[0]=0x20, [1]=0x20, [2]=0x0, nrg=0 2019-06-24 07:03:47.964 : nsevreg:registering for 0x20 2019-06-24 07:03:47.964 : nsglhe:exit 2019-06-24 07:03:47.964 : nsevwait:6 registered connection(s) 2019-06-24 07:03:47.964 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:47.964 : nsevwait:waiting for transport event (1 thru 7)... 2019-06-24 07:03:47.964 : nsevwait:0 newly-posted event(s) 2019-06-24 07:03:47.964 : nsgetaddr:entry 2019-06-24 07:03:47.964 : nttaddr2bnd:entry 2019-06-24 07:03:47.964 : snlinGetNameInfo:entry 2019-06-24 07:03:47.964 : snlinGetNameInfo:exit 2019-06-24 07:03:47.964 : nttaddr2bnd:exit 2019-06-24 07:03:47.964 : nsevfnt:cxd: 0x917ad0 cid=7 stage 5: NT events set: READ 2019-06-24 07:03:47.964 : nsgetaddr:entry 2019-06-24 07:03:47.964 : nttaddr2bnd:entry 2019-06-24 07:03:47.964 : snlinGetNameInfo:entry 2019-06-24 07:03:47.964 : snlinGetNameInfo:exit 2019-06-24 07:03:47.964 : nttaddr2bnd:exit 2019-06-24 07:03:47.964 : nsevfnt:cxd: 0x917ad0 cid=7 stage 5: NS events set: OUTGOING CALL COMPLETE (ADDRESS=(PROTOCOL=tcp)(HOST=192.168.110.74)(PORT=36328)) 2019-06-24 07:03:47.964 : nsevdansw:at STAGE 5 2019-06-24 07:03:47.964 : nsdo:cid=7, opcode=68, *bl=2040, *what=8, uflgs=0x0, cflgs=0x3 2019-06-24 07:03:47.964 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:03:47.964 : nsdo:nsctx: state=14, flg=0x20004004, mvd=0 2019-06-24 07:03:47.964 : nsdo:gtn=10, gtc=10, ptn=10, ptc=8111 2019-06-24 07:03:47.964 : nscon:doing connect handshake... 2019-06-24 07:03:47.964 : nscon:recving a packet 2019-06-24 07:03:47.964 : nsprecv:reading from transport... 2019-06-24 07:03:47.964 : nttrd:entry 2019-06-24 07:03:47.964 : nttrd:socket 17 had bytes read=284 2019-06-24 07:03:47.964 : nttrd:exit 2019-06-24 07:03:47.964 : nsprecv:284 bytes from transport 2019-06-24 07:03:47.964 : nsprecv:tlen=284, plen=284, type=1 2019-06-24 07:03:47.964 : nscon:got NSPTCN packet 2019-06-24 07:03:47.964 : nsconneg:vsn=318, lov=300, opt=0xc41, sdu=8192, tdu=65535, ntc=0x7f08 2019-06-24 07:03:47.964 : nsconneg:Large sdu=8192,tdu=2097152,compression flg=0 2019-06-24 07:03:47.964 : nsconneg:after negotiation compression flag=0 2019-06-24 07:03:47.964 : nsconneg:vsn=318, gbl=0xc01, sdu=8192, tdu=2097152 2019-06-24 07:03:47.964 : nscon:connect id = 0x3fc9 2019-06-24 07:03:47.964 : nscon:got 210 bytes connect data 2019-06-24 07:03:47.964 : nsdo:nsctxrnk=0 2019-06-24 07:03:47.964 : nsevdansw:exit 2019-06-24 07:03:47.964 : nttctl:entry 2019-06-24 07:03:47.964 : nttctl:Clearing non-blocking mode 2019-06-24 07:03:47.964 : nsevrec:event is 0x20, on 7 2019-06-24 07:03:47.964 : nsevwait:1 posted event(s) 2019-06-24 07:03:47.964 : nsglhe:entry 2019-06-24 07:03:47.964 : nsglfc:Assume establishing a connection... 2019-06-24 07:03:47.964 : nsglfc:command = establish 2019-06-24 07:03:47.964 : nsglauthorized:entry 2019-06-24 07:03:47.964 : nsglecmd:entry 2019-06-24 07:03:47.964 : nstoClearTimeout:ATO disabled for ctx=0x0x9d5cc0 2019-06-24 07:03:47.964 : nstoUpdateActive:Active timeout is -1 (see nstotyp) 2019-06-24 07:03:47.964 : nstoControlATO:ATO disabled for ctx=0x0x9d5cc0 2019-06-24 07:03:47.964 : nscontrol:cmd=26, lcl=0x0 2019-06-24 07:03:47.964 : nsglbgetRSPidx:entry 2019-06-24 07:03:47.964 : nsglbgetRSPidx:returning ecode=0 2019-06-24 07:03:47.964 : nsglbgetRSPidx:exit 2019-06-24 07:03:47.964 : nsgettrans_bycxdwattr:entry 2019-06-24 07:03:47.964 : ntgettrans:entry 2019-06-24 07:03:47.965 : snlinGetNameInfo:entry 2019-06-24 07:03:47.965 : snlinGetNameInfo:exit 2019-06-24 07:03:47.965 : snlinGetAddrInfo:entry 2019-06-24 07:03:47.965 : snlinGetAddrInfo:exit 2019-06-24 07:03:47.965 : snlinFreeAddrInfo:entry 2019-06-24 07:03:47.965 : snlinFreeAddrInfo:exit 2019-06-24 07:03:47.965 : ntgettrans:exit 2019-06-24 07:03:47.965 : nsgettrans_bycxdwattr:exit 2019-06-24 07:03:47.965 : nsglbgetSdPidx:secondary protocol=4 2019-06-24 07:03:47.965 : nsglb:entry 2019-06-24 07:03:47.965 : nsglb:instance:KL3DB new lbscore:1 2019-06-24 07:03:47.965 : nsglb:exit 2019-06-24 07:03:47.965 : nsgettrans_bycxd:entry 2019-06-24 07:03:47.965 : ntgettrans:entry 2019-06-24 07:03:47.965 : ntgettrans:exit 2019-06-24 07:03:47.965 : nsgettrans_bycxd:exit 2019-06-24 07:03:47.965 : nsc2addr:(ADDRESS=(PROTOCOL=BEQ)(PROGRAM=/opt/oracle/product/19c/dbhome_1/bin/oracle)(ARGV0='oracleKL3DB')(ARGS='(LOCAL=NO)')(ENVS='XDG_SESSION_ID=313,LISTENER_NAME=LISTENER,HOSTNAME=kl3dbs,TEMPLATE_NAME=General_Purpose.dbc,SHELL=/bin/bash,TERM=xterm-256color,HISTSIZE=1000,USER=oracle,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:,ORACLE_SID=KL3DB,PDB_NAME=ORCLPDB1,CREATE_AS_CDB=true,ORACLE_VERSION=19c,MAIL=/var/spool/mail/root,PATH=,_=/opt/oracle/product/19c/dbhome_1/bin/sqlplus,PWD=/root,LANG=de_DE.UTF-8,NUMBER_OF_PDBS=1,HISTCONTROL=ignoredups,SHLVL=3,HOME=/home/oracle,LOGNAME=oracle,XDG_DATA_DIRS=/root/.local/share/flatpak/exports/share:/var/lib/flatpak/exports/share:/usr/local/share:/usr/share,LESSOPEN=||/usr/bin/lesspipe.sh %s,XDG_RUNTIME_DIR=/run/user/54321,DISPLAY=localhost:10.0,ORACLE_HOME=/opt/oracle/product/19c/dbhome_1,CHARSET=AL32UTF8,ORA_NET2_DESC=9,12,SKGP_SPAWN_DIAG_POST_FORK_TS=,SKGP_HIDDEN_ARGS=,SKGP_SPAWN_DIAG_PRE_FORK_TS=,SKGP_SPAWN_DIAG_PRE_EXEC_TS=,ORACLE_SPAWNED_PROCESS=1,RDMAV_FORK_SAFE=1,RDMAV_HUGEPAGES_SAFE=1')(ENV_POLICY=NONE)) 2019-06-24 07:03:47.965 : nlpcaini:entry 2019-06-24 07:03:47.965 : nlpcaini:prg = /opt/oracle/product/19c/dbhome_1/bin/oracle 2019-06-24 07:03:47.965 : nlpcaini:arg[0] = oracleKL3DB 2019-06-24 07:03:47.965 : nlpcaini:arg[1] = (LOCAL=NO) 2019-06-24 07:03:47.965 : nlpcaini:env[0] = XDG_SESSION_ID=313 2019-06-24 07:03:47.965 : nlpcaini:env[1] = LISTENER_NAME=LISTENER 2019-06-24 07:03:47.965 : nlpcaini:env[2] = HOSTNAME=kl3dbs 2019-06-24 07:03:47.965 : nlpcaini:env[3] = TEMPLATE_NAME=General_Purpose.dbc 2019-06-24 07:03:47.965 : nlpcaini:env[4] = SHELL=/bin/bash 2019-06-24 07:03:47.965 : nlpcaini:env[5] = TERM=xterm-256color 2019-06-24 07:03:47.965 : nlpcaini:env[6] = HISTSIZE=1000 2019-06-24 07:03:47.965 : nlpcaini:env[7] = USER=oracle 2019-06-24 07:03:47.965 : nlpcaini:env[8] = 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 07:03:47.965 : nlpcaini:env[9] = ORACLE_SID=KL3DB 2019-06-24 07:03:47.965 : nlpcaini:env[10] = PDB_NAME=ORCLPDB1 2019-06-24 07:03:47.965 : nlpcaini:env[11] = CREATE_AS_CDB=true 2019-06-24 07:03:47.965 : nlpcaini:env[12] = ORACLE_VERSION=19c 2019-06-24 07:03:47.965 : nlpcaini:env[13] = MAIL=/var/spool/mail/root 2019-06-24 07:03:47.965 : nlpcaini:env[14] = PATH= 2019-06-24 07:03:47.965 : nlpcaini:env[15] = _=/opt/oracle/product/19c/dbhome_1/bin/sqlplus 2019-06-24 07:03:47.965 : nlpcaini:env[16] = PWD=/root 2019-06-24 07:03:47.965 : nlpcaini:env[17] = LANG=de_DE.UTF-8 2019-06-24 07:03:47.965 : nlpcaini:env[18] = NUMBER_OF_PDBS=1 2019-06-24 07:03:47.965 : nlpcaini:env[19] = HISTCONTROL=ignoredups 2019-06-24 07:03:47.965 : nlpcaini:env[20] = SHLVL=3 2019-06-24 07:03:47.965 : nlpcaini:env[21] = HOME=/home/oracle 2019-06-24 07:03:47.965 : nlpcaini:env[22] = LOGNAME=oracle 2019-06-24 07:03:47.965 : nlpcaini:env[23] = XDG_DATA_DIRS=/root/.local/share/flatpak/exports/share:/var/lib/flatpak/exports/share:/usr/local/share:/usr/share 2019-06-24 07:03:47.965 : nlpcaini:env[24] = LESSOPEN=||/usr/bin/lesspipe.sh %s 2019-06-24 07:03:47.965 : nlpcaini:env[25] = XDG_RUNTIME_DIR=/run/user/54321 2019-06-24 07:03:47.965 : nlpcaini:env[26] = DISPLAY=localhost:10.0 2019-06-24 07:03:47.965 : nlpcaini:env[27] = ORACLE_HOME=/opt/oracle/product/19c/dbhome_1 2019-06-24 07:03:47.965 : nlpcaini:env[28] = CHARSET=AL32UTF8 2019-06-24 07:03:47.965 : nlpcaini:env[29] = ORA_NET2_DESC=9,12 2019-06-24 07:03:47.965 : nlpcaini:env[30] = SKGP_SPAWN_DIAG_POST_FORK_TS= 2019-06-24 07:03:47.965 : nlpcaini:env[31] = SKGP_HIDDEN_ARGS= 2019-06-24 07:03:47.965 : nlpcaini:env[32] = SKGP_SPAWN_DIAG_PRE_FORK_TS= 2019-06-24 07:03:47.965 : nlpcaini:env[33] = SKGP_SPAWN_DIAG_PRE_EXEC_TS= 2019-06-24 07:03:47.965 : nlpcaini:env[34] = ORACLE_SPAWNED_PROCESS=1 2019-06-24 07:03:47.965 : nlpcaini:env[35] = RDMAV_FORK_SAFE=1 2019-06-24 07:03:47.965 : nlpcaini:env[36] = RDMAV_HUGEPAGES_SAFE=1 2019-06-24 07:03:47.965 : nlpcaini:exit 2019-06-24 07:03:47.965 : nsbeqaddr:connecting... 2019-06-24 07:03:47.965 : nsmal:1952 bytes at 0x9d0e20 2019-06-24 07:03:47.965 : nsmal:3368 bytes at 0x9dc360 2019-06-24 07:03:47.965 : nsmal:168 bytes at 0x9d6680 2019-06-24 07:03:47.965 : ntpcon:entry 2019-06-24 07:03:47.965 : ntpcon:toc = 6 2019-06-24 07:03:47.965 : ntpcon:exit 2019-06-24 07:03:47.965 : nsopen:opening transport... 2019-06-24 07:03:47.965 : ntpcon:entry 2019-06-24 07:03:47.965 : ntpcon:toc = 1 2019-06-24 07:03:47.965 : sntpcall:entry 2019-06-24 07:03:47.967 : sntpcall:detaching from parent with additional fork 2019-06-24 07:03:47.968 : sntpcall:hdl[IR]=22, hdl[IW]=21 2019-06-24 07:03:47.968 : ntpcon:exit 2019-06-24 07:03:47.968 : nsoptions:lcl[0]=0x0, lcl[1]=0x2006, gbl[0]=0x0, gbl[1]=0x0, cha=0x0 2019-06-24 07:03:47.968 : nsoptions:lcl[0]=0xf4ffe9ff, lcl[1]=0x6016, gbl[0]=0xe881, gbl[1]=0x0 2019-06-24 07:03:47.968 : nsopen:global context check-in (to slot 8) complete 2019-06-24 07:03:47.968 : nsopen:lcl[0]=0xf4ffe9ff, lcl[1]=0x6016, gbl[0]=0xe881, gbl[1]=0x0, tdu=4096, sdu=8192 2019-06-24 07:03:47.968 : nsfull_opn:cid=8, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x0 2019-06-24 07:03:47.968 : nsfull_opn:nsctx: state=7, flg=0x4001, mvd=0 2019-06-24 07:03:47.968 : nsevreg:begin registration process for 8 2019-06-24 07:03:47.968 : nsevreg:sgt=0, evn=1, evt[2]=0x0 2019-06-24 07:03:47.968 : nsevreg:begin notification process for 8 2019-06-24 07:03:47.968 : nsevreg:rdm=0, sgt=0, evt[0]=0x800, [1]=0x800, [2]=0x0, nrg=0 2019-06-24 07:03:47.968 : nsevreg:registering for 0x800 2019-06-24 07:03:47.968 : ntpctl:entry 2019-06-24 07:03:47.968 : ntpctl:exit 2019-06-24 07:03:47.968 : nsglhe:exit 2019-06-24 07:03:47.968 : nsevwait:7 registered connection(s) 2019-06-24 07:03:47.968 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:47.968 : nsevwait:waiting for transport event (1 thru 8)... 2019-06-24 07:03:47.988 : nsevwait:0 newly-posted event(s) 2019-06-24 07:03:47.988 : nsgetaddr:entry 2019-06-24 07:03:47.988 : nserror:nsres: id=8, 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 07:03:47.988 : nsevfnt:cxd: 0x9d5ce0 cid=8 stage 0: NT events set: READ 2019-06-24 07:03:47.988 : nsgetaddr:entry 2019-06-24 07:03:47.988 : nserror:nsres: id=8, 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 07:03:47.988 : nsevfnt:cxd: 0x9d5ce0 cid=8 stage 0: NS events set: 2019-06-24 07:03:47.988 : nsevunreg:cid=8, sgt=0, rdm=0 2019-06-24 07:03:47.988 : nsevunreg:6 registered connection(s) 2019-06-24 07:03:47.988 : ntpctl:entry 2019-06-24 07:03:47.988 : sntpcall:result string is NTP0 16723 2019-06-24 07:03:47.988 : sntpcall:exit 2019-06-24 07:03:47.988 : ntpctl:exit 2019-06-24 07:03:47.988 : nsgetaddr:entry 2019-06-24 07:03:47.988 : nttaddr2bnd:entry 2019-06-24 07:03:47.988 : snlinGetNameInfo:entry 2019-06-24 07:03:47.988 : snlinGetNameInfo:exit 2019-06-24 07:03:47.988 : nttaddr2bnd:exit 2019-06-24 07:03:47.988 : nsbequeath_stg2:doing connect handshake... 2019-06-24 07:03:47.988 : nsgettrans_bycxd:entry 2019-06-24 07:03:47.988 : ntgettrans:entry 2019-06-24 07:03:47.988 : ntgettrans:exit 2019-06-24 07:03:47.988 : nsgettrans_bycxd:exit 2019-06-24 07:03:47.988 : nsbequeath:doing connect handshake... 2019-06-24 07:03:47.988 : ntpwr:entry 2019-06-24 07:03:47.988 : ntpwr:exit 2019-06-24 07:03:47.988 : ntpwr:entry 2019-06-24 07:03:47.988 : ntpwr:exit 2019-06-24 07:03:47.988 : ntpwr:entry 2019-06-24 07:03:47.988 : ntpwr:exit 2019-06-24 07:03:47.988 : ntprd:entry 2019-06-24 07:03:47.988 : ntprd:exit 2019-06-24 07:03:47.988 : ntprd:entry 2019-06-24 07:03:47.988 : ntprd:exit 2019-06-24 07:03:47.988 : nsbequeath:NSE=12586 2019-06-24 07:03:47.988 : nsbequeath:error reading REDIR/NSE msg 2019-06-24 07:03:47.988 : nserror:nsres: id=7, 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 07:03:47.988 : nsdo:cid=7, opcode=67, *bl=0, *what=13, uflgs=0x0, cflgs=0x3 2019-06-24 07:03:47.988 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:03:47.988 : nsdo:nsctx: state=2, flg=0xa0004004, mvd=0 2019-06-24 07:03:47.988 : nsdo:gtn=284, gtc=284, ptn=10, ptc=8111 2019-06-24 07:03:47.988 : nscon:sending NSPTRS packet 2019-06-24 07:03:47.988 : nspsend:plen=8, type=11 2019-06-24 07:03:47.988 : nttwr:entry 2019-06-24 07:03:47.989 : nttwr:socket 17 had bytes written=8 2019-06-24 07:03:47.989 : nttwr:exit 2019-06-24 07:03:47.989 : nspsend:8 bytes to transport 2019-06-24 07:03:47.989 : nsdo:nsctxrnk=0 2019-06-24 07:03:47.989 : nsvntx_dei:entry 2019-06-24 07:03:47.989 : nsvntx_dei:exit 2019-06-24 07:03:47.989 : nstimarmed:no timer allocated 2019-06-24 07:03:47.989 : nstoClearTimeout:ATO disabled for ctx=0x0x9d0e20 2019-06-24 07:03:47.989 : nstoClearTimeout:STO disabled for ctx=0x0x9d0e20 2019-06-24 07:03:47.989 : nstoClearTimeout:RTO disabled for ctx=0x0x9d0e20 2019-06-24 07:03:47.989 : nstoClearTimeout:PITO disabled for ctx=0x0x9d0e20 2019-06-24 07:03:47.989 : nstoUpdateActive:Active timeout is -1 (see nstotyp) 2019-06-24 07:03:47.989 : ntpctl:entry 2019-06-24 07:03:47.989 : ntpctl:exit 2019-06-24 07:03:47.989 : ntpctl:entry 2019-06-24 07:03:47.989 : ntpctl:exit 2019-06-24 07:03:47.989 : nsfull_cls:cid=8, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x40 2019-06-24 07:03:47.989 : nsfull_cls:nsctx: state=8, flg=0x4001, mvd=0 2019-06-24 07:03:47.989 : nsclose:closing transport 2019-06-24 07:03:47.989 : ntpdisc:entry 2019-06-24 07:03:47.989 : ntpdisc:exit 2019-06-24 07:03:47.989 : nsclose:global context check-out (from slot 8) complete 2019-06-24 07:03:47.989 : nsvntx_dei:entry 2019-06-24 07:03:47.989 : nsvntx_dei:exit 2019-06-24 07:03:47.989 : nsmfr:3368 bytes at 0x9dc360 2019-06-24 07:03:47.989 : nsmfr:1952 bytes at 0x9d0e20 2019-06-24 07:03:47.989 : nsvntx_dei:entry 2019-06-24 07:03:47.989 : nsvntx_dei:exit 2019-06-24 07:03:47.989 : nstimarmed:no timer allocated 2019-06-24 07:03:47.989 : nsfull_cls:cid=7, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x40 2019-06-24 07:03:47.989 : nsfull_cls:nsctx: state=2, flg=0xa0004000, mvd=0 2019-06-24 07:03:47.989 : nsevunreg:cid=7, sgt=0, rdm=0 2019-06-24 07:03:47.989 : nsevunreg:5 registered connection(s) 2019-06-24 07:03:47.989 : nsclose:closing transport 2019-06-24 07:03:47.989 : nttdisc:entry 2019-06-24 07:03:47.989 : nttdisc:Closed socket 17 2019-06-24 07:03:47.989 : nttdisc:exit 2019-06-24 07:03:47.989 : nsclose:global context check-out (from slot 7) complete 2019-06-24 07:03:47.989 : nsvntx_dei:entry 2019-06-24 07:03:47.989 : nsvntx_dei:exit 2019-06-24 07:03:47.989 : nsmfr:3368 bytes at 0x9d00f0 2019-06-24 07:03:47.989 : nsmfr:1952 bytes at 0x9d5cc0 2019-06-24 07:03:47.989 : nsevrec:event is 0x20, on -1 2019-06-24 07:03:47.989 : nsevwait:1 posted event(s) 2019-06-24 07:03:47.989 : nsglhe:entry 2019-06-24 07:03:47.989 : nsgldissolve:entry 2019-06-24 07:03:47.989 : nsgldissolve:Deallocating cxd 0x917ad0. 2019-06-24 07:03:47.989 : nsgldissolve:exit 2019-06-24 07:03:47.989 : nsglhe:exit 2019-06-24 07:03:47.989 : nsevwait:5 registered connection(s) 2019-06-24 07:03:47.989 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:47.989 : nsevwait:waiting for transport event (1 thru 6)... 2019-06-24 07:03:49.872 : nsevwait:0 newly-posted event(s) 2019-06-24 07:03:49.873 : nsgetaddr:entry 2019-06-24 07:03:49.873 : nttaddr2bnd:entry 2019-06-24 07:03:49.873 : snlinGetNameInfo:entry 2019-06-24 07:03:49.873 : snlinGetNameInfo:exit 2019-06-24 07:03:49.873 : nttaddr2bnd:exit 2019-06-24 07:03:49.873 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NT events set: READ 2019-06-24 07:03:49.873 : nsgetaddr:entry 2019-06-24 07:03:49.873 : nttaddr2bnd:entry 2019-06-24 07:03:49.873 : snlinGetNameInfo:entry 2019-06-24 07:03:49.873 : snlinGetNameInfo:exit 2019-06-24 07:03:49.873 : nttaddr2bnd:exit 2019-06-24 07:03:49.873 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NS events set: INCOMING SEND 2019-06-24 07:03:49.873 : nsevrec:event is 0x2, on 5 2019-06-24 07:03:49.873 : nsevwait:1 pre-posted event(s) 2019-06-24 07:03:49.873 : nsevrec:event is 0x2, on 5 2019-06-24 07:03:49.873 : nsevwait:1 posted event(s) 2019-06-24 07:03:49.873 : nsglhe:entry 2019-06-24 07:03:49.873 : nsglrohe:entry 2019-06-24 07:03:49.873 : nsdo:cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3 2019-06-24 07:03:49.873 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:03:49.873 : nsdo:nsctx: state=8, flg=0x2000400c, mvd=0 2019-06-24 07:03:49.873 : nsdo:gtn=422, gtc=422, ptn=10, ptc=8111 2019-06-24 07:03:49.873 : nsrdr:recving a packet 2019-06-24 07:03:49.874 : nsprecv:reading from transport... 2019-06-24 07:03:49.874 : nttmrd:entry 2019-06-24 07:03:49.874 : nttmrd:socket 14 had bytes read=446 2019-06-24 07:03:49.874 : nttmrd:exit 2019-06-24 07:03:49.874 : nsprecv:446 bytes from transport 2019-06-24 07:03:49.874 : nsprecv:tlen=446, plen=446, type=6 2019-06-24 07:03:49.874 : nsrdr:got NSPTDA packet 2019-06-24 07:03:49.874 : nsrdr:NSPTDA flags: 0x0 2019-06-24 07:03:49.874 : nsdo:*what=1, *bl=8196 2019-06-24 07:03:49.874 : nsdo:436 bytes from NS buffer 2019-06-24 07:03:49.874 : nsdo:nsctxrnk=0 2019-06-24 07:03:49.874 : nsglgrDoRegister:inst loads: ld1:5 mld1:5120 ld2:1 mld2:472 2019-06-24 07:03:49.874 : nsglgrDoRegister:service:KL3DB what:2 value:1 2019-06-24 07:03:49.874 : nsglgrDoRegister:exit 2019-06-24 07:03:49.874 : nsdo:cid=5, opcode=67, *bl=232, *what=1, uflgs=0x2, cflgs=0x3 2019-06-24 07:03:49.874 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:03:49.874 : nsdo:nsctx: state=8, flg=0x2000400c, mvd=0 2019-06-24 07:03:49.874 : nsdo:gtn=446, gtc=446, ptn=10, ptc=8111 2019-06-24 07:03:49.874 : nsdo:232 bytes to NS buffer 2019-06-24 07:03:49.874 : nsdofls:DATA flags: 0x0 2019-06-24 07:03:49.874 : nsdofls:sending NSPTDA packet 2019-06-24 07:03:49.874 : nspsend:plen=242, type=6 2019-06-24 07:03:49.874 : nttmwr:entry 2019-06-24 07:03:49.875 : nttmwr:socket 14 had bytes written=242 2019-06-24 07:03:49.875 : nttmwr:exit 2019-06-24 07:03:49.875 : nspsend:242 bytes to transport 2019-06-24 07:03:49.875 : nsdoacts:flushing transport 2019-06-24 07:03:49.875 : nttctl:entry 2019-06-24 07:03:49.875 : nsdo:nsctxrnk=0 2019-06-24 07:03:49.875 : nsglroHandleError:exit 2019-06-24 07:03:49.875 : nsglrohe:exit 2019-06-24 07:03:49.876 : nsglhe:exit 2019-06-24 07:03:49.876 : nsevwait:5 registered connection(s) 2019-06-24 07:03:49.876 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:49.876 : nsevwait:waiting for transport event (1 thru 6)... 2019-06-24 07:03:49.876 : nsevwait:0 newly-posted event(s) 2019-06-24 07:03:49.876 : nsgetaddr:entry 2019-06-24 07:03:49.876 : nttaddr2bnd:entry 2019-06-24 07:03:49.876 : snlinGetNameInfo:entry 2019-06-24 07:03:49.876 : snlinGetNameInfo:exit 2019-06-24 07:03:49.876 : nttaddr2bnd:exit 2019-06-24 07:03:49.876 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NT events set: WRITE 2019-06-24 07:03:49.876 : nsgetaddr:entry 2019-06-24 07:03:49.876 : nttaddr2bnd:entry 2019-06-24 07:03:49.876 : snlinGetNameInfo:entry 2019-06-24 07:03:49.876 : snlinGetNameInfo:exit 2019-06-24 07:03:49.876 : nttaddr2bnd:exit 2019-06-24 07:03:49.876 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NS events set: READY FOR SEND 2019-06-24 07:03:49.876 : nsevrec:event is 0x10, on 5 2019-06-24 07:03:49.876 : nsevwait:1 pre-posted event(s) 2019-06-24 07:03:49.876 : nsevrec:event is 0x10, on 5 2019-06-24 07:03:49.876 : nsevwait:1 posted event(s) 2019-06-24 07:03:49.876 : nsglhe:entry 2019-06-24 07:03:49.876 : nsglrohe:entry 2019-06-24 07:03:49.876 : nsevmute:cid=5 2019-06-24 07:03:49.876 : nsglrohe:exit 2019-06-24 07:03:49.876 : nsglhe:exit 2019-06-24 07:03:49.876 : nsevwait:5 registered connection(s) 2019-06-24 07:03:49.876 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:49.876 : nsevwait:waiting for transport event (1 thru 6)... 2019-06-24 07:03:52.874 : nsevwait:0 newly-posted event(s) 2019-06-24 07:03:52.874 : nsgetaddr:entry 2019-06-24 07:03:52.874 : nttaddr2bnd:entry 2019-06-24 07:03:52.874 : snlinGetNameInfo:entry 2019-06-24 07:03:52.874 : snlinGetNameInfo:exit 2019-06-24 07:03:52.874 : nttaddr2bnd:exit 2019-06-24 07:03:52.874 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NT events set: READ 2019-06-24 07:03:52.874 : nsgetaddr:entry 2019-06-24 07:03:52.874 : nttaddr2bnd:entry 2019-06-24 07:03:52.874 : snlinGetNameInfo:entry 2019-06-24 07:03:52.874 : snlinGetNameInfo:exit 2019-06-24 07:03:52.874 : nttaddr2bnd:exit 2019-06-24 07:03:52.874 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NS events set: INCOMING SEND 2019-06-24 07:03:52.874 : nsevrec:event is 0x2, on 5 2019-06-24 07:03:52.874 : nsevwait:1 pre-posted event(s) 2019-06-24 07:03:52.874 : nsevrec:event is 0x2, on 5 2019-06-24 07:03:52.874 : nsevwait:1 posted event(s) 2019-06-24 07:03:52.874 : nsglhe:entry 2019-06-24 07:03:52.874 : nsglrohe:entry 2019-06-24 07:03:52.874 : nsdo:cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3 2019-06-24 07:03:52.875 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:03:52.875 : nsdo:nsctx: state=8, flg=0x2000400c, mvd=0 2019-06-24 07:03:52.875 : nsdo:gtn=446, gtc=446, ptn=10, ptc=8111 2019-06-24 07:03:52.875 : nsrdr:recving a packet 2019-06-24 07:03:52.875 : nsprecv:reading from transport... 2019-06-24 07:03:52.875 : nttmrd:entry 2019-06-24 07:03:52.875 : nttmrd:socket 14 had bytes read=414 2019-06-24 07:03:52.875 : nttmrd:exit 2019-06-24 07:03:52.875 : nsprecv:414 bytes from transport 2019-06-24 07:03:52.875 : nsprecv:tlen=414, plen=414, type=6 2019-06-24 07:03:52.875 : nsrdr:got NSPTDA packet 2019-06-24 07:03:52.875 : nsrdr:NSPTDA flags: 0x0 2019-06-24 07:03:52.875 : nsdo:*what=1, *bl=8196 2019-06-24 07:03:52.875 : nsdo:404 bytes from NS buffer 2019-06-24 07:03:52.875 : nsdo:nsctxrnk=0 2019-06-24 07:03:52.875 : nsglgrDoRegister:inst loads: ld1:5 mld1:5120 ld2:0 mld2:472 2019-06-24 07:03:52.875 : nsglgrDoRegister:service:KL3DB what:2 value:0 2019-06-24 07:03:52.875 : nsglgrDoRegister:exit 2019-06-24 07:03:52.875 : nsdo:cid=5, opcode=67, *bl=232, *what=1, uflgs=0x2, cflgs=0x3 2019-06-24 07:03:52.875 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:03:52.875 : nsdo:nsctx: state=8, flg=0x2000400c, mvd=0 2019-06-24 07:03:52.875 : nsdo:gtn=414, gtc=414, ptn=10, ptc=8111 2019-06-24 07:03:52.875 : nsdo:232 bytes to NS buffer 2019-06-24 07:03:52.875 : nsdofls:DATA flags: 0x0 2019-06-24 07:03:52.875 : nsdofls:sending NSPTDA packet 2019-06-24 07:03:52.875 : nspsend:plen=242, type=6 2019-06-24 07:03:52.875 : nttmwr:entry 2019-06-24 07:03:52.876 : nttmwr:socket 14 had bytes written=242 2019-06-24 07:03:52.876 : nttmwr:exit 2019-06-24 07:03:52.876 : nspsend:242 bytes to transport 2019-06-24 07:03:52.876 : nsdoacts:flushing transport 2019-06-24 07:03:52.876 : nttctl:entry 2019-06-24 07:03:52.876 : nsdo:nsctxrnk=0 2019-06-24 07:03:52.876 : nsglroHandleError:exit 2019-06-24 07:03:52.876 : nsglrohe:exit 2019-06-24 07:03:52.876 : nsglhe:exit 2019-06-24 07:03:52.876 : nsevwait:5 registered connection(s) 2019-06-24 07:03:52.876 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:52.876 : nsevwait:waiting for transport event (1 thru 6)... 2019-06-24 07:03:52.877 : nsevwait:0 newly-posted event(s) 2019-06-24 07:03:52.877 : nsgetaddr:entry 2019-06-24 07:03:52.877 : nttaddr2bnd:entry 2019-06-24 07:03:52.877 : snlinGetNameInfo:entry 2019-06-24 07:03:52.877 : snlinGetNameInfo:exit 2019-06-24 07:03:52.877 : nttaddr2bnd:exit 2019-06-24 07:03:52.877 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NT events set: WRITE 2019-06-24 07:03:52.877 : nsgetaddr:entry 2019-06-24 07:03:52.877 : nttaddr2bnd:entry 2019-06-24 07:03:52.877 : snlinGetNameInfo:entry 2019-06-24 07:03:52.877 : snlinGetNameInfo:exit 2019-06-24 07:03:52.877 : nttaddr2bnd:exit 2019-06-24 07:03:52.877 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NS events set: READY FOR SEND 2019-06-24 07:03:52.877 : nsevrec:event is 0x10, on 5 2019-06-24 07:03:52.877 : nsevwait:1 pre-posted event(s) 2019-06-24 07:03:52.877 : nsevrec:event is 0x10, on 5 2019-06-24 07:03:52.877 : nsevwait:1 posted event(s) 2019-06-24 07:03:52.877 : nsglhe:entry 2019-06-24 07:03:52.877 : nsglrohe:entry 2019-06-24 07:03:52.877 : nsevmute:cid=5 2019-06-24 07:03:52.877 : nsglrohe:exit 2019-06-24 07:03:52.877 : nsglhe:exit 2019-06-24 07:03:52.877 : nsevwait:5 registered connection(s) 2019-06-24 07:03:52.877 : nsevwait:0 pre-posted event(s) 2019-06-24 07:03:52.877 : nsevwait:waiting for transport event (1 thru 6)... 2019-06-24 07:11:29.187 : nsevwait:0 newly-posted event(s) 2019-06-24 07:11:29.187 : nsgetaddr:entry 2019-06-24 07:11:29.188 : nttaddr2bnd:entry 2019-06-24 07:11:29.188 : snlinGetNameInfo:entry 2019-06-24 07:11:29.188 : snlinGetNameInfo:exit 2019-06-24 07:11:29.188 : nttaddr2bnd:exit 2019-06-24 07:11:29.188 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NT events set: READ 2019-06-24 07:11:29.188 : nsgetaddr:entry 2019-06-24 07:11:29.188 : nttaddr2bnd:entry 2019-06-24 07:11:29.188 : snlinGetNameInfo:entry 2019-06-24 07:11:29.188 : snlinGetNameInfo:exit 2019-06-24 07:11:29.188 : nttaddr2bnd:exit 2019-06-24 07:11:29.188 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NS events set: INCOMING SEND 2019-06-24 07:11:29.188 : nsevrec:event is 0x2, on 5 2019-06-24 07:11:29.188 : nsevwait:1 pre-posted event(s) 2019-06-24 07:11:29.188 : nsevrec:event is 0x2, on 5 2019-06-24 07:11:29.188 : nsevwait:1 posted event(s) 2019-06-24 07:11:29.188 : nsglhe:entry 2019-06-24 07:11:29.188 : nsglrohe:entry 2019-06-24 07:11:29.188 : nsdo:cid=5, opcode=68, *bl=8196, *what=0, uflgs=0x0, cflgs=0x3 2019-06-24 07:11:29.188 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:11:29.188 : nsdo:nsctx: state=8, flg=0x2000400c, mvd=0 2019-06-24 07:11:29.188 : nsdo:gtn=414, gtc=414, ptn=10, ptc=8111 2019-06-24 07:11:29.188 : nsrdr:recving a packet 2019-06-24 07:11:29.188 : nsprecv:reading from transport... 2019-06-24 07:11:29.188 : nttmrd:entry 2019-06-24 07:11:29.189 : nttmrd:socket 14 had bytes read=454 2019-06-24 07:11:29.189 : nttmrd:exit 2019-06-24 07:11:29.189 : nsprecv:454 bytes from transport 2019-06-24 07:11:29.189 : nsprecv:tlen=454, plen=454, type=6 2019-06-24 07:11:29.189 : nsrdr:got NSPTDA packet 2019-06-24 07:11:29.189 : nsrdr:NSPTDA flags: 0x0 2019-06-24 07:11:29.189 : nsdo:*what=1, *bl=8196 2019-06-24 07:11:29.189 : nsdo:444 bytes from NS buffer 2019-06-24 07:11:29.189 : nsdo:nsctxrnk=0 2019-06-24 07:11:29.189 : nsglgrDoRegister:inst loads: ld1:17 mld1:5120 ld2:0 mld2:472 2019-06-24 07:11:29.189 : nsglgrDoRegister:exit 2019-06-24 07:11:29.189 : nsdo:cid=5, opcode=67, *bl=240, *what=1, uflgs=0x2, cflgs=0x3 2019-06-24 07:11:29.189 : nsdo:rank=64, nsctxrnk=0 2019-06-24 07:11:29.189 : nsdo:nsctx: state=8, flg=0x2000400c, mvd=0 2019-06-24 07:11:29.189 : nsdo:gtn=454, gtc=454, ptn=10, ptc=8111 2019-06-24 07:11:29.189 : nsdo:240 bytes to NS buffer 2019-06-24 07:11:29.189 : nsdofls:DATA flags: 0x0 2019-06-24 07:11:29.189 : nsdofls:sending NSPTDA packet 2019-06-24 07:11:29.189 : nspsend:plen=250, type=6 2019-06-24 07:11:29.189 : nttmwr:entry 2019-06-24 07:11:29.189 : nttmwr:socket 14 had bytes written=250 2019-06-24 07:11:29.189 : nttmwr:exit 2019-06-24 07:11:29.189 : nspsend:250 bytes to transport 2019-06-24 07:11:29.189 : nsdoacts:flushing transport 2019-06-24 07:11:29.190 : nttctl:entry 2019-06-24 07:11:29.190 : nsdo:nsctxrnk=0 2019-06-24 07:11:29.190 : nsglroHandleError:exit 2019-06-24 07:11:29.190 : nsglrohe:exit 2019-06-24 07:11:29.191 : nsglhe:exit 2019-06-24 07:11:29.191 : nsevwait:5 registered connection(s) 2019-06-24 07:11:29.191 : nsevwait:0 pre-posted event(s) 2019-06-24 07:11:29.191 : nsevwait:waiting for transport event (1 thru 6)... 2019-06-24 07:11:29.191 : nsevwait:0 newly-posted event(s) 2019-06-24 07:11:29.191 : nsgetaddr:entry 2019-06-24 07:11:29.191 : nttaddr2bnd:entry 2019-06-24 07:11:29.191 : snlinGetNameInfo:entry 2019-06-24 07:11:29.191 : snlinGetNameInfo:exit 2019-06-24 07:11:29.191 : nttaddr2bnd:exit 2019-06-24 07:11:29.191 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NT events set: WRITE 2019-06-24 07:11:29.191 : nsgetaddr:entry 2019-06-24 07:11:29.191 : nttaddr2bnd:entry 2019-06-24 07:11:29.191 : snlinGetNameInfo:entry 2019-06-24 07:11:29.191 : snlinGetNameInfo:exit 2019-06-24 07:11:29.191 : nttaddr2bnd:exit 2019-06-24 07:11:29.191 : nsevfnt:cxd: 0x8fc590 cid=5 stage 0: NS events set: READY FOR SEND 2019-06-24 07:11:29.191 : nsevrec:event is 0x10, on 5 2019-06-24 07:11:29.191 : nsevwait:1 pre-posted event(s) 2019-06-24 07:11:29.191 : nsevrec:event is 0x10, on 5 2019-06-24 07:11:29.191 : nsevwait:1 posted event(s) 2019-06-24 07:11:29.191 : nsglhe:entry 2019-06-24 07:11:29.192 : nsglrohe:entry 2019-06-24 07:11:29.192 : nsevmute:cid=5 2019-06-24 07:11:29.192 : nsglrohe:exit 2019-06-24 07:11:29.192 : nsglhe:exit 2019-06-24 07:11:29.192 : nsevwait:5 registered connection(s) 2019-06-24 07:11:29.192 : nsevwait:0 pre-posted event(s) 2019-06-24 07:11:29.192 : nsevwait:waiting for transport event (1 thru 6)...