Date: Sat, 5 Mar 2011 17:48:44 +0100 From: Christian Gusenbauer <c47g@gmx.at> To: freebsd-current@freebsd.org, fs@freebsd.org Cc: Kostik Belousov <kostikbel@gmail.com> Subject: Re: FS errors during unmount after dump'ing Message-ID: <201103051748.44554.c47g@gmx.at> In-Reply-To: <201103051736.04605.c47g@gmx.at> References: <201103050851.38930.c47g@gmx.at> <20110305093640.GL78089@deviant.kiev.zoral.com.ua> <201103051736.04605.c47g@gmx.at>
next in thread | previous in thread | raw e-mail | index | archive | help
OK, the attachment got lost, so here's the dmesg output:
Copyright (c) 1992-2011 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 9.0-CURRENT #48 r219265: Fri Mar 4 18:51:23 CET 2011
root@bones.gusis.at:/usr/obj/jail/mytestjail/usr/src/sys/BONES i386
CPU: Intel(R) Core(TM)2 CPU 6600 @ 2.40GHz (2401.97-MHz 686-class
CPU)
Origin = "GenuineIntel" Id = 0x6f6 Family = 6 Model = f Stepping = 6
Features=0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Features2=0xe3bd<SSE3,DTES64,MON,DS_CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM>
AMD Features=0x20100000<NX,LM>
AMD Features2=0x1<LAHF>
TSC: P-state invariant
real memory = 2147483648 (2048 MB)
avail memory = 2086711296 (1990 MB)
Event timer "LAPIC" quality 400
ACPI APIC Table: <MSTEST OEMAPIC >
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 1 package(s) x 2 core(s)
cpu0 (BSP): APIC ID: 0
cpu1 (AP): APIC ID: 1
ioapic0 <Version 2.0> irqs 0-23 on motherboard
acpi0: <KOZIRO FRONTIER> on motherboard
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, 7ff00000 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0
cpu0: <ACPI CPU> on acpi0
ACPI Warning: Incorrect checksum in table [OEMB] - 0xD6, should be 0xC9
(20110211/tbutils-282)
cpu1: <ACPI CPU> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
pcib1: <ACPI PCI-PCI bridge> irq 16 at device 1.0 on pci0
pci1: <ACPI PCI bus> on pcib1
vgapci0: <VGA-compatible display> port 0xac00-0xac7f mem
0xfd000000-0xfdffffff,0xc0000000-0xcfffffff,0xfc000000-0xfcffffff irq 16 at
device 0.0 on pci1
nvidia0: <GeForce 7600 GT> on vgapci0
vgapci0: child nvidia0 requested pci_enable_io
vgapci0: child nvidia0 requested pci_enable_io
uhci0: <Intel 82801H (ICH8) USB controller USB-D> port 0xe000-0xe01f irq 16 at
device 26.0 on pci0
usbus0: <Intel 82801H (ICH8) USB controller USB-D> on uhci0
uhci1: <Intel 82801H (ICH8) USB controller USB-E> port 0xe080-0xe09f irq 17 at
device 26.1 on pci0
usbus1: <Intel 82801H (ICH8) USB controller USB-E> on uhci1
ehci0: <Intel 82801H (ICH8) USB 2.0 controller USB2-B> mem
0xfebff400-0xfebff7ff irq 18 at device 26.7 on pci0
usbus2: EHCI version 1.0
usbus2: <Intel 82801H (ICH8) USB 2.0 controller USB2-B> on ehci0
hdac0: <Intel 82801H High Definition Audio Controller> mem
0xfebf8000-0xfebfbfff irq 22 at device 27.0 on pci0
hdac0: HDA Driver Revision: 20100226_0142
pcib2: <ACPI PCI-PCI bridge> irq 16 at device 28.0 on pci0
pci4: <ACPI PCI bus> on pcib2
pcib3: <ACPI PCI-PCI bridge> irq 19 at device 28.3 on pci0
pci3: <ACPI PCI bus> on pcib3
pci3: <network, ethernet> at device 0.0 (no driver attached)
pcib4: <ACPI PCI-PCI bridge> irq 16 at device 28.4 on pci0
pci2: <ACPI PCI bus> on pcib4
ahci0: <JMicron JMB363 AHCI SATA controller> mem 0xfe8fe000-0xfe8fffff irq 16
at device 0.0 on pci2
ahci0: AHCI v1.00 with 2 3Gbps ports, Port Multiplier supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
atapci0: <JMicron JMB363 UDMA133 controller> port
0xbc00-0xbc07,0xb880-0xb883,0xb800-0xb807,0xb480-0xb483,0xb400-0xb40f at
device 0.1 on pci2
ata2: <ATA channel 0> on atapci0
uhci2: <Intel 82801H (ICH8) USB controller USB-A> port 0xd800-0xd81f irq 23 at
device 29.0 on pci0
usbus3: <Intel 82801H (ICH8) USB controller USB-A> on uhci2
uhci3: <Intel 82801H (ICH8) USB controller USB-B> port 0xd880-0xd89f irq 19 at
device 29.1 on pci0
usbus4: <Intel 82801H (ICH8) USB controller USB-B> on uhci3
uhci4: <Intel 82801H (ICH8) USB controller USB-C> port 0xdc00-0xdc1f irq 18 at
device 29.2 on pci0
usbus5: <Intel 82801H (ICH8) USB controller USB-C> on uhci4
ehci1: <Intel 82801H (ICH8) USB 2.0 controller USB2-A> mem
0xfebff000-0xfebff3ff irq 23 at device 29.7 on pci0
usbus6: EHCI version 1.0
usbus6: <Intel 82801H (ICH8) USB 2.0 controller USB2-A> on ehci1
pcib5: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci5: <ACPI PCI bus> on pcib5
xl0: <3Com 3c905-TX Fast Etherlink XL> port 0xcc00-0xcc3f irq 23 at device 2.0
on pci5
miibus0: <MII bus> on xl0
nsphy0: <DP83840 10/100 media interface> PHY 24 on miibus0
nsphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
xl0: Ethernet address: 00:60:97:11:d6:21
pci5: <serial bus, FireWire> at device 3.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
ahci1: <Intel ICH8 AHCI SATA controller> port
0xec00-0xec07,0xe880-0xe883,0xe800-0xe807,0xe480-0xe483,0xe400-0xe41f mem
0xfebff800-0xfebfffff irq 19 at device 31.2 on pci0
ahci1: AHCI v1.10 with 6 3Gbps ports, Port Multiplier supported
ahcich2: <AHCI channel> at channel 0 on ahci1
ahcich3: <AHCI channel> at channel 1 on ahci1
ahcich4: <AHCI channel> at channel 2 on ahci1
ahcich5: <AHCI channel> at channel 3 on ahci1
ahcich6: <AHCI channel> at channel 4 on ahci1
ahcich7: <AHCI channel> at channel 5 on ahci1
ichsmb0: <Intel 82801H (ICH8) SMBus controller> port 0x400-0x41f irq 18 at
device 31.3 on pci0
smbus0: <System Management Bus> on ichsmb0
smb0: <SMBus generic I/O> on smbus0
acpi_button0: <Power Button> on acpi0
attimer0: <AT timer> port 0x40-0x43 irq 0 on acpi0
Timecounter "i8254" frequency 1193182 Hz quality 0
Event timer "i8254" frequency 1193182 Hz quality 100
atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
Event timer "RTC" frequency 32768 Hz quality 0
fdc0: <floppy drive controller (FDE)> port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on
acpi0
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Timecounter "HPET" frequency 14318180 Hz quality 900
Event timer "HPET" frequency 14318180 Hz quality 450
Event timer "HPET1" frequency 14318180 Hz quality 440
Event timer "HPET2" frequency 14318180 Hz quality 440
ppc0: <Parallel port> port 0x378-0x37f,0x778-0x77f irq 7 drq 3 on acpi0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/9 bytes threshold
ppbus0: <Parallel port bus> on ppc0
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model IntelliMouse Explorer, device ID 4
pmtimer0 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
ata0 at port 0x1f0-0x1f7,0x3f6 irq 14 on isa0
ata1 at port 0x170-0x177,0x376 irq 15 on isa0
coretemp0: <CPU On-Die Thermal Sensors> on cpu0
est0: <Enhanced SpeedStep Frequency Control> on cpu0
p4tcc0: <CPU Frequency Thermal Control> on cpu0
coretemp1: <CPU On-Die Thermal Sensors> on cpu1
est1: <Enhanced SpeedStep Frequency Control> on cpu1
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Timecounters tick every 1.000 msec
vboxdrv: fAsync=0 offMin=0x132 offMax=0x369
hdac0: HDA Codec #0: Analog Devices AD1988A
pcm0: <HDA Analog Devices AD1988A PCM #0 Analog> at cad 0 nid 1 on hdac0
pcm1: <HDA Analog Devices AD1988A PCM #1 Analog> at cad 0 nid 1 on hdac0
pcm2: <HDA Analog Devices AD1988A PCM #2 Digital> at cad 0 nid 1 on hdac0
usbus0: 12Mbps Full Speed USB v1.0
usbus1: 12Mbps Full Speed USB v1.0
usbus2: 480Mbps High Speed USB v2.0
usbus3: 12Mbps Full Speed USB v1.0
usbus4: 12Mbps Full Speed USB v1.0
usbus5: 12Mbps Full Speed USB v1.0
usbus6: 480Mbps High Speed USB v2.0
ugen0.1: <Intel> at usbus0
uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
ugen1.1: <Intel> at usbus1
uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
ugen2.1: <Intel> at usbus2
uhub2: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus2
ugen3.1: <Intel> at usbus3
uhub3: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus3
ugen4.1: <Intel> at usbus4
uhub4: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus4
ugen5.1: <Intel> at usbus5
uhub5: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus5
ugen6.1: <Intel> at usbus6
uhub6: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus6
uhub0: 2 ports with 2 removable, self powered
uhub1: 2 ports with 2 removable, self powered
uhub3: 2 ports with 2 removable, self powered
uhub4: 2 ports with 2 removable, self powered
uhub5: 2 ports with 2 removable, self powered
ada0 at ahcich2 bus 0 scbus2 target 0 lun 0
ada0: <WDC WD3200AAKS-22B3A0 01.03A01> ATA-8 SATA 2.x device
ada0: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
ada0: Command Queueing enabled
ada0: 305245MB (625142448 512 byte sectors: 16H 63S/T 16383C)
cd0 at ahcich6 bus 0 scbus6 target 0 lun 0SMP: AP CPU #1 Launched!
cd0:
<TSSTcorp CDDVDW SH-S223C SB06> Removable CD-ROM SCSI-0 device
cd0: 150.000MB/s transfers (SATA 1.x, WDMA2, ATAPI 12bytes, PIO 8192bytes)
cd0: Attempt to query device size failed: NOT READY, Medium not present - tray
closed
Root mount waiting for: usbus6 usbus2
uhub2: 4 ports with 4 removable, self powered
uhub6: 6 ports with 6 removable, self powered
Trying to mount root from ufs:/dev/label/root [rw]...
ada1 at ahcich0 bus 0 scbus0 target 0 lun 0
ada1: <SAMSUNG SP2504C VT100-50> ATA-7 SATA 2.x device
ada1: 150.000MB/s transfers (SATA 1.x, UDMA6, PIO 8192bytes)
ada1: Command Queueing enabled
ada1: 238475MB (488397168 512 byte sectors: 16H 63S/T 16383C)
GEOM: ada1s1: geometry does not match label (255h,63s != 16h,63s).
GEOM: label/amanda: geometry does not match label (255h,63s != 16h,63s).
GEOM: ufsid/4a9e8e3d6361b6c0: geometry does not match label (255h,63s !=
16h,63s).
handle_workitem_freeblocks: inode 5 block count 84
handle_workitem_freeblocks: inode 328 block count 112
handle_workitem_freeblocks: inode 5 block count 84
GEOM: ada1s1: geometry does not match label (255h,63s != 16h,63s).
GEOM: label/amanda: geometry does not match label (255h,63s != 16h,63s).
(ada1:ahcich0:0:0:0): lost device
(ada1:ahcich0:0:0:0): removing device entry
ROOT LOGIN (root) ON ttyv0
exiting on signal 15
kernel boot file is /boot/kernel/kernel
/jail: unmount pending error: blocks 112 files 0
softdep_waitidle: Failed to flush worklist for 0xc5bdb510
/data: unmount pending error: blocks 84 files 0
Waiting (max 60 seconds) for system process `vnlru' to stop...done
Waiting (max 60 seconds) for system process `bufdaemon' to stop...done
Waiting (max 60 seconds) for system process `syncer' to stop...
Syncing disks, vnodes remaining...1 0 0 done
All buffers synced.
softdep_waitidle: Failed to flush worklist for 0xc5bdb510
unmount of /home failed (BUSY)
On Saturday 05 March 2011 17:36:04 Christian Gusenbauer wrote:
> On Saturday 05 March 2011 10:36:40 Kostik Belousov wrote:
> > [Added fs, please remove current on reply].
> >
> > On Sat, Mar 05, 2011 at 08:51:38AM +0100, Christian Gusenbauer wrote:
> > > Hi!
> > >
> > > Once in a week I backup my PC using amanda (which uses dump(8)).
> > > Subsequently unmounting a filesystem (or during shutdown) I get errors
> > > like these:
> > >
> > > handle_workitem_freeblocks: inode 3579904 block count 384
> > > handle_workitem_freeblocks: inode 3579962 block count 384
> > > /spare: unmount pending error: blocks 768 files 0
> > > softdep_waitidle: Failed to flush worklist for 0xc5be2288
> > > softdep_waitidle: Failed to flush worklist for 0xc5be2288
> > > softdep_waitidle: Failed to flush worklist for 0xc5be2288
> > > softdep_waitidle: Failed to flush worklist for 0xc5be2288
> > > softdep_waitidle: Failed to flush worklist for 0xc5be2288
> > > softdep_waitidle: Failed to flush worklist for 0xc5be2510
> > > softdep_waitidle: Failed to flush worklist for 0xc5be2510
> > > /data: unmount pending error: blocks 84 files 0
> > >
> > > Note, I get these messages *only* after dumping filesystems!
> > >
> > > Is there anything wrong with my filesystems?
> > >
> > > Any clues?
> >
> > No clues, you did not provided information even to start looking.
> >
> > What version of the OS you are using ?
> > How do you call dump, in particular, did you specified -L ?
> > Are there any hung (unkillable) processes after the issue appeared ?
> > Please show all kernel messages, not only the tail of it.
>
> Hi!
>
> I'm running current revision 219265. Amanda calls dump like this (ps ax
> output):
>
> 2607 ?? DL 0:00.04 dump 0ubLshf 64 1048576 0 - /dev/label/home (dump)
>
> so the -L is specified. There are no hung processes.
>
> I attach a compressed dmesg output. It appears to me, that the
> 'handle_workitem_freeblocks' message is printed during the backup. The
> other messages are printed during shutdown.
>
> Thanks,
> Christian.
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?201103051748.44554.c47g>