- 最后登录
- 2023-8-16
- 在线时间
- 1686 小时
- 威望
- 2135
- 金钱
- 50532
- 注册时间
- 2011-10-12
- 阅读权限
- 200
- 帖子
- 5207
- 精华
- 39
- 积分
- 2135
- UID
- 2
|
3#
发表于 2012-1-4 19:04:33
ODM Data- /oracle/admin/racdb/udump/racdb2_ora_9402.trc
- Oracle Database 10g Enterprise Edition Release 10.2.0.5.0 - 64bit Production
- With the Partitioning, Real Application Clusters, OLAP, Data Mining
- and Real Application Testing options
- ORACLE_HOME = /oracle/ora10g
- System name: Linux
- Node name: oracle-2
- Release: 2.6.18-194.el5
- Version: #1 SMP Tue Mar 16 21:52:39 EDT 2010
- Machine: x86_64
- Instance name: racdb2
- Redo thread mounted by this instance: 2
- Oracle process number: 56
- Unix process pid: 9402, image: oracle@oracle-2
- *** ACTION NAME:(Command Window - New) 2012-01-04 17:40:15.176
- *** MODULE NAME:(PL/SQL Developer) 2012-01-04 17:40:15.176
- *** SERVICE NAME:(racdb) 2012-01-04 17:40:15.176
- *** SESSION ID:(1053.2913) 2012-01-04 17:40:15.176
- WAIT #1: nam='SQL*Net message to client' ela= 1 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599624196111
- WAIT #1: nam='SQL*Net message from client' ela= 14278 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599624210695
- =====================
- PARSING IN CURSOR #2 len=61 dep=0 uid=63 oct=47 lid=63 tim=1294599624210792 hv=356401299 ad='59bee200'
- begin :id := sys.dbms_transaction.local_transaction_id; end;
- END OF STMT
- PARSE #2:c=0,e=22,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=1294599624210788
- BINDS #2:
- kkscoacd
- Bind#0
- oacdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
- oacflg=01 fl2=1000000 frm=01 csi=852 siz=2000 off=0
- kxsbbbfp=2b87021f5d58 bln=2000 avl=00 flg=05
- WAIT #2: nam='SQL*Net message to client' ela= 1 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599624210996
- EXEC #2:c=0,e=152,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=1294599624211024
- *** 2012-01-04 17:40:27.639
- WAIT #2: nam='SQL*Net message from client' ela= 12155349 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636366428
- =====================
- PARSING IN CURSOR #1 len=21 dep=0 uid=63 oct=3 lid=63 tim=1294599636367721 hv=2564406738 ad='59bef4c8'
- select 'x' from dual
- END OF STMT
- PARSE #1:c=1000,e=1116,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=1294599636367715
- BINDS #1:
- EXEC #1:c=1000,e=71,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=1294599636367864
- WAIT #1: nam='SQL*Net message to client' ela= 1 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636367898
- WAIT #1: nam='SQL*Net message from client' ela= 633 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636368583
- WAIT #1: nam='SQL*Net message to client' ela= 1 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636368633
- FETCH #1:c=0,e=35,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=1294599636368657
- WAIT #1: nam='SQL*Net message from client' ela= 3347 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636372074
- STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='FAST DUAL (cr=0 pr=0 pw=0 time=3 us)'
- =====================
- PARSING IN CURSOR #2 len=61 dep=0 uid=63 oct=47 lid=63 tim=1294599636372247 hv=356401299 ad='59bee200'
- begin :id := sys.dbms_transaction.local_transaction_id; end;
- END OF STMT
- PARSE #2:c=0,e=25,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=1294599636372243
- BINDS #2:
- kkscoacd
- Bind#0
- oacdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
- oacflg=01 fl2=1000000 frm=01 csi=852 siz=2000 off=0
- kxsbbbfp=2b87021f5d58 bln=2000 avl=00 flg=05
- WAIT #2: nam='SQL*Net message to client' ela= 0 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636372460
- EXEC #2:c=0,e=175,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=1294599636372493
- WAIT #2: nam='SQL*Net message from client' ela= 617 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636373160
- =====================
- PARSING IN CURSOR #2 len=37 dep=1 uid=0 oct=3 lid=0 tim=1294599636374899 hv=1398610540 ad='5af5c758'
- select text from view$ where rowid=:1
- END OF STMT
- PARSE #2:c=1000,e=998,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=1294599636374895
- BINDS #2:
- kkscoacd
- Bind#0
- oacdty=11 mxl=16(16) mxlc=00 mal=00 scl=00 pre=00
- oacflg=18 fl2=0001 frm=00 csi=00 siz=16 off=0
- kxsbbbfp=2b87021f6290 bln=16 avl=16 flg=05
- value=000001CC.000C.0001
- EXEC #2:c=2000,e=2021,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=1294599636377019
- FETCH #2:c=0,e=44,p=0,cr=2,cu=0,mis=0,r=1,dep=1,og=4,tim=1294599636377104
- STAT #2 id=1 cnt=1 pid=0 pos=1 obj=63 op='TABLE ACCESS BY USER ROWID VIEW$ (cr=1 pr=0 pw=0 time=28 us)'
- =====================
- PARSING IN CURSOR #1 len=25 dep=0 uid=63 oct=3 lid=63 tim=1294599636388424 hv=3206370776 ad='58566068'
- select * from v$session
- END OF STMT
- PARSE #1:c=15997,e=15178,p=0,cr=2,cu=0,mis=1,r=0,dep=0,og=1,tim=1294599636388420
- BINDS #1:
- EXEC #1:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=1294599636388545
- WAIT #1: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1294599636388635
- WAIT #1: nam='SQL*Net more data to client' ela= 24 driver id=1413697536 #bytes=2001 p3=0 obj#=-1 tim=1294599636388750
- WAIT #1: nam='SQL*Net more data to client' ela= 11 driver id=1413697536 #bytes=2005 p3=0 obj#=-1 tim=1294599636388847
复制代码 wait event "SQL*Net more data to client"
related bug :- Bug 9087501: SELECT FROM V$SESSION AND SOME OTHER DICTIONARY VIEWS HANG FROM A REMOTE CLIENT
- Show Bug Attributes Bug Attributes
- Type B - Defect Fixed in Product Version -
- Severity 2 - Severe Loss of Service Product Version 10.2.0.4
- Status 92 - Closed, Not a Bug Platform 226 - Linux x86-64
- Created 04-Nov-2009 Platform Version RED HAT ENTERPRISE LINUX 5
- Updated 11-Dec-2009 Base Bug -
- Database Version 10.2.0.4
- Affects Platforms Generic
- Product Source Oracle
- Show Related Products Related Products
- Line Oracle Database Products Family Oracle Database
- Area Net Services Product 115 - Oracle Net Services
- Hdr: 9087501 10.2.0.4 NET 10.2.0.4 PRODID-115 PORTID-226
- Abstract: SELECT FROM V$SESSION AND SOME OTHER DICTIONARY VIEWS HANG FROM A REMOTE CLIENT
- *** 11/04/09 02:45 pm ***
- TAR:
- ----
- SR:2-5813605 (Previous 7711612.992)
- PROBLEM:
- --------
- select * from v$session hang from a remote client connected through listener.
- But same SQL runs ok with a local connection.
- systemstate dump shows the session is waiting on client. So there might be
- some problem with sql*net. However if turn on sql*net server trace, the
- problem is gone.
- DIAGNOSTIC ANALYSIS:
- --------------------
- checked sql*net trace and find many entries like:
- nioqrc: exit
- nsmore2recv: entry
- nsmore2recv: testing for MOREDATA from transport
- sntpoltsts: fd 14 need 11 readiness event,wait
- Asked customer to try patch 6734871 but it does not help.
- WORKAROUND:
- -----------
- RELATED BUGS:
- -------------
- REPRODUCIBILITY:
- ----------------
- TEST CASE:
- ----------
- STACK TRACE:
- ------------
- SUPPORTING INFORMATION:
- -----------------------
- 24 HOUR CONTACT INFORMATION FOR P1 BUGS:
- ----------------------------------------
- DIAL-IN INFORMATION:
- --------------------
- IMPACT DATE:
- ------------
- new info: only windows client has this issue. A linux client at customer site
- does not have the issue. For windows client, customer tested 9.2.0.1,
- 10.2.0.1 and 10.2.0.4, all have problem.
- Did you try sqlnet tracing on the windows client? Did problem reproduce?.
- Did you enable sqlnet tracing at both client and server sides?
- Could you please set event 10046 level 12 and reproduce the hang?
- Does the hang happen if array size is changed to one in the sqplus session?
- Please also get an errorstack dump when session hangs.
- Thank you,
- Agustin Amador.
- Thank you for the update.
- sqlnet client trace files shows that connections are using different naming
- methods. On the hang case (client_8164.trc) it used TNSNAMES.
- Could you please simplify the test case by:
- 1) changing NAMES.DIRECTORY_PATH = (TNSNAMES, EZCONNECT) to
- NAMES.DIRECTORY_PATH = (TNSNAMES)
- so that TNSNAMES is always used
- 2) Create a new tnsnames.ora file with just one basic connection string to
- the server.
- Reproduce the problem again and provide the same data:
- a) sqlnet trace client (for both cases hang and no hang)
- b) 10046 level 12 and errorstack level 3 dumps while session is hanging
- Trace file client_6196_1.trc shows that client received the first package
- from the server. Then it stopped waiting for more information from the
- server.
- ...
- [12-NOV-2009 12:03:36:728] nsprecv: 631 bytes from transport
- [12-NOV-2009 12:03:36:728] nsprecv: packet dump
- [12-NOV-2009 12:03:36:728] nsprecv: 07 DB 00 00 06 00 00 00 |........|
- [12-NOV-2009 12:03:36:728] nsprecv: 00 00 10 17 8D C9 C8 00 |........|
- [12-NOV-2009 12:03:36:728] nsprecv: EA F8 1C 90 C5 DA E4 C3 |........|
- ...
- [12-NOV-2009 12:03:36:728] nsprecv: 00 00 22 00 01 80 00 00 |..".....|
- [12-NOV-2009 12:03:36:728] nsprecv: 20 00 00 00 00 00 00 00 |........|
- [12-NOV-2009 12:03:36:728] nsprecv: 00 00 00 00 00 00 00 00 |........|
- [12-NOV-2009 12:03:36:728] nsprecv: 00 00 69 |..i |
- [12-NOV-2009 12:03:36:728] nsprecv: normal exit
- [12-NOV-2009 12:03:36:728] nsrdr: got NSPTDA packet
- [12-NOV-2009 12:03:36:728] nsrdr: NSPTDA flags: 0x0
- [12-NOV-2009 12:03:36:728] nsrdr: normal exit
- [12-NOV-2009 12:03:36:728] nsdo: *what=1, *bl=2001
- [12-NOV-2009 12:03:36:728] nsdo: nsctxrnk=0
- [12-NOV-2009 12:03:36:728] nsdo: normal exit
- [12-NOV-2009 12:03:36:728] nioqrc: exit
- [12-NOV-2009 12:03:36:728] nioqrc: entry
- [12-NOV-2009 12:03:36:728] nsdo: entry
- [12-NOV-2009 12:03:36:728] nsdo: cid=0, opcode=85, *bl=0, *what=0, uflgs=0x0,
- cflgs=0x3
- [12-NOV-2009 12:03:36:728] nsdo: rank=64, nsctxrnk=0
- [12-NOV-2009 12:03:36:728] nsdo: nsctx: state=8, flg=0x400d, mvd=0
- [12-NOV-2009 12:03:36:728] nsdo: gtn=127, gtc=127, ptn=10, ptc=2011
- [12-NOV-2009 12:03:36:728] nsdo: switching to application buffer
- [12-NOV-2009 12:03:36:728] nsrdr: entry
- [12-NOV-2009 12:03:36:728] nsrdr: recving a packet
- [12-NOV-2009 12:03:36:728] nsprecv: entry
- [12-NOV-2009 12:03:36:728] nsprecv: reading from transport...
- [12-NOV-2009 12:03:36:728] nttrd: entry
- ...
- On the other hand, trace file mpint1_ora_25450.trc shows that server is
- waiting for the client.
- Call stack
- ...
- ksdxfdmp <- ksdxcb <- sspuser <- funlockfile <- read_nocancel
- <- snttread <- nttrd <- nsprecv <- nsrdr <- nsdo
- <- nsbrecv <- nioqrc <- opikndf2 <- opitsk <- opiino
- <- opiodr <- opidrv <- sou2o <- opimai_real <- main
- <- libc_start_main
- ...
- ----------------------------------------
- SO: 0xdc169ed8, type: 4, owner: 0xdc0086e0, flag: INIT/-/-/0x00
- (session) sid: 421 trans: (nil), creator: 0xdc0086e0, flag: (41) USR/-
- -/-/-/-/-/-
- DID: 0001-0025-0000070B, short-term DID: 0001-0025-0000070A
- txn branch: (nil)
- oct: 3, prv: 0, sql: 0x7581a1a0, psql: 0x65d983a0, user:
- 5/SYSTEM
- O/S info: user: mng, term: INFRA-31, ospid: 1296:3904, machine:
- FXALL\INFRA-31
- program: sqlplusw.exe
- application name: SQL*Plus, hash value=3669949024
- waiting for 'SQL*Net message from client' blocking sess=0x(nil) seq=135
- wait_time=0 seconds since wait started=6
- driver id=54435000, #bytes=1, =0
- Dumping Session Wait History
- for 'SQL*Net more data to client' count=1 wait_time=5
- driver id=54435000, #bytes=7d0, =0
- for 'SQL*Net more data to client' count=1 wait_time=42
- driver id=54435000, #bytes=7d4, =0
- for 'SQL*Net message to client' count=1 wait_time=3
- driver id=54435000, #bytes=1, =0
- for 'row cache lock' count=1 wait_time=357
- cache id=10, mode=0, request=3
- ----
- It seems client and server are going out of sync during the communication.
- Questions:
- - Is the client running windows vista? If yes please review note:444694.1
- - Does select sid from v$session run without problems (less data)?
- - Please ask customer to set SDU=32768 and see if hang reproduces.
- Thank you for the update.
- Could you please try SDU parameter in the DESCRIPTION part. For example:
- (DESCRIPTION =
- (SDU=32768)
- (ADDRESS_LIST =
- (ADDRESS = (PROTOCOL = TCP)(HOST = numpintdb01)(PORT = 1521))
- )
- (CONNECT_DATA =
- (INSTANCE_NAME = MPINT1)
- )
- )
- test again and provide the client's sqlnet trace.
复制代码 |
|