cancel
Showing results for 
Search instead for 
Did you mean: 

TNS - 12531 : Cannot allocate memory - Listner does not start

Former Member
0 Kudos

Hi Experts,

Recently I am facing a new problem where the listener doesn't startup.

OS - Unix AIX

DB - Oracle 10g 10.2

The UNIX was restarted for some other changes after which i wanted to start SAP EP. But when I started the listener ( >lsnrctl start ) it just hangs and doesn't start. I checked the listener.log file I foud the following eror

TNS - 12531 : Cannot allocate memory

But as ths error suggest there is no memory problem. Nothing is runing on the box as it was just restarted and it has more than enough memory ( 16 GB).

I checked the listener.ora and tnsnames.ora file and found nothing wrong. I don't understant why its giving this error as nothing was changed. It was only a UNIX restart. We have an ERP instance and a EP 7.0 instance on it. They were running very smoothly.

I tried to increase the log level (USER & ADMIN) of listener but in the listener.trc file also it shows the same memory problem.

Please assist.

Accepted Solutions (1)

Accepted Solutions (1)

stefan_koehler
Active Contributor
0 Kudos

Hello Jimmy,

the ORA-error TNS-12531 is just a little bit misleading. It points not only to a memory problem - it is facing an network error, too.

It is described in metalink note #219206.1

So please do the following and post your results:

-


shell>hostname

shell>uname -n

shell>lsattr -E -l inet0

shell>ping system02

shell>traceroute system02

shell>cat /etc/hosts | grep system02

-


Regards

Stefan

Former Member
0 Kudos

Hi Stefan,

Thanks for the reply. I do think the same that TNS-12531 is misleading as there was no problem so far and the system was running smoothly before. Here are the results you asked

Am using ssh to connect as telenet has been disabled so i get hostname as +Bstem02 instead of system02 . Dont know the reason why ?

*>hostname *

+Bstem02

>uname -n

+Bstem02

>lsattr -E -l init0

authm 65536 Authentication Methods True

bootup_option no Use BSD-style Network Configuration True

gateway Gateway True

hostname +Bstem02 Host Name True

rout6 IPv6 Route True

route net,-hopcount,0,,0,10.3.28.1 Route True

>ping system02

PING system02: (10.3.28.43): 56 data bytes

64 bytes from 10.3.28.43: icmp_seq=0 ttl=255 time=0 ms

64 bytes from 10.3.28.43: icmp_seq=1 ttl=255 time=0 ms

64 bytes from 10.3.28.43: icmp_seq=2 ttl=255 time=0 ms

64 bytes from 10.3.28.43: icmp_seq=3 ttl=255 time=0 ms

64 bytes from 10.3.28.43: icmp_seq=4 ttl=255 time=0 ms

^C

-


system02 PING Statistics----

5 packets transmitted, 5 packets received, 0% packet loss

round-trip min/avg/max = 0/0/0 ms

>traceroute system02

trying to get source for system02

source should be 10.3.28.43

traceroute to system02 (10.3.28.43) from 10.3.28.43 (10.3.28.43), 30 hops max

outgoing MTU = 16384

1 system02 (10.3.28.43) 0 ms 0 ms 0 ms

>cat /etc/hosts |grep system02

10.3.28.43 system02

10.3.56.106 system02s

Regards

Jimmy

stefan_koehler
Active Contributor
0 Kudos

Hello Jimmy,

as i can see in your listener trace you have "system02" as HOST but your hostname is Bstem02.

Your inet0 device is point also to "Bstem02".

I have seen such situations many times and oracle net is very sensitive on AIX in this way.. so please try the following:

-


1) Change the Listener configuration to the following:

LISTENER = (ADDRESS_LIST = (ADDRESS = (PROTOCOL = IPC) (KEY = EPQ.WORLD)) (ADDRESS= (PROTOCOL = IPC) (KEY = EPQ)) (ADDRESS =

(COMMUNITY = SAP.WORLD) (PROTOCOL = TCP) (HOST = Bstem02) (PORT = 1527)))

2) Try to start the listener

-


If it doesn't solve the problem... please post the following output

shell> cat /etc/hosts | grep -i Bstem02

shell> ping Bstem02

shell> traceroute Bstem02

shell> ls -la /etc/hosts

Btw. are Bstem02 and system02 in the same domain?

Regards

Stefan

Former Member
0 Kudos

Hi Stefan,

.

The problem still exists

This is what i get when i changed the listener.ora & tnsnames.ora to include Bstem02

LSNRCTL for IBM/AIX RISC System/6000: Version 10.2.0.2.0 - Production on 20-DEC-2007 11:21:38

Copyright (c) 1991, 2005, Oracle. All rights reserved.

Starting /oracle/EPQ/102_64/bin/tnslsnr: please wait...

TNSLSNR for IBM/AIX RISC System/6000: Version 10.2.0.2.0 - Production

System parameter file is /oracle/EPQ/102_64/network/admin/listener.ora

Log messages written to /oracle/EPQ/102_64/network/log/listener.log

Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EPQ.WORLD)))

Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EPQ)))

Error listening on: (ADDRESS=(COMMUNITY=SAP.WORLD)(PROTOCOL=TCP)(HOST=Bstem02)(PORT=1527))

TNS-12545: Connect failed because target host or object does not exist

TNS-12560: TNS:protocol adapter error

TNS-00515: Connect failed because target host or object does not exist

IBM/AIX RISC System/6000 Error: 78: Connection timed out

Listener failed to start. See the error message(s) above...

-


After this the UNIX guys changed the uname to system02

This after i change it back to system02

LSNRCTL for IBM/AIX RISC System/6000: Version 10.2.0.2.0 - Production on 20-DEC-2007 11:40:53

Copyright (c) 1991, 2005, Oracle. All rights reserved.

Starting /oracle/EPQ/102_64/bin/tnslsnr: please wait...

TNSLSNR for IBM/AIX RISC System/6000: Version 10.2.0.2.0 - Production

System parameter file is /oracle/EPQ/102_64/network/admin/listener.ora

Log messages written to /oracle/EPQ/102_64/network/log/listener.log

Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EPQ.WORLD)))

Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EPQ)))

Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=tcp)(HOST=10.3.28.43)(PORT=1527)))

Connecting to (ADDRESS=(PROTOCOL=IPC)(KEY=EPQ.WORLD))

-


Further results of the commands you asked for

+Bstem02:oraepq> hostname

+Bstem02

+Bstem02:oraepq> uname -n

system02

+Bstem02:oraepq> lsattr -E -l init0

lsattr: 0514-519 The following device was not found in the customized

device configuration database:

init0

+Bstem02:oraepq> ping Bstem02

0821-062 ping: host name Bstem02 NOT FOUND

+Bstem02:oraepq> traceroute Bstem02

traceroute: unknown host Bstem02

+Bstem02:oraepq> cat /etc/hosts | grep Bstem02

+Bstem02:oraepq> cat /etc/hosts |grep system02

10.3.28.43 system02

10.3.56.106 system02s

Now what I don't understand is that where does +Bstem02 come from and even in the command prompt. Am using ssh to connect as telnet is blocked and Exceed software.

Why does hostname say +Bstem02 ? where does it read this from as there s no such host.

Moreover the box is 99.7% idle , 16GB memory present and nothing running on the box.

Here is my current listener.ora file

-


################

  1. Filename......: listener.ora

  2. Created.......: created by SAP AG, R/3 Rel. >= 6.10

  3. Name..........:

  4. Date..........:

  5. @(#) $Id: //bc/700-1_REL/src/ins/SAPINST/impl/tpls/ora/ind/LISTENER.ORA#4 $

################

ADMIN_RESTRICTIONS_LISTENER = on

LISTENER =

(ADDRESS_LIST =

(ADDRESS =

(PROTOCOL = IPC)

(KEY = EPQ.WORLD)

)

(ADDRESS=

(PROTOCOL = IPC)

(KEY = EPQ)

)

(ADDRESS =

(COMMUNITY = SAP.WORLD)

(PROTOCOL = TCP)

(HOST = system02)

(PORT = 1527)

)

)

STARTUP_WAIT_TIME_LISTENER = 0

CONNECT_TIMEOUT_LISTENER = 10

TRACE_LEVEL_LISTENER = OFF

SID_LIST_LISTENER =

(SID_LIST =

(SID_DESC =

(SID_NAME = EPQ)

(ORACLE_HOME = /oracle/EPQ/102_64)

)

)

-


file tnsnames.ora

stefan_koehler
Active Contributor
0 Kudos

Hello Jimmy,

you have to check the device inet0 not init0.

shell> lsattr -E -l inet0

The name Bstem02 comes out if the inet0 device. Please check this again with the command that i wrote above. AIX has "different" places where it store its hostname.

So as i can see the only hostname that really exists is system02. So AIX have to set system02 in the inet0 device - if not you will get errors like that.

Please do the following again and post the results:

-


shell> lsattr -E -l inet0

shell> smitty hostname (and set the hostname to system02) or "shell> chdev -l inet0 -a hostname=system02"

shell> lsattr -E -l inet0

shell> hostname

shell> uname -n

-


Btw. as you said:

>> After this the UNIX guys changed the uname to system02 This after i change it back to system02

Does it now work or not?

Regards

Stefan

Former Member
0 Kudos

Hi Stefan,

Thankyou very much. I did change the hotsname as you instructed and it works now. The listener started up.

Finally it was the hostname that was the culprit.

Awarded you the points for your assistance.

Regards,

Jimmy.

Answers (2)

Answers (2)

markus_doehr2
Active Contributor
0 Kudos

No.. not obvious...

On which ML/TL level are you on? (oslevel -r)

Markus

Former Member
0 Kudos

Hi Markus,

ML/TL level

> oslevel -r

5300-05

Regards,

Jimmy

markus_doehr2
Active Contributor
0 Kudos

Check

977410 - Oracle error after upgrade to AIX 5.3 TL05

You should update to 5300-05-01 (at least) - there are known problem with only TL5 an Oracle.

Also check your VMM settings:

973227 - AIX Virtual Memory Management: Tuning Recommendations

Markus

markus_doehr2
Active Contributor
0 Kudos

Check, that the shell limits of the user are set correctly.

What do you see when you execute

limits (for csh)

ulimit -a (for sh)

Markus

Former Member
0 Kudos

Hi Markus,

Thanks for your reply.

This is the result of ulimit - a

time(seconds) unlimited

file(blocks) unlimited

data(kbytes) unlimited

stack(kbytes) unlimited

memory(kbytes) unlimited

coredump(blocks) 2097151

nofiles(descriptors) unlimited

What does it suggest...

Here is my listener.trc file contents

18-DEC-2007 12:07:09:150] --- TRACE CONFIGURATION INFORMATION FOLLOWS ---

[18-DEC-2007 12:07:09:150] New trace stream is /oracle/EPQ/102_64/network/trace/listener.trc

[18-DEC-2007 12:07:09:150] New trace level is 6

[18-DEC-2007 12:07:09:150] --- TRACE CONFIGURATION INFORMATION ENDS ---

[18-DEC-2007 12:07:09:150] --- PARAMETER SOURCE INFORMATION FOLLOWS ---

[18-DEC-2007 12:07:09:150] Attempted load of system pfile source /oracle/EPQ/102_64/network/admin/listener.ora

[18-DEC-2007 12:07:09:150] Parameter source loaded successfully

[18-DEC-2007 12:07:09:150]

[18-DEC-2007 12:07:09:150] Attempted load of command line source

[18-DEC-2007 12:07:09:150] Parameter source was not loaded

[18-DEC-2007 12:07:09:150]

[18-DEC-2007 12:07:09:150] -> PARAMETER TABLE LOAD RESULTS FOLLOW <-

[18-DEC-2007 12:07:09:150] Successful parameter table load

[18-DEC-2007 12:07:09:150] -> PARAMETER TABLE HAS THE FOLLOWING CONTENTS <-

[18-DEC-2007 12:07:09:150] SID_LIST_LISTENER = (SID_LIST = (SID_DESC = (SID_NAME = EPQ) (ORACLE_HOME = /oracle/EPQ/102_64)))

[18-DEC-2007 12:07:09:150] ADMIN_RESTRICTIONS_LISTENER = on

[18-DEC-2007 12:07:09:150] TRACE_LEVEL_LISTENER = ADMIN

[18-DEC-2007 12:07:09:150] LISTENER = (ADDRESS_LIST = (ADDRESS = (PROTOCOL = IPC) (KEY = EPQ.WORLD)) (ADDRESS= (PROTOCOL = IPC) (KEY = EPQ)) (ADDRESS =

(COMMUNITY = SAP.WORLD) (PROTOCOL = TCP) (HOST = system02) (PORT = 1527)))

[18-DEC-2007 12:07:09:150] CONNECT_TIMEOUT_LISTENER = 10

[18-DEC-2007 12:07:09:150] STARTUP_WAIT_TIME_LISTENER = 0

[18-DEC-2007 12:07:09:150] --- PARAMETER SOURCE INFORMATION ENDS ---

[18-DEC-2007 12:07:09:150] --- LOG CONFIGURATION INFORMATION FOLLOWS ---

[18-DEC-2007 12:07:09:150] Log stream will be "/oracle/EPQ/102_64/network/log/listener.log"

[18-DEC-2007 12:07:09:151] Log stream validation requested

[18-DEC-2007 12:07:09:151] Log stream is valid

[18-DEC-2007 12:07:09:151] --- LOG CONFIGURATION INFORMATION ENDS ---

[18-DEC-2007 12:07:09:151] nlstdipi: entry

[18-DEC-2007 12:07:09:151] nlstdipi: exit

[18-DEC-2007 12:07:09:151] nsglldprm: Resolved "INBOUND_CONNECT_TIMEOUT_LISTENER" to: 0

[18-DEC-2007 12:07:09:151] nsglldprm: Resolved "STARTUP_WAIT_TIME_LISTENER" to: 0

[18-DEC-2007 12:07:09:151] nsglldprm: LocalOSauth enabled

[18-DEC-2007 12:07:09:151] snsgblini: exit

[18-DEC-2007 12:07:09:158] nsinherit: doing connect handshake...

[18-DEC-2007 12:07:09:158] nsc2addr: (ADDRESS=(PROTOCOL=beq))

[18-DEC-2007 12:07:09:158] nlpcaini: entry

[18-DEC-2007 12:07:09:158] nlpcaini: No process parameters set

[18-DEC-2007 12:07:09:158] nlpcaini: exit

[18-DEC-2007 12:07:09:158] nsinh_hoff: inheriting the connection...

[18-DEC-2007 12:07:09:158] nsmal: 1144 bytes at 0x1105f9df0

[18-DEC-2007 12:07:09:158] nsmal: 2160 bytes at 0x1105fa290

[18-DEC-2007 12:07:09:158] nsmal: 168 bytes at 0x1105fac50

[18-DEC-2007 12:07:09:158] nsopen: opening transport...

[18-DEC-2007 12:07:09:158] ntpcon: entry

[18-DEC-2007 12:07:09:158] ntpcon: toc = 4

[18-DEC-2007 12:07:09:158] ntpcon: NT layer IPC connection has been established.

[18-DEC-2007 12:07:09:158] ntpcon: exit

[18-DEC-2007 12:07:09:158] nsopen: transport is open

[18-DEC-2007 12:07:09:158] nsoptions: lcl[0]=0x0, lcl[1]=0x10000000, gbl[0]=0x0, gbl[1]=0x0, cha=0x0

[18-DEC-2007 12:07:09:158] nsoptions: lcl[0]=0xf4ffe9ff, lcl[1]=0x10000000, gbl[0]=0x7ebf, gbl[1]=0x0

[18-DEC-2007 12:07:09:158] nsnainit: inherit

[18-DEC-2007 12:07:09:158] nsnasvnainfo: NA disabled for this connection

[18-DEC-2007 12:07:09:158] ntpctl: entry

[18-DEC-2007 12:07:09:158] ntpctl: exit

[18-DEC-2007 12:07:09:158] nainit: entry

[18-DEC-2007 12:07:09:158] nagblini: entry

[18-DEC-2007 12:07:09:159] nau_gin: entry

[18-DEC-2007 12:07:09:159] nau_gparams: entry

[18-DEC-2007 12:07:09:159] nam_gbp: Reading parameter "sqlnet.authentication_required" from parameter file

[18-DEC-2007 12:07:09:159] nam_gbp: Parameter not found

[18-DEC-2007 12:07:09:159] nau_gparams: Using default value "FALSE"

[18-DEC-2007 12:07:09:159] nau_gslf: entry

[18-DEC-2007 12:07:09:159] nam_gic: entry

[18-DEC-2007 12:07:09:159] nam_gic: Counting # of items in "sqlnet.authentication_services" parameter

[18-DEC-2007 12:07:09:159] nam_gic: Parameter not found

[18-DEC-2007 12:07:09:159] nam_gic: Found 0 items

[18-DEC-2007 12:07:09:159] nam_gic: exit

[18-DEC-2007 12:07:09:159] nau_gslf: Using default value "all available adapters"

[18-DEC-2007 12:07:09:159] nauss_set_state: entry

[18-DEC-2007 12:07:09:159] nauss_set_state: exit

[18-DEC-2007 12:07:09:159] nau_gslf: exit

[18-DEC-2007 12:07:09:159] nau_gparams: exit

[18-DEC-2007 12:07:09:159] nau_gin: exit

[18-DEC-2007 12:07:09:159] nagblini: exit

[18-DEC-2007 12:07:09:159] na_saveprot: entry

[18-DEC-2007 12:07:09:159] na_saveprot: exit

[18-DEC-2007 12:07:09:159] nacomin: entry

[18-DEC-2007 12:07:09:159] nas_init: entry

[18-DEC-2007 12:07:09:159] nas_init: exit

[18-DEC-2007 12:07:09:159] nau_ini: entry

[18-DEC-2007 12:07:09:159] naugcp_get_connect_parameters: entry

[18-DEC-2007 12:07:09:159] nauss_set_state: entry

[18-DEC-2007 12:07:09:159] nauss_set_state: exit

[18-DEC-2007 12:07:09:159] naugcp_get_connect_parameters: exit

[18-DEC-2007 12:07:09:159] nau_gettab: entry

[18-DEC-2007 12:07:09:160] nau_gettab: exit

[18-DEC-2007 12:07:09:160] nau_sini: entry

[18-DEC-2007 12:07:09:160] nau_sini: exit

[18-DEC-2007 12:07:09:160] nau_ini: exit

[18-DEC-2007 12:07:09:160] naeeinit: entry

[18-DEC-2007 12:07:09:160] nam_gbp: Reading parameter "SQLNET.FIPS_140" from parameter file

[18-DEC-2007 12:07:09:160] nam_gbp: Parameter not found

[18-DEC-2007 12:07:09:160] nam_gnsp: Reading parameter "SQLNET.ENCRYPTION_SERVER" from parameter file

[18-DEC-2007 12:07:09:160] nam_gnsp: Parameter not found

[18-DEC-2007 12:07:09:160] naequad: Using default value "ACCEPTED"

[18-DEC-2007 12:07:09:160] nam_gic: entry

[18-DEC-2007 12:07:09:160] nam_gic: Counting # of items in "SQLNET.ENCRYPTION_TYPES_SERVER" parameter

[18-DEC-2007 12:07:09:160] nam_gic: Parameter not found

[18-DEC-2007 12:07:09:160] nam_gic: exit

[18-DEC-2007 12:07:09:160] naesno: Using default value "all available algorithms"

[18-DEC-2007 12:07:09:160] naeshow: entry

[18-DEC-2007 12:07:09:160] naeshow: exit

[18-DEC-2007 12:07:09:160] naeeinit: exit

[18-DEC-2007 12:07:09:160] naecinit: entry

[18-DEC-2007 12:07:09:160] nam_gnsp: Reading parameter "SQLNET.CRYPTO_CHECKSUM_SERVER" from parameter file

[18-DEC-2007 12:07:09:160] nam_gnsp: Parameter not found

[18-DEC-2007 12:07:09:160] naequad: Using default value "ACCEPTED"

[18-DEC-2007 12:07:09:160] nam_gic: entry

[18-DEC-2007 12:07:09:160] nam_gic: Counting # of items in "SQLNET.CRYPTO_CHECKSUM_TYPES_SERVER" parameter

[18-DEC-2007 12:07:09:160] nam_gic: Parameter not found

[18-DEC-2007 12:07:09:160] nam_gic: exit

[18-DEC-2007 12:07:09:160] naesno: Using default value "all available algorithms"

[18-DEC-2007 12:07:09:160] naeshow: entry

[18-DEC-2007 12:07:09:160] naeshow: exit

[18-DEC-2007 12:07:09:160] naecinit: exit

[18-DEC-2007 12:07:09:160] nadisc: entry

[18-DEC-2007 12:07:09:160] nacomtm: entry

[18-DEC-2007 12:07:09:160] nacompd: entry

[18-DEC-2007 12:07:09:160] nacompd: exit

[18-DEC-2007 12:07:09:160] nacompd: entry

[18-DEC-2007 12:07:09:160] nacompd: exit

[18-DEC-2007 12:07:09:160] nacomtm: exit

[18-DEC-2007 12:07:09:160] nas_dis: entry

[18-DEC-2007 12:07:09:160] nas_dis: exit

[18-DEC-2007 12:07:09:160] nau_dis: entry

[18-DEC-2007 12:07:09:160] nau_dis: exit

[18-DEC-2007 12:07:09:160] naeetrm: entry

[18-DEC-2007 12:07:09:161] naeetrm: exit

[18-DEC-2007 12:07:09:161] naectrm: entry

[18-DEC-2007 12:07:09:161] naectrm: exit

[18-DEC-2007 12:07:09:161] nagbltrm: entry

[18-DEC-2007 12:07:09:161] nau_gtm: entry

[18-DEC-2007 12:07:09:161] nau_gtm: exit

[18-DEC-2007 12:07:09:161] nagbltrm: exit

[18-DEC-2007 12:07:09:161] nadisc: exit

[18-DEC-2007 12:07:09:161] nainit: exit

[18-DEC-2007 12:07:09:161] nsnainit: NS Connection version: 313

[18-DEC-2007 12:07:09:161] nsnainit: inf->nsinfflg[0]: 0xd inf->nsinfflg[1]: 0xd

[18-DEC-2007 12:07:09:161] 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

[18-DEC-2007 12:07:09:161] 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

[18-DEC-2007 12:07:09:161] nsopen: global context check-in (to slot 0) complete

[18-DEC-2007 12:07:09:161] nsopen: lcl[0]=0xf4ffe9ff, lcl[1]=0x10000000, gbl[0]=0x7ebf, gbl[1]=0x0, tdu=32767, sdu=2048

[18-DEC-2007 12:07:09:161] nsdo: cid=0, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2

[18-DEC-2007 12:07:09:161] nsdo: rank=64, nsctxrnk=0

[18-DEC-2007 12:07:09:161] nsdo: nsctx: state=7, flg=0x4000, mvd=0

[18-DEC-2007 12:07:09:161] nsmal: 168 bytes at 0x1105fbd10

[18-DEC-2007 12:07:09:161] nsmal: 168 bytes at 0x1105fc610

[18-DEC-2007 12:07:09:161] nsdo: nsctxrnk=0

[18-DEC-2007 12:07:09:161] nsdo: cid=0, opcode=68, *bl=0, *what=8, uflgs=0x0, cflgs=0x3

[18-DEC-2007 12:07:09:161] nsdo: rank=64, nsctxrnk=0

[18-DEC-2007 12:07:09:161] nsdo: nsctx: state=14, flg=0x4004, mvd=0

[18-DEC-2007 12:07:09:161] nsdo: gtn=10, gtc=10, ptn=10, ptc=2011

[18-DEC-2007 12:07:09:161] nscon: doing connect handshake...

[18-DEC-2007 12:07:09:161] nscon: recving a packet

[18-DEC-2007 12:07:09:161] nsprecv: reading from transport...

[18-DEC-2007 12:07:09:161] ntprd: entry

[18-DEC-2007 12:07:09:161] ntprd: exit

[18-DEC-2007 12:07:09:161] nsprecv: 175 bytes from transport

[18-DEC-2007 12:07:09:161] nsprecv: tlen=175, plen=175, type=1

[18-DEC-2007 12:07:09:161] nscon: got NSPTCN packet

[18-DEC-2007 12:07:09:161] nsconneg: vsn=313, lov=300, opt=0x0, sdu=2048, tdu=32767, ntc=0x4f98

[18-DEC-2007 12:07:09:161] nsconneg: vsn=313, gbl=0x0, sdu=2048, tdu=32767

[18-DEC-2007 12:07:09:161] nsmal: 117 bytes at 0x1105fcf10

[18-DEC-2007 12:07:09:161] nscon: got 117 bytes connect data

[18-DEC-2007 12:07:09:161] nsmfr: 117 bytes at 0x1105fcf10

[18-DEC-2007 12:07:09:161] nscon: discarding connect data (117 bytes)

[18-DEC-2007 12:07:09:161] nsdo: nsctxrnk=0

[18-DEC-2007 12:07:09:161] nsinh_hoff: connection inherited

[18-DEC-2007 12:07:09:161] nsinherit: connected

[18-DEC-2007 12:07:09:162] nsglma: entry

[18-DEC-2007 12:07:09:162] nsglma: Listener's pid=549022

[18-DEC-2007 12:07:09:162] nladini: entry

[18-DEC-2007 12:07:09:162] nladini: exit

[18-DEC-2007 12:07:09:162] nladget: entry

[18-DEC-2007 12:07:09:162] nladget: exit

[18-DEC-2007 12:07:09:162] nsgllsn: LSNDSC==>(ADDRESS=(PROTOCOL=IPC)(KEY=EPQ.WORLD))

[18-DEC-2007 12:07:09:162] nsglhcrt: entry

[18-DEC-2007 12:07:09:165] nsglhcrt: exit

[18-DEC-2007 12:07:09:165] nsglbgetRSPidx: entry

[18-DEC-2007 12:07:09:165] nladini: entry

[18-DEC-2007 12:07:09:165] nladini: exit

[18-DEC-2007 12:07:09:165] nladget: entry

[18-DEC-2007 12:07:09:165] nladget: exit

[18-DEC-2007 12:07:09:165] nsglbn2i: entry

[18-DEC-2007 12:07:09:165] nsglbn2i: exit

[18-DEC-2007 12:07:09:165] nladtrm: entry

[18-DEC-2007 12:07:09:165] nladtrm: exit

[18-DEC-2007 12:07:09:165] nsglbgetRSPidx: returning ecode=0

[18-DEC-2007 12:07:09:165] nsglbgetRSPidx: exit

[18-DEC-2007 12:07:09:165] nsc2addr: (ADDRESS=(PROTOCOL=IPC)(KEY=EPQ.WORLD))

[18-DEC-2007 12:07:09:165] sntuscrt: entry

[18-DEC-2007 12:07:09:165] sntuscrt: exit

[18-DEC-2007 12:07:09:165] ntusb2adr: entry

[18-DEC-2007 12:07:09:165] ntusb2adr: KEY = EPQ.WORLD

[18-DEC-2007 12:07:09:165] ntusb2adr: exit

[18-DEC-2007 12:07:09:165] nsmal: 1144 bytes at 0x110617530

[18-DEC-2007 12:07:09:165] nsmal: 2160 bytes at 0x1106179d0

[18-DEC-2007 12:07:09:165] nsmal: 168 bytes at 0x110618390

[18-DEC-2007 12:07:09:165] ntusconn: entry

[18-DEC-2007 12:07:09:165] ntusconn: connect: operation 6

[18-DEC-2007 12:07:09:165] ntuscnp: entry

[18-DEC-2007 12:07:09:165] ntuscnp: exit

[18-DEC-2007 12:07:09:165] ntusconn: exit

[18-DEC-2007 12:07:09:165] nsopen: opening transport...

[18-DEC-2007 12:07:09:165] ntusconn: entry

[18-DEC-2007 12:07:09:165] ntusconn: connect: operation 2

[18-DEC-2007 12:07:09:165] ntuscnp: entry

[18-DEC-2007 12:07:09:165] sntusgph: entry

[18-DEC-2007 12:07:09:165] sntusgph: exit

[18-DEC-2007 12:07:09:165] ntus2err: entry

[18-DEC-2007 12:07:09:166] ntus2err: sd=9, op=1, resnt[0]=512, resnt[1]=67, resnt[2]=0

[18-DEC-2007 12:07:09:166] ntus2err: exit

[18-DEC-2007 12:07:09:166] sntusgph: entry

[18-DEC-2007 12:07:09:173] sntusgph: exit

[18-DEC-2007 12:07:09:181] ntuscnp: exit

[18-DEC-2007 12:07:09:181] ntusconn: exit

[18-DEC-2007 12:07:09:181] nsopen: transport is open

[18-DEC-2007 12:07:09:181] nsoptions: lcl[0]=0x0, lcl[1]=0x12003, gbl[0]=0x0, gbl[1]=0x2001, cha=0x0

[18-DEC-2007 12:07:09:181] nsoptions: lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001

[18-DEC-2007 12:07:09:181] nsopen: global context check-in (to slot 1) complete

[18-DEC-2007 12:07:09:181] nsopen: lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001, tdu=32767, sdu=8192

[18-DEC-2007 12:07:09:181] nsdo: cid=1, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2

[18-DEC-2007 12:07:09:181] nsdo: rank=64, nsctxrnk=0

[18-DEC-2007 12:07:09:181] nsdo: nsctx: state=7, flg=0x4002, mvd=0

[18-DEC-2007 12:07:09:181] nsdo: nsctxrnk=0

[18-DEC-2007 12:07:09:181] nsgllsn: completing partial addr: (ADDRESS=(PROTOCOL=IPC)(KEY=EPQ.WORLD))

[18-DEC-2007 12:07:09:181] ntusad2b: entry

[18-DEC-2007 12:07:09:181] ntusad2b: exit

[18-DEC-2007 12:07:09:181] nsgllsn: Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EPQ.WORLD)))

[18-DEC-2007 12:07:09:181] nsevreg: begin registration process for 1

[18-DEC-2007 12:07:09:181] nsevreg: sgt=0, evn=1, evt[2]=0x0

[18-DEC-2007 12:07:09:181] nsevreg: begin notification process for 1

[18-DEC-2007 12:07:09:181] nsevreg: rdm=0, sgt=0, evt[0]=0x1, [1]=0x1, [2]=0x0, nrg=0

[18-DEC-2007 12:07:09:181] nsevreg: registering for 0x1

[18-DEC-2007 12:07:09:181] nsglhins: entry

[18-DEC-2007 12:07:09:181] nsglhins: exit

[18-DEC-2007 12:07:09:181] nsc2addr: (ADDRESS=(PROTOCOL=ipc)(PARTIAL=yes)(QUEUESIZE=1))

[18-DEC-2007 12:07:09:181] ntusb2adr: entry

[18-DEC-2007 12:07:09:181] nsmal: 1144 bytes at 0x11061b2f0

[18-DEC-2007 12:07:09:181] nsmal: 2160 bytes at 0x11061b790

[18-DEC-2007 12:07:09:165] nsmal: 168 bytes at 0x110618390

[18-DEC-2007 12:07:09:165] ntusconn: entry

[18-DEC-2007 12:07:09:165] ntusconn: connect: operation 6

[18-DEC-2007 12:07:09:165] ntuscnp: entry

[18-DEC-2007 12:07:09:165] ntuscnp: exit

[18-DEC-2007 12:07:09:165] ntusconn: exit

[18-DEC-2007 12:07:09:165] nsopen: opening transport...

[18-DEC-2007 12:07:09:165] ntusconn: entry

[18-DEC-2007 12:07:09:165] ntusconn: connect: operation 2

[18-DEC-2007 12:07:09:165] ntuscnp: entry

[18-DEC-2007 12:07:09:165] sntusgph: entry

[18-DEC-2007 12:07:09:165] sntusgph: exit

[18-DEC-2007 12:07:09:165] ntus2err: entry

[18-DEC-2007 12:07:09:166] ntus2err: sd=9, op=1, resnt[0]=512, resnt[1]=67, resnt[2]=0

[18-DEC-2007 12:07:09:166] ntus2err: exit

[18-DEC-2007 12:07:09:166] sntusgph: entry

[18-DEC-2007 12:07:09:173] sntusgph: exit

[18-DEC-2007 12:07:09:181] ntuscnp: exit

[18-DEC-2007 12:07:09:181] ntusconn: exit

[18-DEC-2007 12:07:09:181] nsopen: transport is open

[18-DEC-2007 12:07:09:181] nsoptions: lcl[0]=0x0, lcl[1]=0x12003, gbl[0]=0x0, gbl[1]=0x2001, cha=0x0

[18-DEC-2007 12:07:09:181] nsoptions: lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001

[18-DEC-2007 12:07:09:181] nsopen: global context check-in (to slot 1) complete

[18-DEC-2007 12:07:09:181] nsopen: lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001, tdu=32767, sdu=8192

[18-DEC-2007 12:07:09:181] nsdo: cid=1, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2

[18-DEC-2007 12:07:09:181] nsdo: rank=64, nsctxrnk=0

[18-DEC-2007 12:07:09:181] nsdo: nsctx: state=7, flg=0x4002, mvd=0

[18-DEC-2007 12:07:09:181] nsdo: nsctxrnk=0

[18-DEC-2007 12:07:09:181] nsgllsn: completing partial addr: (ADDRESS=(PROTOCOL=IPC)(KEY=EPQ.WORLD))

[18-DEC-2007 12:07:09:181] ntusad2b: entry

[18-DEC-2007 12:07:09:181] ntusad2b: exit

[18-DEC-2007 12:07:09:181] nsgllsn: Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EPQ.WORLD)))

[18-DEC-2007 12:07:09:181] nsevreg: begin registration process for 1

[18-DEC-2007 12:07:09:181] nsevreg: sgt=0, evn=1, evt[2]=0x0

[18-DEC-2007 12:07:09:181] nsevreg: begin notification process for 1

[18-DEC-2007 12:07:09:181] nsevreg: rdm=0, sgt=0, evt[0]=0x1, [1]=0x1, [2]=0x0, nrg=0

[18-DEC-2007 12:07:09:181] nsevreg: registering for 0x1

[18-DEC-2007 12:07:09:181] nsglhins: entry

[18-DEC-2007 12:07:09:181] nsglhins: exit

[18-DEC-2007 12:07:09:181] nsc2addr: (ADDRESS=(PROTOCOL=ipc)(PARTIAL=yes)(QUEUESIZE=1))

[18-DEC-2007 12:07:09:181] ntusb2adr: entry

[18-DEC-2007 12:07:09:181] nsmal: 1144 bytes at 0x11061b2f0

[18-DEC-2007 12:07:09:181] nsmal: 2160 bytes at 0x11061b790

[18-DEC-2007 12:07:09:165] nsmal: 168 bytes at 0x110618390

[18-DEC-2007 12:07:09:165] ntusconn: entry

[18-DEC-2007 12:07:09:165] ntusconn: connect: operation 6

[18-DEC-2007 12:07:09:165] ntuscnp: entry

[18-DEC-2007 12:07:09:165] ntuscnp: exit

[18-DEC-2007 12:07:09:165] ntusconn: exit

[18-DEC-2007 12:07:09:165] nsopen: opening transport...

[18-DEC-2007 12:07:09:165] ntusconn: entry

[18-DEC-2007 12:07:09:165] ntusconn: connect: operation 2

[18-DEC-2007 12:07:09:165] ntuscnp: entry

[18-DEC-2007 12:07:09:165] sntusgph: entry

[18-DEC-2007 12:07:09:165] sntusgph: exit

[18-DEC-2007 12:07:09:165] ntus2err: entry

[18-DEC-2007 12:07:09:166] ntus2err: sd=9, op=1, resnt[0]=512, resnt[1]=67, resnt[2]=0

[18-DEC-2007 12:07:09:166] ntus2err: exit

[18-DEC-2007 12:07:09:166] sntusgph: entry

[18-DEC-2007 12:07:09:173] sntusgph: exit

[18-DEC-2007 12:07:09:181] ntuscnp: exit

[18-DEC-2007 12:07:09:181] ntusconn: exit

[18-DEC-2007 12:07:09:181] nsopen: transport is open

[18-DEC-2007 12:07:09:181] nsoptions: lcl[0]=0x0, lcl[1]=0x12003, gbl[0]=0x0, gbl[1]=0x2001, cha=0x0

[18-DEC-2007 12:07:09:181] nsoptions: lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001

[18-DEC-2007 12:07:09:181] nsopen: global context check-in (to slot 1) complete

[18-DEC-2007 12:07:09:181] nsopen: lcl[0]=0xf4ffe9ff, lcl[1]=0x12003, gbl[0]=0x78bf, gbl[1]=0x2001, tdu=32767, sdu=8192

[18-DEC-2007 12:07:09:181] nsdo: cid=1, opcode=65, *bl=0, *what=0, uflgs=0x0, cflgs=0x2

[18-DEC-2007 12:07:09:181] nsdo: rank=64, nsctxrnk=0

[18-DEC-2007 12:07:09:181] nsdo: nsctx: state=7, flg=0x4002, mvd=0

[18-DEC-2007 12:07:09:181] nsdo: nsctxrnk=0

[18-DEC-2007 12:07:09:181] nsgllsn: completing partial addr: (ADDRESS=(PROTOCOL=IPC)(KEY=EPQ.WORLD))

[18-DEC-2007 12:07:09:181] ntusad2b: entry

[18-DEC-2007 12:07:09:181] ntusad2b: exit

[18-DEC-2007 12:07:09:181] nsgllsn: Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EPQ.WORLD)))

[18-DEC-2007 12:07:09:181] nsevreg: begin registration process for 1

[18-DEC-2007 12:07:09:181] nsevreg: sgt=0, evn=1, evt[2]=0x0

[18-DEC-2007 12:07:09:181] nsevreg: begin notification process for 1

[18-DEC-2007 12:07:09:181] nsevreg: rdm=0, sgt=0, evt[0]=0x1, [1]=0x1, [2]=0x0, nrg=0

[18-DEC-2007 12:07:09:181] nsevreg: registering for 0x1

[18-DEC-2007 12:07:09:181] nsglhins: entry

[18-DEC-2007 12:07:09:181] nsglhins: exit

[18-DEC-2007 12:07:09:181] nsc2addr: (ADDRESS=(PROTOCOL=ipc)(PARTIAL=yes)(QUEUESIZE=1))

[18-DEC-2007 12:07:09:181] ntusb2adr: entry

[18-DEC-2007 12:07:09:181] nsmal: 1144 bytes at 0x11061b2f0

[18-DEC-2007 12:07:09:181] nsmal: 2160 bytes at 0x11061b790

............................................................

...................................................................

[18-DEC-2007 12:07:09:262] nsnainconn: "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

[18-DEC-2007 12:07:09:262] nsnainconn: "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: 0xc Translations follow:

NA disabled remotely for this connection

NA services unavailable on both processes - negotiation not needed

[18-DEC-2007 12:07:09:266] nsevwait: 4 registered connection(s)

[18-DEC-2007 12:07:09:266] nsevwait: 0 pre-posted event(s)

[18-DEC-2007 12:07:09:266] nsevwait: waiting for transport event (1 thru 5)...

[18-DEC-2007 12:07:09:266] nsevwait: 1 newly-posted event(s)

[18-DEC-2007 12:07:09:266] nsevfnt: cxd: 0x105fed30 stage 0: NT events set:

CONNECTION REQUEST

[18-DEC-2007 12:07:09:266] nsevfnt: cxd: 0x105fed30 stage 0: NS events set:

INCOMING CALL

[18-DEC-2007 12:07:09:266] nsevwait: event is 0x1, on 1

[18-DEC-2007 12:07:09:266] nsevwait: 1 posted event(s)

[18-DEC-2007 12:07:09:266] nsglhe: entry

[18-DEC-2007 12:07:09:266] nsmal: 1144 bytes at 0x110735bf0

[18-DEC-2007 12:07:09:269] nserror: nsres: id=0, op=65, ns=12531, ns2=0; nt[0]=0, nt[1]=0, nt[2]=0; ora[0]=0, ora[1]=0, ora[2]=0

[18-DEC-2007 12:07:09:269] nsmfr: 1144 bytes at 0x110735bf0

[18-DEC-2007 12:07:09:269] nsopen: unable to allocate context area

[18-DEC-2007 12:07:09:269] nsglhc: nsanswer error 12531

[18-DEC-2007 12:07:09:269] nsglhc: Deallocating cxd 0x11072ea70.

[18-DEC-2007 12:07:09:269] nsglhe: exit

[18-DEC-2007 12:07:09:269] nsevwait: 4 registered connection(s)

[18-DEC-2007 12:07:09:269] nsevwait: 0 pre-posted event(s)

[18-DEC-2007 12:07:09:269] nsevwait: waiting for transport event (1 thru 5)...

[18-DEC-2007 12:07:09:269] nsevwait: 1 newly-posted event(s)

[18-DEC-2007 12:07:09:269] nsevfnt: cxd: 0x105fed30 stage 0: NT events set:

CONNECTION REQUEST

[18-DEC-2007 12:07:09:269] nsevfnt: cxd: 0x105fed30 stage 0: NS events set:

INCOMING CALL

...................

................................

continues........

Does it give any clue to the problem ?

Regards,

Jimmy.