xfs
[Top] [All Lists]

ADD 800480 - xlog_grant_log_space can wait indefinitely

To: lord@xxxxxxx
Subject: ADD 800480 - xlog_grant_log_space can wait indefinitely
From: pv@xxxxxxxxxxxxx (ananth@xxxxxxxxxxxx)
Date: Tue, 12 Sep 2000 11:37:53 -0700 (PDT)
Cc: tduffy@xxxxxxxxxxxxxxxxxxxx, linux-xfs@xxxxxxxxxxx
Reply-to: sgi.bugs.xfs@xxxxxxxxxxxxxxxxx
Sender: owner-linux-xfs@xxxxxxxxxxx
Webexec: webpvupdate,pvincident
Webpv: sgigate.sgi.com
View Incident: 
http://co-op.engr.sgi.com/BugWorks/code/bwxquery.cgi?search=Search&wlong=1&view_type=Bug&wi=800480

 Status : open                         Priority : 2                         
 Assigned Engineer : lord              Submitter : ananth                   
*Modified User : ananth               *Modified User Domain : engr          
*Description :
We have a semi-production build machine that is
running XFS bits as of 8/23/00. I have seen
things like "rm" getting into the following backtrace:

---------
schedule+0x415
_sv_wait+0xcd
xlog_grant_log_space+0x139
xfs_log_reserve+0x7b
xfs_trans_reserve+0x76

.....


==========================
ADDITIONAL INFORMATION (ADD)
From: ananth@engr (BugWorks)
Date: Sep 12 2000 11:37:52AM
==========================


First, the system did get back to normal state after
the apparent process hangs yesterday. I could compile,
edit, etc. on that FS several times. Eventually
the problem surfaced again. This time it is a "mv"
command, pid 5031. I'm going to leave the system in
kdb so the hung process doesn't go away. Following
is the debug information requested. Tom, can
you please leave the system in kdb until Steve gets
a chance to look into things? BTW, I'm not at the office today.

----------
[0]kdb> btp 5031
    EBP       EIP         Function(args)
0xc9f3dcf4 0xc0119bdd schedule+0x415 (0xf77f0cd0)
                               kernel .text 0xc0100000 0xc01197c8 0xc011a1b0
0xc9f3dd30 0xf88838e5 [xfs]_sv_wait+0xcd (0xf77f0cd0, 0xf7cee914, 0x282, 0x0, 
0x0)
                               xfs .text 0xf8830060 0xf8883818 0xf8883900
0xc9f3dd5c 0xf8867fa5 [xfs]xlog_grant_log_space+0x139 (0xf7cee880, 0xf77f0cd0, 
0xf43ae400, 0x9aa70, 0xf7cee880)
                               xfs .text 0xf8830060 0xf8867e6c 0xf88680d0
0xc9f3dd94 0xf8866587 [xfs]xfs_log_reserve+0x7b (0xf43ae400, 0x4ab38, 0x2, 
0xed2a0994, 0x69)
                               xfs .text 0xf8830060 0xf886650c 0xf8866594
0xc9f3ddc0 0xf887390a [xfs]xfs_trans_reserve+0x76 (0xed2a0960, 0x3f, 0x4ab38, 
0x0, 0x4)
                               xfs .text 0xf8830060 0xf8873894 0xf88739b4
0xc9f3deac 0xf886fb80 [xfs]xfs_rename+0x394 (0xf1e65b94, 0xdc10c5e0, 
0xf14d97c0, 0xd28c4580, 0xc9f3def4)
                               xfs .text 0xf8830060 0xf886f7ec 0xf8870280
0xc9f3df00 0xf88829a2 [xfs]linvfs_rename+0x7a (0xf14d96e0, 0xc53192c0, 
0xf14d96e0, 0xd28c4520)
                               xfs .text 0xf8830060 0xf8882928 0xf8882a30
0xc9f3df24 0xc01428e8 vfs_rename_other+0x270 (0xf14d96e0, 0xc53192c0, 
0xf14d96e0, 0xd28c4520)
                               kernel .text 0xc0100000 0xc0142678 0xc0142934
0xc9f3df44 0xc0142960 vfs_rename+0x2c (0xf14d96e0, 0xc53192c0, 0xf14d96e0, 
0xd28c4520)
                               kernel .text 0xc0100000 0xc0142934 0xc0142974
0xc9f3dfbc 0xc0142b38 sys_rename+0x1c4 (0xbffff394, 0xbffff3d1, 0xbffff0ec, 
0x1, 0xbffff3d1)
                               kernel .text 0xc0100000 0xc0142974 0xc0142bfc
           0xc010a860 system_call+0x34
                               kernel .text 0xc0100000 0xc010a82c 0xc010a864
[0]more> 
[0]kdb> xlog 0xf7cee880
xlog at 0xf7cee880
&flushsm: 0xf7cee880  tic_cnt: 100  tic_tcnt: 102  
freelist: 0xf77f07a8  tail: 0xf77f0230  ICLOG: 0xf44d0000  
&icloglock: 0xf7cee8b4  tail_lsn: 0x5f000002094  last_sync_lsn: 0x5f100001bdf 
mp: 0xf43ae400  xbuf: 0xf70e6e40  roundoff: 444  l_covered_state: need 
flags: log 0x0 <>   dev: 0x811 logBBstart: 7962656 logsize: 4915200 logBBsize: 
9600
curr_cycle: 1521  prev_cycle: 1521  curr_block: 7141  prev_block: 7135
iclog_bak: 0xf7cee904  iclog_size: 0x8000 (32768)  num iclogs: 2
&grant_lock: 0xf7cee914  resHeadQ: 0xf77f0cd0  wrHeadQ: 0x00000000
GResCycle: 1521  GResBytes: 3655748  GWrCycle: 1521  GWrBytes: 3655748
GResBlocks: 7141  GResRemain: 0  GWrBlocks: 7141  GWrRemain: 0
[0]kdb> xicall 0xf44d0000
xlog_in_core/header at 0xf44d0000
magicno: feedbabe  cycle: 1521  version: 1  lsn: 0x0
tail_lsn: 0x5f000002094  len: 3764  prev_block: 7116  num_ops: 0
cycle_data: 0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0 
 0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  
0  0  0  0  0  0  0  0  0  0  0  0  0  0  
--------------------------------------------------
data: 0xf44d0400  &forcesema: 0xf44d0000  next: 0xf4f80000 bp: 0xf70e6da0
log: 0xf7cee880  callb: 0x00000000  callb_tail: 0xf44d0020  roundoff: 332
size: 32256  (OFFSET: 0) trace: 0x00000000  refcnt: 0  bwritecnt: 0  state: 
state 0x1 <ACTIVE > 
=================================================
xlog_in_core/header at 0xf4f80000
magicno: feedbabe  cycle: 1521  version: 1  lsn: 0x0
tail_lsn: 0x5f000002094  len: 2448  prev_block: 7126  num_ops: 0
cycle_data: 0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0 
 0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  
0  0  0  0  0  0  0  0  0  0  0  0  0  0  
--------------------------------------------------
data: 0xf4f80400  &forcesema: 0xf4f80000  next: 0xf44d0000 bp: 0xf70e6d00
log: 0xf7cee880  callb: 0x00000000  callb_tail: 0xf4f80020  roundoff: 112
size: 32256  (OFFSET: 0) trace: 0x00000000  refcnt: 0  bwritecnt: 0  state: 
state 0x1 <ACTIVE > 
=================================================
[0]kdb> pb 0xf70e6da0
page_buf_t at 0xf70e6da0
  pb_flags MAPPED ASYNC SYNC LOCKABLE FORECIO
  pb_target 0xf735a200 pb_hold 1 pb_next 0xf70e6da0 pb_prev 0xf70e6da0
  pb_file_offset 0x0 pb_buffer_length 0x1200 pb_addr 0xf44d0200
  pb_bn 0x799bf6 pb_count_desired 0x1200
  pb_io_remaining 0   pb_error 0 pba_kiovec[0] 0xf3012d40 pba_kiocnt 1
  pb_iodonesema (0,0) pb_sema (0,0) pincount (0) last holder 0xf7538000
pb_fspriv 0xf44d0000 pb_fspriv2 0x00000001
[0]kdb> pb 0xf70e6d00
page_buf_t at 0xf70e6d00
  pb_flags MAPPED ASYNC SYNC LOCKABLE FORECIO
  pb_target 0xf735a200 pb_hold 1 pb_next 0xf70e6d00 pb_prev 0xf70e6d00
  pb_file_offset 0x0 pb_buffer_length 0xc00 pb_addr 0xf4f80200
  pb_bn 0x799bff pb_count_desired 0xc00
  pb_io_remaining 0   pb_error 0 pba_kiovec[0] 0xf3012dc0 pba_kiocnt 1
  pb_iodonesema (0,0) pb_sema (0,0) pincount (0) last holder 0xf7538000
pb_fspriv 0xf4f80000 pb_fspriv2 0x00000001
[0]kdb> xmount 0xf43ae400
xfs_mount at 0xf43ae400
vfsp 0xf7715a60 tid 0x0 ail_lock 0xf43ae414 &ail 0xf43ae418
ail_gen 0x40270 &sb 0xf43ae424
sb_lock 0xf43ae4ec sb_bp 0xf70e6ee0 dev 0x811 logdev 0x811 rtdev 0x0
bsize 8 agfrotor 4 agirotor 1 ihash 0xf70d8000 ihsize 332
inodes 0xd0bcfc88 ilock 0xf43ae514 ireclaims 0x2c5f
readio_log 0x10 readio_blocks 0x10 writeio_log 0x10 writeio_blocks 0x10
logbufs -1 logbsize -1 LOG 0xf7cee880
rsumlevels 0x0 rsumsize 0x0 rbmip 0x00000000 rsumip 0x00000000
rootip 0xf730cc88
dircook_elog 0 blkbit_log 15 blkbb_log 3 agno_log 4
agino_log 22 nreadaheads 4 inode cluster size 8192
blockmask 0xfff blockwsize 0x400 blockwmask 0x3ff
alloc_mxr[lf,nd] 510 340 alloc_mnr[lf,nd] 255 170
bmap_dmxr[lfnr,ndnr] 254 254 bmap_dmnr[lfnr,ndnr] 127 127
inobt_mxr[lf,nd] 255 510 inobt_mnr[lf,nd] 127 255
ag_maxlevels 3 bm_maxlevels[d,a] 5 3 in_maxlevels 3
perag 0xf7cee980 &peraglock 0xf43ae5d8 &growlock 0xf43ae600
flags 0x0 <> ialloc_inos 64 ialloc_blks 4 litino 156
attroffset 120 da_node_ents 510 maxicount 8957888 inoalign_mask 1
resblks 0 resblks_avail 0
 inoadd 0
quotainfo NULL
[0]more> 
quotaflags 0x0 <> 
dalign 0 swidth 0 sinoalign 0 attr_magicpct 1515 dir_magicpct 1515
mk_sharedro 0 dirversion 2 dirblkfsbs 1 &dirops 0xf43ae6cc
dirblksize 4096 dirdatablk 0x0 dirleafblk 0x800000 dirfreeblk 0x1000000
chsize 37 chash 0xf5bcec00
mountpoint "sd(8,17)"
[0]kdb> xail 0xf43ae400
AIL for mp 0xf43ae400, oldest first
[0] type inode flags: 0x1 <in ail >   lsn [5f0:2094]
   inode 0xf730cc88 logged 0 flags: 0x0 <> format: 0x1 <core > lastfield: 0x0 <>

<Prev in Thread] Current Thread [Next in Thread>