PROCESS HANG AT 'DB FILE SEQUENTIAL READ' WHEN USING DNFS and seeing several failed DNFS reconnections

(Doc ID 1530378.1)

Last updated on JULY 05, 2017

Applies to:

Oracle Database - Enterprise Edition - Version 11.1.0.7 and later
Information in this document applies to any platform.

Symptoms

Found intermittent hang with process waiting for 'db file sequential read' when using DNFS.

PROCESS 65:
 ----------------------------------------
 SO: 0xc98a42a18, type: 2, owner: (nil), flag: INIT/-/-/0x00 if: 0x3 c: 0x3
  proc=0xc98a42a18, name=process, file=ksu.h LINE:10706, pg=0
 (process) Oracle pid:65, ser:1, calls cur/top: 0xc303c5238/0xc303c5238
           flags : (0x0) -
           flags2: (0x0),  flags3: (0x0)
           int error: 0, call error: 0, sess error: 0, txn error 0
 ksudlp FALSE at location: 0
 (post info) last post received: 129 0 2
             last post received-location: ksl2.h LINE:1794 ID:kslpsr
             last process to post me: c90a425b8 1 6
             last post sent: 0 0 25
             last post sent-location: ksa2.h LINE:282 ID:ksasnd
             last process posted by me: c90a425b8 1 6
   (latch info) wait_event=0 bits=0
   Process Group: DEFAULT, pseudo proc: 0xcd8d05ae0
   O/S info: user: oracle, term: UNKNOWN, ospid: 29257
   OSD pid info: Unix process pid: 29257, image:
  oracle@<server_name>
   Short stack dump:
<-ksedsts()+315<-ksdxfstk()+32<-ksdxcb()+1764<-sspuser()+112<-__restore_rt()<-__poll()+47<-skgnfs_poll_channels()+161<-kgnfswat()+1655<-kgnfs_wat()+11<-kgodm_wait_ios()+629<-kgodm_io()+260<-odm_io()+28<-ksfd_odmio()+534<-ksfd_odmsio()+1345<-ksfd_io()+4218<-ksfdread1()+212<-kcfrbd()+556<-kcbzib()+3007<-kcbgtcr()+9714<-ktrgtc2()+599<-qeilbk1()+6728<-qeilbk0()+11<-qerixFetchByLogicalRowid()+1585<-qergiFetch()+300<-opifch2()+3570<-kpoal8()+3597<-opiodr()+1220<-ttcpip()+1217<-opitsk()+1449<-opiino()+1026<-opiodr()+1220<-opidrv()+580<-sou2o()+90<-opimai_real()+145<-ssthrdmain()+177<-main()+215<-__libc_start_main()+244<-_start()+41
......
   ----------------------------------------
   SO: 0xcd9543c10, type: 4, owner: 0xc98a42a18, flag: INIT/-/-/0x00 if: 0x3c: 0x3
    proc=0xc98a42a18, name=session, file=ksu.h LINE:10719, pg=0
   (session) sid: 5422 ser: 13 trans: (nil), creator: 0xc98a42a18
             flags: (0x100045) USR/- flags_idl: (0x1) BSY/-/-/-/-/-
             flags2: (0x40009) -/-/INC
             DID: , short-term DID:
             txn branch: (nil)
             oct: 3, prv: 0, sql: 0xccfbf40d0, psql: 0xccfc78860, user:72/EFEAPP
   ksuxds FALSE at location: 0
   service name: SYS$USERS
   client details:
     O/S info: user: jboss, term: unknown, ospid: 1234
       machine: <server_name> program: JDBC Thin Client
     application name: JDBC Thin Client, hash value=2546894660
   Current Wait Stack:
    0: waiting for 'db file sequential read'
       file#=148, block#=25ad, blocks=1
       wait_id=69047 seq_num=3513 snap_id=1
       wait times: snap=91 min 14 sec, exc=91 min 14 sec, total=91 min 14 sec
       wait times: max=infinite
       wait counts: calls=0 os=0
       in_wait=1 iflags=0x5a0
   There is at least one session blocked by this session.
   Dumping one waiter:
     inst: 1, sid: 5380, ser: 8
     wait event: 'read by other session'
       p1: 'file#'=0x148
       p2: 'block#'=0x25ad
       p3: 'class#'=0x1
     row_wait_obj#: 567271, block#: 9645, row#: 0, file# 328
     min_blocked_time: 0 secs, waiter_cache_ver: 2888
   Wait State:
     auto_close=0 flags=0x22 boundary=(nil)/-1
   Session Wait History:
    0: waited for 'SQL*Net message to client'
       driver id=74637000, #bytes=1, =0
       wait_id=69046 seq_num=3512 snap_id=1
       wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec
       wait times: max=infinite
       wait counts: calls=0 os=0
       occurred after 0.000093 sec of elapsed time
    1: waited for 'SQL*Net message from client'
       driver id=74637000, #bytes=1, =0
       wait_id=69045 seq_num=3511 snap_id=1
       wait times: snap=0.001476 sec, exc=0.001476 sec, total=0.001476 sec
       wait times: max=infinite
       wait counts: calls=0 os=0
       occurred after 0.000041 sec of elapsed time
.....

 

Cause

Sign In with your My Oracle Support account

Don't have a My Oracle Support account? Click to get started

My Oracle Support provides customers with access to over a
Million Knowledge Articles and hundreds of Community platforms