Enabling SSL through Net Manger is not working !!!!!!!
650204Sep 14 2010 — edited Sep 15 2010When configuring SSL in the "Oracle net manager"
1) Should the Service Name also be TCP/IP with SSL or just the Listener
Steps I followed:
1) Created a wallet in [READY] mode
2) configured *ora files through net manager
3) Restarted the listner
/////////////////////////////////////////////////////////////////////////////////////////
listener.ora:
===========
SSL_CLIENT_AUTHENTICATION = FALSE
WALLET_LOCATION =
(SOURCE =
(METHOD = FILE)
(METHOD_DATA =
(DIRECTORY = C:\oracle\db\11g\11.1.0\db_2\admin\orcl\wallet)
)
)
LISTENER =
(DESCRIPTION_LIST =
(DESCRIPTION =
(ADDRESS = (PROTOCOL = IPC)(KEY = EXTPROC1521))
)
(DESCRIPTION =
(ADDRESS = (PROTOCOL = TCPS)(HOST = kcr-PC)(PORT = 2484))
)
)
TRACE_LEVEL_LISTENER = ADMIN
//////////////////////////////////////////////////////////////////////////////////////////
/////////////////////////////////////////////////////////////////////////////////////////
tnsname.ora:
===========
ORCL =
(DESCRIPTION =
(ADDRESS_LIST =
(ADDRESS = (PROTOCOL = TCP)(HOST = kcr-PC)(PORT = 1521)) // I have trried (PROTOCOL = TCPS) doesn't work
)
(CONNECT_DATA =
(SERVER = DEDICATED)
(SERVICE_NAME = orcl)
)
)
//////////////////////////////////////////////////////////////////////////////////////////
/////////////////////////////////////////////////////////////////////////////////////////
sqlnet.ora:
===========
SQLNET.AUTHENTICATION_SERVICES= (BEQ, TCPS, NTS)
SSL_VERSION = 0
NAMES.DIRECTORY_PATH= (TNSNAMES, EZCONNECT)
SSL_CLIENT_AUTHENTICATION = FALSE
WALLET_LOCATION =
(SOURCE =
(METHOD = FILE)
(METHOD_DATA =
(DIRECTORY = C:\oracle\db\11g\11.1.0\db_2\admin\orcl\wallet)
)
)
SSL_CIPHER_SUITES= (SSL_RSA_EXPORT_WITH_RC4_40_MD5, SSL_RSA_EXPORT_WITH_DES40_CBC_SHA)
//////////////////////////////////////////////////////////////////////////////////////////
Log file output
////////////////////////////////////////////////////////////////////////////////////////////
listener.log
==========
Wed Sep 15 14:08:30 2010
15-SEP-2010 14:08:30 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
No longer listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(PIPENAME=\\.\pipe\EXTPROC1521ipc)))
No longer listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=tcps)(HOST=kcr-PC)(PORT=2484)))
Listener completed notification to CRS on stop
15-SEP-2010 14:08:32 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=stop)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * stop * 0
Wed Sep 15 14:13:13 2010
System parameter file is C:\oracle\db\11g\11.1.0\db_2\network\admin\listener.ora
Log messages written to c:\oracle\db\11g\diag\tnslsnr\kcr-PC\listener\alert\log.xml
Trace information written to c:\oracle\db\11g\diag\tnslsnr\kcr-PC\listener\trace\ora_2192_3556.trc
Trace level is currently 6
Started with pid=2192
Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(PIPENAME=\\.\pipe\EXTPROC1521ipc)))
Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=tcps)(HOST=kcr-PC)(PORT=2484)))
Listener completed notification to CRS on start
TIMESTAMP * CONNECT DATA [* PROTOCOL INFO] * EVENT [* SID] * RETURN CODE
15-SEP-2010 14:13:18 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
Wed Sep 15 14:13:30 2010
15-SEP-2010 14:13:30 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
Wed Sep 15 14:16:18 2010
15-SEP-2010 14:16:18 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
////////////////////////////////////////////////////////////////////////////////////////////
////////////////////////////////////////////////////////////////////////////////////////////
listener.log
==========
Wed Sep 15 14:08:30 2010
15-SEP-2010 14:08:30 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
No longer listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(PIPENAME=\\.\pipe\EXTPROC1521ipc)))
No longer listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=tcps)(HOST=kcr-PC)(PORT=2484)))
Listener completed notification to CRS on stop
15-SEP-2010 14:08:32 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=stop)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * stop * 0
Wed Sep 15 14:13:13 2010
System parameter file is C:\oracle\db\11g\11.1.0\db_2\network\admin\listener.ora
Log messages written to c:\oracle\db\11g\diag\tnslsnr\kcr-PC\listener\alert\log.xml
Trace information written to c:\oracle\db\11g\diag\tnslsnr\kcr-PC\listener\trace\ora_2192_3556.trc
Trace level is currently 6
Started with pid=2192
Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(PIPENAME=\\.\pipe\EXTPROC1521ipc)))
Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=tcps)(HOST=kcr-PC)(PORT=2484)))
Listener completed notification to CRS on start
TIMESTAMP * CONNECT DATA [* PROTOCOL INFO] * EVENT [* SID] * RETURN CODE
15-SEP-2010 14:13:18 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
Wed Sep 15 14:13:30 2010
15-SEP-2010 14:13:30 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
Wed Sep 15 14:16:18 2010
15-SEP-2010 14:16:18 * (CONNECT_DATA=(CID=(PROGRAM=)(HOST=)(USER=kcr))(COMMAND=status)(ARGUMENTS=64)(SERVICE=LISTENER)(VERSION=185599744)) * status * 0
////////////////////////////////////////////////////////////////////////////////////////////
Trace log:
=========
Trace file c:\oracle\db\11g\diag\tnslsnr\kcr-PC\listener\trace\ora_2192_3556.trc
TNSLSNR for 32-bit Windows: Version 11.1.0.7.0 - Production on 15-SEP-2010 14:13:13
Copyright (c) 1991, 2008, Oracle. All rights reserved.
2010-09-15 14:13:13.706315 : --- TRACE CONFIGURATION INFORMATION FOLLOWS ---
2010-09-15 14:13:13.706426 : New trace stream is c:\oracle\db\11g\diag\tnslsnr\kcr-PC\listener\trace\ora_2192_3556.trc
2010-09-15 14:13:13.706493 : New trace level is 6
2010-09-15 14:13:13.706556 : --- TRACE CONFIGURATION INFORMATION ENDS ---
2010-09-15 14:13:13.706621 : --- PARAMETER SOURCE INFORMATION FOLLOWS ---
2010-09-15 14:13:13.706767 : Attempted load of system pfile source C:\oracle\db\11g\11.1.0\db_2\network\admin\listener.ora
2010-09-15 14:13:13.706822 : Parameter source loaded successfully
2010-09-15 14:13:13.706869 :
2010-09-15 14:13:13.706914 : Attempted load of command line source
2010-09-15 14:13:13.706961 : Parameter source was not loaded
2010-09-15 14:13:13.707006 :
2010-09-15 14:13:13.707051 : -> PARAMETER TABLE LOAD RESULTS FOLLOW <-
2010-09-15 14:13:13.707098 : Successful parameter table load
2010-09-15 14:13:13.707148 : -> PARAMETER TABLE HAS THE FOLLOWING CONTENTS <-
2010-09-15 14:13:13.707198 : LISTENER = (DESCRIPTION_LIST = (DESCRIPTION = (ADDRESS = (PROTOCOL = IPC)(KEY = EXTPROC1521))) (DESCRIPTION = (ADDRESS = (PROTOCOL = TCPS)(HOST = kcr-PC)(PORT = 2484))))
2010-09-15 14:13:13.707250 : SSL_CLIENT_AUTHENTICATION = FALSE
2010-09-15 14:13:13.707297 : TRACE_LEVEL_LISTENER = ADMIN
2010-09-15 14:13:13.707344 : WALLET_LOCATION = (SOURCE = (METHOD = FILE) (METHOD_DATA = (DIRECTORY = C:\oracle\db\11g\11.1.0\db_2\admin\orcl\wallet)))
2010-09-15 14:13:13.707394 : --- PARAMETER SOURCE INFORMATION ENDS ---
2010-09-15 14:13:13.707447 : --- LOG CONFIGURATION INFORMATION FOLLOWS ---
2010-09-15 14:13:13.707520 : Log stream will be "c:\oracle\db\11g\diag\tnslsnr\kcr-PC\listener\alert\log.xml"
2010-09-15 14:13:13.707570 : Log stream validation not requested
2010-09-15 14:13:13.707617 : --- LOG CONFIGURATION INFORMATION ENDS ---
2010-09-15 14:13:13.707672 : nlstdipi:entry
2010-09-15 14:13:13.708416 : nlstdipi:exit
2010-09-15 14:13:13.713189 : nsglldprm:Resolved "INBOUND_CONNECT_TIMEOUT_LISTENER" to: 60
2010-09-15 14:13:13.713270 : nsglldprm:Resolved "STARTUP_WAIT_TIME_LISTENER" to: 0
2010-09-15 14:13:13.713356 : nsglldprm:LocalOSauth enabled
2010-09-15 14:13:13.713407 : nsglldprm:No Default Service Configured for LISTENER
2010-09-15 14:13:13.714149 : nsinherit:doing connect handshake...
2010-09-15 14:13:13.714227 : nsinh_hoff:ADR="(ADDRESS=(PROTOCOL=beq))"
2010-09-15 14:13:13.714282 : nsc2addr:(ADDRESS=(PROTOCOL=beq))
2010-09-15 14:13:13.715334 : nlpcaini:entry
2010-09-15 14:13:13.715415 : nlpcaini:No process parameters set
2010-09-15 14:13:13.715468 : nlpcaini:exit
2010-09-15 14:13:13.715522 : nsinh_hoff:inheriting the connection...
2010-09-15 14:13:13.715601 : nsmal:996 bytes at 0x1e048e0
2010-09-15 14:13:13.715667 : nsmal:2096 bytes at 0x1e04cd0
2010-09-15 14:13:13.715737 : nsmal:84 bytes at 0x1e05640
2010-09-15 14:13:13.715902 : nsopen:opening transport...
2010-09-15 14:13:13.715938 : ntpcon:entry
2010-09-15 14:13:13.715966 : ntpcon:toc = 6
2010-09-15 14:13:13.715992 : ntpcon:exit
2010-09-15 14:13:13.716018 : ntpcon:entry
2010-09-15 14:13:13.716044 : ntpcon:toc = 4
2010-09-15 14:13:13.716075 : sntpinherit:entry
2010-09-15 14:13:13.716176 : sntpinherit:Attempting to make pipe \\.\PIPE\ORANTP890.DE4.w
2010-09-15 14:13:13.716245 : sntpinherit:Listening on pipe \\.\PIPE\ORANTP890.DE4.w
2010-09-15 14:13:13.716277 : sntpinherit:Attempting to make pipe \\.\PIPE\ORANTP890.DE4
2010-09-15 14:13:13.716328 : sntpinherit:Listening on pipe \\.\PIPE\ORANTP890.DE4
2010-09-15 14:13:15.633322 : sntpinherit:Successfully established pipe 508 to parent.
2010-09-15 14:13:15.633435 : sntpinherit:Successfully established pipe 504 to parent.
2010-09-15 14:13:15.633476 : sntpinherit:exit
2010-09-15 14:13:15.633508 : ntpcon:NT layer IPC connection has been established.
2010-09-15 14:13:15.633645 : ntpcon:exit
2010-09-15 14:13:15.633683 : nsopen:transport is open
2010-09-15 14:13:15.633719 : nsoptions:lcl[0]=0x0, lcl[1]=0x0, gbl[0]=0x0, gbl[1]=0x0, cha=0x0
2010-09-15 14:13:15.633752 : nsoptions:lcl[0]=0xf4ffefff, lcl[1]=0x0, gbl[0]=0x78bf, gbl[1]=0x0
2010-09-15 14:13:15.633799 : nsnainit:inherit
2010-09-15 14:13:15.633834 : nsnasvnainfo:NA disabled for this connection
2010-09-15 14:13:15.633872 : nainit:entry
2010-09-15 14:13:15.633910 : nagblini:entry
2010-09-15 14:13:15.633953 : nau_gin:entry
2010-09-15 14:13:15.633987 : nau_gparams:entry
2010-09-15 14:13:15.634020 : nam_gbp:Reading parameter "sqlnet.authentication_required" from parameter file
2010-09-15 14:13:15.634053 : nam_gbp:Parameter not found
2010-09-15 14:13:15.634084 : nau_gparams:Using default value "FALSE"
2010-09-15 14:13:15.634115 : nau_gslf:entry
2010-09-15 14:13:15.634145 : nam_gic:entry
2010-09-15 14:13:15.634174 : nam_gic:Counting # of items in "sqlnet.authentication_services" parameter
2010-09-15 14:13:15.634208 : nam_gic:Parameter not found
2010-09-15 14:13:15.634236 : nam_gic:Found 0 items
2010-09-15 14:13:15.634263 : nam_gic:exit
2010-09-15 14:13:15.634290 : nau_gslf:Using default value "all available adapters"
2010-09-15 14:13:15.634321 : nauss_set_state:entry
2010-09-15 14:13:15.634348 : nauss_set_state:exit
2010-09-15 14:13:15.634377 : nau_gslf:exit
2010-09-15 14:13:15.634404 : nau_gparams:exit
2010-09-15 14:13:15.634430 : nau_gin:exit
2010-09-15 14:13:15.634457 : nagblini:exit
2010-09-15 14:13:15.634489 : na_saveprot:entry
2010-09-15 14:13:15.634518 : na_saveprot:exit
2010-09-15 14:13:15.634549 : nacomin:entry
2010-09-15 14:13:15.634582 : nas_init:entry
2010-09-15 14:13:15.634611 : nas_init:exit
2010-09-15 14:13:15.634645 : nau_ini:entry
2010-09-15 14:13:15.634674 : naugcp_get_connect_parameters:entry
2010-09-15 14:13:15.634702 : nauss_set_state:entry
2010-09-15 14:13:15.634728 : nauss_set_state:exit
2010-09-15 14:13:15.634755 : naugcp_get_connect_parameters:exit
2010-09-15 14:13:15.634785 : nau_gettab:entry
2010-09-15 14:13:15.634840 : nau_gettab:exit
2010-09-15 14:13:15.634868 : nau_sini:entry
2010-09-15 14:13:15.634895 : nau_sini:exit
2010-09-15 14:13:15.634927 : nau_ini:exit
2010-09-15 14:13:15.634959 : naeeinit:entry
2010-09-15 14:13:15.634992 : nam_gbp:Reading parameter "SQLNET.FIPS_140" from parameter file
2010-09-15 14:13:15.635022 : nam_gbp:Parameter not found
2010-09-15 14:13:15.635055 : nam_gnsp:Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter file
2010-09-15 14:13:15.635088 : nam_gnsp:Parameter not found
2010-09-15 14:13:15.635119 : naequad:Using default value "ACCEPTED"
2010-09-15 14:13:15.635147 : nam_gic:entry
2010-09-15 14:13:15.635174 : nam_gic:Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER" parameter
2010-09-15 14:13:15.635205 : nam_gic:Parameter not found
2010-09-15 14:13:15.635232 : nam_gic:exit
2010-09-15 14:13:15.635259 : naesno:Using default value "all available algorithms"
2010-09-15 14:13:15.635290 : naeshow:entry
2010-09-15 14:13:15.635409 : naeshow:exit
2010-09-15 14:13:15.635439 : naeeinit:exit
2010-09-15 14:13:15.635469 : naecinit:entry
2010-09-15 14:13:15.635498 : nam_gnsp:Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from parameter file
2010-09-15 14:13:15.635530 : nam_gnsp:Parameter not found
2010-09-15 14:13:15.635557 : naequad:Using default value "ACCEPTED"
2010-09-15 14:13:15.635584 : nam_gic:entry
2010-09-15 14:13:15.635611 : nam_gic:Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER" parameter
2010-09-15 14:13:15.635643 : nam_gic:Parameter not found
2010-09-15 14:13:15.635669 : nam_gic:exit
2010-09-15 14:13:15.635696 : naesno:Using default value "all available algorithms"
2010-09-15 14:13:15.635724 : naeshow:entry
2010-09-15 14:13:15.635750 : naeshow:exit
2010-09-15 14:13:15.635776 : naecinit:exit
2010-09-15 14:13:15.635868 : nadisc:entry
2010-09-15 14:13:15.635903 : nacomtm:entry
2010-09-15 14:13:15.635932 : nacompd:entry
2010-09-15 14:13:15.635959 : nacompd:exit
2010-09-15 14:13:15.635985 : nacompd:entry
2010-09-15 14:13:15.636074 : nacompd:exit
2010-09-15 14:13:15.636103 : nacomtm:exit
2010-09-15 14:13:15.636134 : nas_dis:entry
2010-09-15 14:13:15.636161 : nas_dis:exit
2010-09-15 14:13:15.636193 : nau_dis:entry
2010-09-15 14:13:15.636222 : nau_dis:exit
2010-09-15 14:13:15.636251 : naeetrm:entry
2010-09-15 14:13:15.636278 : naeetrm:exit
2010-09-15 14:13:15.636307 : naectrm:entry
2010-09-15 14:13:15.636336 : naectrm:exit
2010-09-15 14:13:15.636365 : nagbltrm:entry
2010-09-15 14:13:15.636397 : nau_gtm:entry
2010-09-15 14:13:15.636423 : nau_gtm:exit
2010-09-15 14:13:15.636450 : nagbltrm:exit
2010-09-15 14:13:15.636477 : nadisc:exit
2010-09-15 14:13:15.636503 : nainit:exit
2010-09-15 14:13:15.636531 : nsnainit:NS Connection version: 314
2010-09-15 14:13:15.636565 : nsnainit:inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd
2010-09-15 14:13:15.636601 : 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
2010-09-15 14:13:15.636643 : 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
2010-09-15 14:13:15.636697 : nsopen:global context check-in (to slot 0) complete
2010-09-15 14:13:15.636732 : nsopen:lcl[0]=0xf4ffefff, lcl[1]=0x0, gbl[0]=0x78bf, gbl[1]=0x0, tdu=4096, sdu=8192
2010-09-15 14:13:15.636773 : nsfull_opn:cid=0, opcode=65, bl=0, what=0, uflgs=0x0, cflgs=0x0
2010-09-15 14:13:15.636805 : nsfull_opn:nsctx: state=7, flg=0x4000, mvd=0
2010-09-15 14:13:15.636841 : nsmal:84 bytes at 0x1e07da8
2010-09-15 14:13:15.636892 : nsmal:84 bytes at 0x1e09e28
2010-09-15 14:13:15.636948 : nsdo:cid=0, opcode=68, bl=0, what=8, uflgs=0x0, cflgs=0x3
2010-09-15 14:13:15.636983 : nsdo:rank=64, nsctxrnk=0
2010-09-15 14:13:15.637012 : nsdo:nsctx: state=14, flg=0x4004, mvd=0
2010-09-15 14:13:15.637044 : nsdo:gtn=10, gtc=10, ptn=10, ptc=8155
2010-09-15 14:13:15.637078 : nscon:doing connect handshake...
2010-09-15 14:13:15.637110 : nscon:recving a packet
2010-09-15 14:13:15.637144 : nsprecv:reading from transport...
2010-09-15 14:13:15.637174 : ntprd:entry
2010-09-15 14:13:15.637201 : sntpread:entry
2010-09-15 14:13:15.637229 : sntpread:Attempting to read 8208 bytes from handle 508
2010-09-15 14:13:15.637266 : sntpread:ReadFile returned 152 bytes
2010-09-15 14:13:15.637295 : sntpread:rc = 0, ntresnt[0] = 0
2010-09-15 14:13:15.637322 : sntpread:exit
2010-09-15 14:13:15.637349 : ntprd:exit
2010-09-15 14:13:15.637380 : nsprecv:152 bytes from transport
2010-09-15 14:13:15.637411 : nsprecv:tlen=152, plen=152, type=1
2010-09-15 14:13:15.637443 : nscon:got NSPTCN packet
2010-09-15 14:13:15.637481 : nsconneg:vsn=314, lov=300, opt=0x0, sdu=8192, tdu=4096, ntc=0x20e
2010-09-15 14:13:15.637513 : nsconneg:vsn=314, gbl=0x0, sdu=8192, tdu=4096
2010-09-15 14:13:15.637547 : nsmal:94 bytes at 0x1e0bea8
2010-09-15 14:13:15.637580 : nscon:got 94 bytes connect data
2010-09-15 14:13:15.637612 : nsmfr:94 bytes at 0x1e0bea8
2010-09-15 14:13:15.637644 : nscon:discarding connect data (94 bytes)
2010-09-15 14:13:15.637676 : nsdo:nsctxrnk=0
2010-09-15 14:13:15.637708 : nsinh_hoff:connection inherited
2010-09-15 14:13:15.637739 : nsinherit:connected
2010-09-15 14:13:15.637782 : nsglma:entry
2010-09-15 14:13:15.638886 : nsglma:Listener's pid=2192
2010-09-15 14:13:15.639072 : nladini:entry
2010-09-15 14:13:15.639125 : nladini:exit
2010-09-15 14:13:15.639152 : nladget:entry
2010-09-15 14:13:15.639197 : nladget:exit
2010-09-15 14:13:15.639234 : nsgllsn:LSNDSC==>(DESCRIPTION=(ADDRESS=(PROTOCOL=IPC)(KEY=EXTPROC1521)))
2010-09-15 14:13:15.639270 : nsglhcrt:entry
2010-09-15 14:13:15.642067 : nsglhcrt:exit
2010-09-15 14:13:15.642174 : nsglbgetRSPidx:entry
2010-09-15 14:13:15.642222 : nladini:entry
2010-09-15 14:13:15.642259 : nladini:exit
2010-09-15 14:13:15.642286 : nladget:entry
2010-09-15 14:13:15.642332 : nladget:exit
2010-09-15 14:13:15.642363 : nsglbn2i:entry
2010-09-15 14:13:15.642392 : nsglbn2i:exit
2010-09-15 14:13:15.642435 : nladtrm:entry
2010-09-15 14:13:15.642465 : nladtrm:exit
2010-09-15 14:13:15.642493 : nsglbgetRSPidx:returning ecode=0
2010-09-15 14:13:15.642520 : nsglbgetRSPidx:exit
2010-09-15 14:13:15.642593 : nsc2addr:(DESCRIPTION=(ADDRESS=(PROTOCOL=IPC)(KEY=EXTPROC1521)))
2010-09-15 14:13:15.643490 : ntnbnd2ddr:entry
2010-09-15 14:13:15.643547 : ntnbnd2ddr:exit
2010-09-15 14:13:15.643584 : nsmal:996 bytes at 0x1e0d428
2010-09-15 14:13:15.643622 : nsmal:2096 bytes at 0x1e0d818
2010-09-15 14:13:15.643660 : nsmal:84 bytes at 0x1e0e188
2010-09-15 14:13:15.643728 : nsopen:opening transport...
2010-09-15 14:13:15.643762 : ntnlsn:entry
2010-09-15 14:13:15.643810 : ntnlsn:creating pipe \\.\pipe\EXTPROC1521ipc
2010-09-15 14:13:15.643899 : ntnlsn:listening on pipe 532
2010-09-15 14:13:15.643930 : nsopen:transport is open
2010-09-15 14:13:15.643961 : nsoptions:lcl[0]=0x0, lcl[1]=0x12003, gbl[0]=0x0, gbl[1]=0x2001, cha=0x0
2010-09-15 14:13:15.643994 : nsoptions:lcl[0]=0xf4ffefff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001
2010-09-15 14:13:15.644034 : nsopen:global context check-in (to slot 1) complete
2010-09-15 14:13:15.644067 : nsopen:lcl[0]=0xf4ffefff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001, tdu=32767, sdu=8192
2010-09-15 14:13:15.644101 : nsfull_opn:cid=1, opcode=65, bl=0, what=0, uflgs=0x0, cflgs=0x0
2010-09-15 14:13:15.644132 : nsfull_opn:nsctx: state=7, flg=0x4002, mvd=0
2010-09-15 14:13:15.644162 : nsmal:84 bytes at 0x1e108e8
2010-09-15 14:13:15.644215 : nsgllsn:completing partial addr: (DESCRIPTION=(ADDRESS=(PROTOCOL=IPC)(KEY=EXTPROC1521)))
2010-09-15 14:13:15.645109 : nsgllsn:Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(PIPENAME=\\.\pipe\EXTPROC1521ipc)))
2010-09-15 14:13:15.645216 : nsevreg:begin registration process for 1
2010-09-15 14:13:15.645259 : nsevreg:sgt=0, evn=4, evt[2]=0x0
2010-09-15 14:13:15.645290 : nsevreg:begin notification process for 1
2010-09-15 14:13:15.645331 : nsrah:setting transport read mode (2)
2010-09-15 14:13:15.645375 : ntnrd:failed to read 8155 bytes
2010-09-15 14:13:15.645407 : sntn2err:entry
2010-09-15 14:13:15.645438 : sntn2err:error: handle=532, op=5, nt[0]=524, nt[1]=997
2010-09-15 14:13:15.645467 : sntn2err:exit
2010-09-15 14:13:15.645502 : nsmal:84 bytes at 0x1e12af8
2010-09-15 14:13:15.645556 : ntnrd:failed to read 8155 bytes
2010-09-15 14:13:15.645585 : sntn2err:entry
2010-09-15 14:13:15.645612 : sntn2err:error: handle=532, op=5, nt[0]=524, nt[1]=997
2010-09-15 14:13:15.645640 : sntn2err:exit
2010-09-15 14:13:15.645670 : nsmal:84 bytes at 0x1e14cb0
2010-09-15 14:13:15.645712 : ntnrd:failed to read 8155 bytes
2010-09-15 14:13:15.645740 : sntn2err:entry
2010-09-15 14:13:15.645767 : sntn2err:error: handle=532, op=5, nt[0]=524, nt[1]=997
2010-09-15 14:13:15.645795 : sntn2err:exit
2010-09-15 14:13:15.645830 : nsmal:84 bytes at 0x1e16e68
2010-09-15 14:13:15.645874 : ntnrd:failed to read 8155 bytes
2010-09-15 14:13:15.645903 : sntn2err:entry
2010-09-15 14:13:15.645931 : sntn2err:error: handle=532, op=5, nt[0]=524, nt[1]=997
2010-09-15 14:13:15.645958 : sntn2err:exit
2010-09-15 14:13:15.645989 : nsevreg:rdm=2, sgt=0, evt[0]=0x1, [1]=0x1, [2]=0x0, nrg=0
2010-09-15 14:13:15.646018 : nsevreg:registering for 0x1
2010-09-15 14:13:15.646068 : nsglhins:entry
2010-09-15 14:13:15.646099 : nsglhins:exit
2010-09-15 14:13:15.646180 : nsc2addr:(ADDRESS=(PROTOCOL=tcp)(HOST=127.0.0.1)(PARTIAL=yes)(QUEUESIZE=1))
2010-09-15 14:13:15.646440 : nttbnd2addr:entry
2010-09-15 14:13:15.646487 : snlinGetAddrInfo:entry
2010-09-15 14:13:15.646621 : snlinGetAddrInfo:exit
2010-09-15 14:13:15.646653 : nttbnd2addr:using host IP address: 127.0.0.1
2010-09-15 14:13:15.646686 : snlinFreeAddrInfo:entry
2010-09-15 14:13:15.646714 : snlinFreeAddrInfo:exit
2010-09-15 14:13:15.646832 : nttbnd2addr:exit
2010-09-15 14:13:15.646868 : nsmal:996 bytes at 0x1e19010
2010-09-15 14:13:15.646900 : nsmal:2096 bytes at 0x1e19400
2010-09-15 14:13:15.646932 : nsmal:84 bytes at 0x1e19d70
2010-09-15 14:13:15.646984 : nttcon:entry
2010-09-15 14:13:15.647013 : nttcon:toc = 6
2010-09-15 14:13:15.647052 : nttcnp:entry
2010-09-15 14:13:15.647080 : nttcnp:exit
2010-09-15 14:13:15.647117 : nttcon:exit
2010-09-15 14:13:15.647147 : nsopen:opening transport...
2010-09-15 14:13:15.647175 : nttcon:entry
2010-09-15 14:13:15.647201 : nttcon:toc = 2
2010-09-15 14:13:15.647229 : nttcnp:entry
2010-09-15 14:13:15.647259 : ntvlin:entry
2010-09-15 14:13:15.647291 : ntvllt:entry
2010-09-15 14:13:15.647323 : ntvllt:tcp.validnode_checking not turned on
2010-09-15 14:13:15.647351 : ntvllt:exit
2010-09-15 14:13:15.647377 : ntvlin:exit
2010-09-15 14:13:15.647404 : nttcnp:Validnode Table IN use; err 0x0
2010-09-15 14:13:15.648365 : nttcnp:getting sockname
2010-09-15 14:13:15.648422 : nttcnp:getting peername
2010-09-15 14:13:15.648452 : nttcnp:exit
2010-09-15 14:13:15.648488 : nttcon:exit
2010-09-15 14:13:15.648517 : nsopen:transport is open
2010-09-15 14:13:15.648548 : nsoptions:lcl[0]=0x0, lcl[1]=0x10003, gbl[0]=0x0, gbl[1]=0x2001, cha=0x0
2010-09-15 14:13:15.648581 : nsoptions:lcl[0]=0xf4ffefff, lcl[1]=0x10003, gbl[0]=0x7abf, gbl[1]=0x2001
2010-09-15 14:13:15.648616 : nsopen:global context check-in (to slot 2) complete
2010-09-15 14:13:15.648648 : nsopen:lcl[0]=0xf4ffefff, lcl[1]=0x10003, gbl[0]=0x7abf, gbl[1]=0x2001, tdu=32767, sdu=8192
2010-09-15 14:13:15.648682 : nsfull_opn:cid=2, opcode=65, bl=0, what=0, uflgs=0x0, cflgs=0x0
2010-09-15 14:13:15.648713 : nsfull_opn:nsctx: state=7, flg=0x4002, mvd=0
2010-09-15 14:13:15.648746 : nsmal:84 bytes at 0x1e1c688
2010-09-15 14:13:15.648815 : nladget:entry
2010-09-15 14:13:15.648868 : nladget:exit
2010-09-15 14:13:15.648903 : nsgllsn:LSNDSC==>(DESCRIPTION=(ADDRESS=(PROTOCOL=TCPS)(HOST=kcr-PC)(PORT=2484)))
2010-09-15 14:13:15.649018 : nsglhcrt:entry
2010-09-15 14:13:15.649230 : nsglhcrt:exit
2010-09-15 14:13:15.649265 : nsglbgetRSPidx:entry
2010-09-15 14:13:15.649293 : nladini:entry
2010-09-15 14:13:15.649328 : nladini:exit
2010-09-15 14:13:15.649354 : nladget:entry
2010-09-15 14:13:15.649401 : nladget:exit
2010-09-15 14:13:15.649428 : nsglbn2i:entry
2010-09-15 14:13:15.649456 : nsglbn2i:exit
2010-09-15 14:13:15.649500 : nladtrm:entry
2010-09-15 14:13:15.649531 : nladtrm:exit
2010-09-15 14:13:15.649559 : nsglbgetRSPidx:returning ecode=0
2010-09-15 14:13:15.649586 : nsglbgetRSPidx:exit
2010-09-15 14:13:15.649654 : nsc2addr:(DESCRIPTION=(ADDRESS=(PROTOCOL=TCPS)(HOST=kcr-PC)(PORT=2484)))
2010-09-15 14:13:15.650926 : ntzini:entry
2010-09-15 14:13:15.650997 : ntzSetupConnection:entry
2010-09-15 14:13:15.651039 : ntzgbhapip:entry
2010-09-15 14:13:15.651072 : ntzgbhapip:no value for bhapi parameter specified - using default value: "TRUE"
2010-09-15 14:13:15.651101 : ntzgbhapip:exit
2010-09-15 14:13:15.657818 : ntzSetupConnection:exit
2010-09-15 14:13:15.657905 : ntzSetupConnection:entry
2010-09-15 14:13:15.657936 : ntzgbhapip:entry
2010-09-15 14:13:15.657968 : ntzgbhapip:no value for bhapi parameter specified - using default value: "TRUE"
2010-09-15 14:13:15.657998 : ntzgbhapip:exit
2010-09-15 14:13:15.663017 : ntzSetupConnection:exit
2010-09-15 14:13:15.663075 : ntzcsgtab:entry
2010-09-15 14:13:15.663110 : ntzcsgtab:exit
2010-09-15 14:13:15.663137 : ntzini:exit
2010-09-15 14:13:15.663166 : nttbnd2addr:entry
2010-09-15 14:13:15.663197 : snlinGetAddrInfo:entry
2010-09-15 14:13:15.663240 : snlinGetAddrInfo:getaddrinfo() failed with error 11001
2010-09-15 14:13:15.663269 : snlinGetAddrInfo:exit
2010-09-15 14:13:15.663297 : nttbnd2addr:looking up IP addr for host: kcr-PC
2010-09-15 14:13:15.663324 : snlinGetAddrInfo:entry
2010-09-15 14:13:15.664291 : snlinGetAddrInfo:exit
2010-09-15 14:13:15.664336 : snlinFreeAddrInfo:entry
2010-09-15 14:13:15.664365 : snlinFreeAddrInfo:exit
2010-09-15 14:13:15.664392 : nttbnd2addr:exit
2010-09-15 14:13:15.664445 : nsmal:996 bytes at 0x1e48d18
2010-09-15 14:13:15.664479 : nsmal:2096 bytes at 0x1e49108
2010-09-15 14:13:15.664533 : nsmal:84 bytes at 0x1e49a90
2010-09-15 14:13:15.664583 : ntzconnect:entry
2010-09-15 14:13:15.664612 : nttcon:entry
2010-09-15 14:13:15.664640 : nttcon:toc = 6
2010-09-15 14:13:15.664676 : nttcnp:entry
2010-09-15 14:13:15.664703 : nttcnp:exit
2010-09-15 14:13:15.664734 : nttcon:exit
2010-09-15 14:13:15.664761 : ntzconnect:underlying transport adapter failed.
2010-09-15 14:13:15.664794 : ntzconnect:failed with error 551
2010-09-15 14:13:15.664849 : ntzconnect:exit
2010-09-15 14:13:15.664879 : nsopen:opening transport...
2010-09-15 14:13:15.664908 : ntzconnect:entry
2010-09-15 14:13:15.664934 : nttcon:entry
2010-09-15 14:13:15.664960 : nttcon:toc = 2
2010-09-15 14:13:15.664988 : nttcnp:entry
2010-09-15 14:13:15.665015 : ntvlin:entry
2010-09-15 14:13:15.665042 : ntvllt:entry
2010-09-15 14:13:15.665074 : ntvllt:tcp.validnode_checking not turned on
2010-09-15 14:13:15.665102 : ntvllt:exit
2010-09-15 14:13:15.665128 : ntvlin:exit
2010-09-15 14:13:15.665155 : nttcnp:Validnode Table IN use; err 0x0
2010-09-15 14:13:15.665380 : snlinGetAddrInfo:entry
2010-09-15 14:13:15.666066 : snlinGetAddrInfo:exit
2010-09-15 14:13:15.666106 : snlinFreeAddrInfo:entry
2010-09-15 14:13:15.666134 : snlinFreeAddrInfo:exit
2010-09-15 14:13:15.666213 : nttcnp:getting sockname
2010-09-15 14:13:15.666245 : nttcnp:getting peername
2010-09-15 14:13:15.666273 : nttcnp:exit
2010-09-15 14:13:15.666417 : snlinGetAddrInfo:entry
2010-09-15 14:13:15.666901 : snlinGetAddrInfo:exit
2010-09-15 14:13:15.666939 : snlinFreeAddrInfo:entry
2010-09-15 14:13:15.666966 : snlinFreeAddrInfo:exit
2010-09-15 14:13:15.666994 : nttcon:exit
2010-09-15 14:13:15.667022 : ntzAllocate:entry
2010-09-15 14:13:15.667050 : ntzAllocate:allocating 168 bytes of memory.
2010-09-15 14:13:15.667079 : ntzAllocate:exit
2010-09-15 14:13:15.667105 : ntzconnect:exit
2010-09-15 14:13:15.667134 : nsopen:transport is open
2010-09-15 14:13:15.667164 : nsoptions:lcl[0]=0x0, lcl[1]=0x12003, gbl[0]=0x0, gbl[1]=0x2001, cha=0x0
2010-09-15 14:13:15.667198 : nsoptions:lcl[0]=0xf4ffefff, lcl[1]=0x12003, gbl[0]=0x7abf, gbl[1]=0x2001
2010-09-15 14:13:15.667233 : nsopen:global context check-in (to slot 3) complete
2010-09-15 14:13:15.667265 : nsopen:lcl[0]=0xf4ffefff, lcl[1]=0x12003, gbl[0]=0x7abf, gbl[1]=0x2001, tdu=32767, sdu=8192
2010-09-15 14:13:15.667300 : nsfull_opn:cid=3, opcode=65, bl=0, what=0, uflgs=0x0, cflgs=0x0
2010-09-15 14:13:15.667330 : nsfull_opn:nsctx: state=7, flg=0x4002, mvd=0
2010-09-15 14:13:15.667361 : nsmal:84 bytes at 0x1e49af0
2010-09-15 14:13:15.667419 : nsgllsn:completing partial addr: (DESCRIPTION=(ADDRESS=(PROTOCOL=TCPS)(HOST=kcr-PC)(PORT=2484)))
2010-09-15 14:13:15.667462 : nttaddr2bnd:entry
2010-09-15 14:13:15.667604 : snlinGetAddrInfo:entry
2010-09-15 14:13:15.668269 : snlinGetAddrInfo:exit
2010-09-15 14:13:15.668305 : snlinFreeAddrInfo:entry
2010-09-15 14:13:15.668333 : snlinFreeAddrInfo:exit
2010-09-15 14:13:15.668367 : nttaddr2bnd:exit
2010-09-15 14:13:15.669099 : nsgllsn:Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=tcps)(HOST=kcr-PC)(PORT=2484)))
2010-09-15 14:13:15.669141 : nsevreg:begin registration process for 3
2010-09-15 14:13:15.669174 : nsevreg:sgt=0, evn=1, evt[2]=0x0
2010-09-15 14:13:15.669203 : nsevreg:begin notification process for 3
2010-09-15 14:13:15.669234 : nsevreg:rdm=0, sgt=0, evt[0]=0x1, [1]=0x1, [2]=0x0, nrg=0
2010-09-15 14:13:15.669263 : nsevre