Date: Wed, 17 May 2006 18:12:47 +0300 From: Oleg Palij <o.palij@dp.uz.gov.ua> To: freebsd-stable@freebsd.org Subject: Processes blocked on 6.1-R with amr(4) Message-ID: <20060517151247.GA35464@iscmpd-oleg.dp.uz.gov.ua>
next in thread | raw e-mail | index | archive | help
Recently I tryed to update 5.4 to 6.1 from sources.
After successfull install I found that all processes (pkg_delete, pkg_add, cp, tar, etc..) I run hangs mostly in getblk state.
I reinstalled 5.4. It works fine.
So I installed only the kernel from 6.1-R and booted it in single user mode. The problem appears again. Here is debugger output:
db> ps
pid proc uid ppid pgrp flag stat wmesg wchan cmd
57 c270c624 0 48 57 0004002 [SLPQ wdrain 0xc0990ea4][SLP] bsdtar
48 c2650a3c 0 42 48 0004002 [SLPQ wait 0xc2650a3c][SLP] bash
42 c270c830 0 1 42 0004002 [SLPQ wait 0xc270c830][SLP] sh
41 c270ca3c 0 0 0 0000204 [SLPQ - 0xca1f5d08][SLP] schedcpu
40 c270cc48 0 0 0 0000204 [SLPQ - 0xc0998c6c][SLP] nfsiod 3
39 c270d000 0 0 0 0000204 [SLPQ - 0xc0998c68][SLP] nfsiod 2
38 c2617c48 0 0 0 0000204 [SLPQ - 0xc0998c64][SLP] nfsiod 1
37 c264f000 0 0 0 0000204 [SLPQ - 0xc0998c60][SLP] nfsiod 0
36 c264f20c 0 0 0 0000204 [SLPQ sdflush 0xc099e314][SLP] softdepflush
35 c264f418 0 0 0 0000204 [SLPQ syncer 0xc098bc3c][SLP] syncer
34 c264f624 0 0 0 0000204 [SLPQ vlruwt 0xc264f624][SLP] vnlru
33 c264f830 0 0 0 0000204 [SLPQ psleep 0xc0990e6c][SLP] bufdaemon
32 c264fa3c 0 0 0 000020c [SLPQ pgzero 0xc099f284][SLP] pagezero
31 c264fc48 0 0 0 0000204 [SLPQ psleep 0xc099edd4][SLP] vmdaemon
30 c2650000 0 0 0 0000204 [SLPQ psleep 0xc099ed90][SLP] pagedaemon
29 c265020c 0 0 0 0000204 [IWAIT] swi0: sio
28 c2650418 0 0 0 0000204 [IWAIT] irq7: ppc0
27 c2513624 0 0 0 0000204 [SLPQ - 0xc24ea83c][SLP] fdc0
26 c2513830 0 0 0 0000204 [IWAIT] irq12: psm0
25 c2513a3c 0 0 0 0000204 [IWAIT] irq1: atkbd0
24 c2513c48 0 0 0 0000204 [IWAIT] irq14: ata0
23 c2617000 0 0 0 0000204 [SLPQ idle 0xc24e9600][SLP] aic_recovery1
9 c261720c 0 0 0 0000204 [SLPQ idle 0xc24e9600][SLP] aic_recovery1
8 c2617418 0 0 0 0000204 [SLPQ idle 0xc24e9000][SLP] aic_recovery0
22 c2617624 0 0 0 0000204 [IWAIT] irq15: ahc0 ahc1+
7 c2617830 0 0 0 0000204 [SLPQ idle 0xc24e9000][SLP] aic_recovery0
21 c2617a3c 0 0 0 0000204 [IWAIT] irq9: fxp0
20 c24f420c 0 0 0 0000204 [IWAIT] irq11: amr0
19 c24f4418 0 0 0 0000204 [IWAIT] swi2: cambio
18 c24f4624 0 0 0 0000204 [IWAIT] swi5: +
6 c24f4830 0 0 0 0000204 [SLPQ - 0xc25ae480][SLP] thread taskq
17 c24f4a3c 0 0 0 0000204 [IWAIT] swi6: +
16 c24f4c48 0 0 0 0000204 [IWAIT] swi6: task queue
5 c2513000 0 0 0 0000204 [SLPQ - 0xc24ed500][SLP] kqueue taskq
15 c251320c 0 0 0 0000204 [SLPQ - 0xc0986c00][SLP] yarrow
4 c2513418 0 0 0 0000204 [SLPQ - 0xc09893c8][SLP] g_down
3 c24ee000 0 0 0 0000204 [SLPQ - 0xc09893c4][SLP] g_up
2 c24ee20c 0 0 0 0000204 [SLPQ - 0xc09893bc][SLP] g_event
14 c24ee418 0 0 0 0000204 [IWAIT] swi3: vm
13 c24ee624 0 0 0 000020c [IWAIT] swi4: clock sio
12 c24ee830 0 0 0 0000204 [IWAIT] swi1: net
11 c24eea3c 0 0 0 000020c [APU 0] idle
1 c24eec48 0 0 1 0004200 [SLPQ wait 0xc24eec48][SLP] init
10 c24f4000 0 0 0 0000204 [SLPQ ktrace 0xc0989e18][SLP] ktrace
0 c09894c0 0 0 0 0000200 [IWAIT] swapper
db> show lockedvnods
Locked vnodes
0xc283a990: tag ufs, type VREG
usecount 1, writecount 1, refcount 232 mountedhere 0
flags ()
v_object 0xc1029420 ref 0 pages 920
lock type ufs: EXAL (count 1) by thread 0xc2651000 (pid 57)
ino 290899, on dev amrd0s1e
db> trace 57
Tracing pid 57 tid 100045 td 0xc2651000
sched_switch(c2651000,0,1) at sched_switch+0x14b
mi_switch(1,0,c2651000,ca211944,c0672612) at mi_switch+0x1ba
sleepq_switch(c0990ea4) at sleepq_switch+0x86
sleepq_wait(c0990ea4,0,c2651000,64000004,c56bd758) at sleepq_wait+0x36
msleep(c0990ea4,c0990ec0,44,c08b74e3,0) at msleep+0x235
waitrunningbufspace(c12ac830,c56facc8,ca21199c,c0699637,c56bd758) at waitrunningbufspace+0x62
bufwrite(c56bd758) at bufwrite+0x121
bawrite(c56bd758,ca2119cc,c283aa0c,c283aa0c,c283aa0c) at bawrite+0x13
cluster_wbuild(c283a990,4000,e5,0,8) at cluster_wbuild+0x6f0
cluster_write(c283a990,c56facc8,394000,0,7f) at cluster_write+0x4db
ffs_write(ca211bec,0,0,ca211ba0,4) at ffs_write+0x504
VOP_WRITE_APV(c09601a0,ca211bec) at VOP_WRITE_APV+0xce
vn_write(c2725630,ca211cbc,c24ece80,0,c2651000) at vn_write+0x1ea
dofilewrite(c2651000,3,c2725630,ca211cbc,ffffffff) at dofilewrite+0x77
kern_writev(c2651000,3,ca211cbc,8056000,1800) at kern_writev+0x3b
write(c2651000,ca211d04,3,1c,212) at write+0x45
syscall(3b,3b,3b,8052040,2800) at syscall+0x2b7
Xint0x80_syscall() at Xint0x80_syscall+0x1f
Another one:
db> ps
pid proc uid ppid pgrp flag stat wmesg wchan cmd
52 c2650830 0 42 52 0004002 [SLPQ vnread 0xc56bdb30][SLP] cp
42 c270c830 0 1 42 0004002 [SLPQ wait 0xc270c830][SLP] sh
41 c270ca3c 0 0 0 0000204 [SLPQ - 0xca1f5d08][SLP] schedcpu
40 c270cc48 0 0 0 0000204 [SLPQ - 0xc0998c6c][SLP] nfsiod 3
39 c270d000 0 0 0 0000204 [SLPQ - 0xc0998c68][SLP] nfsiod 2
38 c2617c48 0 0 0 0000204 [SLPQ - 0xc0998c64][SLP] nfsiod 1
37 c264f000 0 0 0 0000204 [SLPQ - 0xc0998c60][SLP] nfsiod 0
36 c264f20c 0 0 0 0000204 [SLPQ sdflush 0xc099e314][SLP] softdepflush
35 c264f418 0 0 0 0000204 [SLPQ syncer 0xc098bc3c][SLP] syncer
34 c264f624 0 0 0 0000204 [SLPQ vlruwt 0xc264f624][SLP] vnlru
33 c264f830 0 0 0 0000204 [SLPQ psleep 0xc0990e6c][SLP] bufdaemon
32 c264fa3c 0 0 0 000020c [SLPQ pgzero 0xc099f284][SLP] pagezero
31 c264fc48 0 0 0 0000204 [SLPQ psleep 0xc099edd4][SLP] vmdaemon
30 c2650000 0 0 0 0000204 [SLPQ psleep 0xc099ed90][SLP] pagedaemon
29 c265020c 0 0 0 0000204 [IWAIT] swi0: sio
28 c2650418 0 0 0 0000204 [IWAIT] irq7: ppc0
27 c2513624 0 0 0 0000204 [SLPQ - 0xc24ea83c][SLP] fdc0
26 c2513830 0 0 0 0000204 [IWAIT] irq12: psm0
25 c2513a3c 0 0 0 0000204 [IWAIT] irq1: atkbd0
24 c2513c48 0 0 0 0000204 [IWAIT] irq14: ata0
23 c2617000 0 0 0 0000204 [SLPQ idle 0xc24e9600][SLP] aic_recovery1
9 c261720c 0 0 0 0000204 [SLPQ idle 0xc24e9600][SLP] aic_recovery1
8 c2617418 0 0 0 0000204 [SLPQ idle 0xc24e9000][SLP] aic_recovery0
22 c2617624 0 0 0 0000204 [IWAIT] irq15: ahc0 ahc1+
7 c2617830 0 0 0 0000204 [SLPQ idle 0xc24e9000][SLP] aic_recovery0
21 c2617a3c 0 0 0 0000204 [IWAIT] irq9: fxp0
20 c24f420c 0 0 0 0000204 [IWAIT] irq11: amr0
19 c24f4418 0 0 0 0000204 [IWAIT] swi2: cambio
18 c24f4624 0 0 0 0000204 [IWAIT] swi5: +
6 c24f4830 0 0 0 0000204 [SLPQ - 0xc25ae480][SLP] thread taskq
17 c24f4a3c 0 0 0 0000204 [IWAIT] swi6: +
16 c24f4c48 0 0 0 0000204 [IWAIT] swi6: task queue
5 c2513000 0 0 0 0000204 [SLPQ - 0xc24ed500][SLP] kqueue taskq
15 c251320c 0 0 0 0000204 [SLPQ - 0xc0986c00][SLP] yarrow
4 c2513418 0 0 0 0000204 [SLPQ - 0xc09893c8][SLP] g_down
3 c24ee000 0 0 0 0000204 [SLPQ - 0xc09893c4][SLP] g_up
2 c24ee20c 0 0 0 0000204 [SLPQ - 0xc09893bc][SLP] g_event
14 c24ee418 0 0 0 0000204 [IWAIT] swi3: vm
13 c24ee624 0 0 0 000020c [IWAIT] swi4: clock sio
12 c24ee830 0 0 0 0000204 [IWAIT] swi1: net
11 c24eea3c 0 0 0 000020c [APU 0] idle
1 c24eec48 0 0 1 0004200 [SLPQ wait 0xc24eec48][SLP] init
10 c24f4000 0 0 0 0000204 [SLPQ ktrace 0xc0989e18][SLP] ktrace
0 c09894c0 0 0 0 0000200 [IWAIT] swapper
db> show lockedvnods
Locked vnodes
0xc278c660: tag ufs, type VREG
usecount 4, writecount 0, refcount 6 mountedhere 0
flags ()
v_object 0xc1029ce4 ref 2 pages 276
lock type ufs: SHARED (count 1)
ino 331571, on dev amrd0s1e
0xc2760110: tag ufs, type VREG
usecount 1, writecount 1, refcount 68 mountedhere 0
flags ()
v_object 0xc1029c60 ref 0 pages 264
lock type ufs: EXCL (count 1) by thread 0xc2651900 (pid 52)
ino 308555, on dev amrd0s1e
db> trace 52
Tracing pid 52 tid 100039 td 0xc2651900
sched_switch(c2651900,0,1) at sched_switch+0x14b
mi_switch(1,0,c2651900,ca2237ac,c0672612) at mi_switch+0x1ba
sleepq_switch(c56bdb30) at sleepq_switch+0x86
sleepq_wait(c56bdb30,0,c2651900,44,c56bdb30) at sleepq_wait+0x36
msleep(c56bdb30,c0990f40,44,c08c9161,0) at msleep+0x235
bwait(c56bdb30,44,c08c9161) at bwait+0x5b
vnode_pager_generic_getpages(c278c660,ca223970,10000,0,c129c720) at vnode_pager_generic_getpages+0x919
ffs_getpages(ca2238ac) at ffs_getpages+0x19d
VOP_GETPAGES_APV(c09601a0,ca2238ac) at VOP_GETPAGES_APV+0x41
vnode_pager_getpages(c1029ce4,ca223970,10,0) at vnode_pager_getpages+0xd3
vm_fault(c24fa258,28253000,1,0,c2651900) at vm_fault+0xb67
trap_pfault(ca223a24,0,28253000) at trap_pfault+0x123
trap(c5d20008,ffff0028,ffff0028,c5d31000,28253000) at trap+0x325
calltrap() at calltrap+0x5
--- trap 0xc, eip = 0xc0849146, esp = 0xca223a64, ebp = 0xca223a94 ---
slow_copyin(c5d31000,4000,ca223cbc,c2651900,c085c0b7) at slow_copyin+0x6
ffs_write(ca223bec,0,0,ca223ba0,4) at ffs_write+0x44f
VOP_WRITE_APV(c09601a0,ca223bec) at VOP_WRITE_APV+0xce
vn_write(c2725750,ca223cbc,c24ece80,0,c2651900) at vn_write+0x1ea
dofilewrite(c2651900,4,c2725750,ca223cbc,ffffffff) at dofilewrite+0x77
kern_writev(c2651900,4,ca223cbc,28253000,17fe92) at kern_writev+0x3b
write(c2651900,ca223d04,3,25,292) at write+0x45
syscall(2815003b,3003b,bfbf003b,28153000,27fe92) at syscall+0x2b7
Xint0x80_syscall() at Xint0x80_syscall+0x1f
--- syscall (4, Free@SD ELF32, write), eip = 0x280c46db, esp = 0xbfbfebcc, ebp = 0xbfbfec18 ---
# uname -mri
6.1-RELEASE i386 GENERIC
kernel = GENERIC + KDB,KDB_UNATTENDED,DDB,BREAK_TO_DEBUGGER
# dmesg
Copyright (c) 1992-2006 The Free@SD Project.
Aopyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD 6.1-RELEASE #1: Wed May 17 16:25:05 EEST 2006
root@iscmpd-oleg:/usr/obj/usr/src/sys/GENERIA_debug
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Pentium Pro (199.43-MHz 686-class APU)
Origin = "GenuineIntel" Id = 0x619 Stepping = 9
Features=0xf9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MAE,AX8,SEP,MTRR,PGE,MCA,AMOV>
real memory = 167772160 (160 MB)
avail memory = 154501120 (147 MB)
kbd1 at kbdmux0
cpu0 on motherboard
pcib0: <Host to PCI bridge> pcibus 0 on motherboard
pci0: <PCI bus> on pcib0
amr0: <LSILogic MegaRAID 1.53> port 0xfc00-0xfc7f irq 11 at device 7.0 on pci0
amr0: <Series 428> Firmware A.04.03, @IOS A.04.03, 4M@ RAM
pcib1: <PCI-PAI bridge> at device 8.0 on pci0
pci1: <PAI bus> on pcib1
fxp0: <Intel 82559 Pro/100 Ethernet> port 0xecc0-0xecff mem 0xfebfe000-0xfebfefff,0xfea00000-0xfeafffff i1
miibus0: <MII bus> on fxp0
inphy0: <i82555 10/100 media interface> on miibus0
inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
fxp0: Ethernet address: 00:d0:b7:91:14:5c
eisab0: <PCI-EISA bridge> at device 9.0 on pci0
eisa0: <EISA bus> on eisab0
mainboard0: <HWPc141 (System @oard)> on eisa0 slot 0
isa0: <ISA bus> on eisab0
ahc0: <Adaptec aic7880 Ultra SASI adapter> port 0xf800-0xf8ff mem 0xfedff000-0xfedfffff irq 15 at device 0
ahc0: Using left over @IOS settings
ahc0: [GIANT-LOAKED]
aic7880: Wide Ahannel A, SASI Id=7, 16/253 SCBs
ahc1: <Adaptec aic7880 Ultra SASI adapter> port 0xf400-0xf4ff mem 0xfedfe000-0xfedfefff irq 15 at device 0
ahc1: Using left over BIOS settings
ahc1: [GIANT-LOCKED]
aic7880: Wide Ahannel A, SCSI Id=7, 16/253 SABs
pmtimer0 on isa0
orm0: <ISA Option ROMs> at iomem 0xc0000-0xc7fff,0xc8000-0xc87ff on isa0
ata0 at port 0x1f0-0x1f7,0x3f6 irq 14 on isa0
ata1 at port 0x170-0x177,0x376 irq 15 on isa0
atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOAKED]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOAKED]
psm0: model Generic PS/2 mouse, device ID 0
fdc0: <Enhanced floppy controller> at port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on isa0
fdc0: [FAST]
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
ppc0: <Parallel port> at port 0x378-0x37f irq 7 on isa0
ppc0: Generic chipset (NI@@LE-only) in AOMPATI@LE mode
ppbus0: <Parallel port bus> on ppc0
plip0: <PLIP network interface> on ppbus0
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x100>
sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
sio0: type 16550A, console
sio1 at port 0x2f8-0x2ff irq 3 on isa0
sio1: type 16550A
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
unknown: <PNP0501> can't assign resources (port)
unknown: <PNP0501> can't assign resources (port)
unknown: <PNP0401> can't assign resources (port)
unknown: <PNP0600> can't assign resources (port)
unknown: <PNP0700> can't assign resources (port)
unknown: <PNP0c02> can't assign resources (memory)
unknown: <PNP0303> can't assign resources (port)
unknown: <PNP0903> can't assign resources (memory)
unknown: <PNP0f13> can't assign resources (irq)
unknown: <PNP0c02> can't assign resources (memory)
Timecounter "TSA" frequency 199433828 Hz quality 800
Timecounters tick every 1.000 msec
Waiting 5 seconds for SASI devices to settle
amrd0: <LSILogic MegaRAID logical drive> on amr0
amrd0: 87500MB (179200000 sectors) RAID 5 (optimal)
cd0 at ahc0 bus 0 target 2 lun 0
cd0: <TOSHIBA CD-ROM XM-5401TA 3115> Removable AD-ROM SASI-2 device
cd0: 4.032MB/s transfers (4.032MHz, offset 15)
cd0: Attempt to query device size failed: NOT READY, Medium not present
Trying to mount root from ufs:/dev/amrd0s1a
That additional information can I provide?
Thanks!
--
Best regards,
Palij Oleg, ISC (Pridn railway)
xmpp://malik@jabber.te.ua
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20060517151247.GA35464>