The server crashes frequently

Bug #455860 reported by Rodrigo Ap. F. Tomazini
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
nfs-utils (Ubuntu)
New
Undecided
Unassigned

Bug Description

I have a NFS server that crashes frequently with this error messages in syslog:

Oct 19 14:05:35 aldebaran kernel: [1711173.674453]
Oct 19 14:05:35 aldebaran kernel: [1711173.674457] Pid: 4481, comm: nfsd Not tainted (2.6.24-24-server #1)
Oct 19 14:05:35 aldebaran kernel: [1711173.674462] EIP: 0060:[find_get_pages_contig+0x88/0xa0] EFLAGS: 00000216 CPU: 0
Oct 19 14:05:35 aldebaran kernel: [1711173.674477] EIP is at find_get_pages_contig+0x88/0xa0
Oct 19 14:05:35 aldebaran kernel: [1711173.674480] EAX: d11ac8dc EBX: c11114c0 ECX: 00000004 EDX: 00000004
Oct 19 14:05:35 aldebaran kernel: [1711173.674483] ESI: 00000004 EDI: 00000004 EBP: f6c55df8 ESP: f6c55cd0
Oct 19 14:05:35 aldebaran kernel: [1711173.674489] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
Oct 19 14:05:35 aldebaran kernel: [1711173.674493] CR0: 8005003b CR2: b7fa8000 CR3: 1f847000 CR4: 000006f0
Oct 19 14:05:35 aldebaran kernel: [1711173.674500] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
Oct 19 14:05:35 aldebaran kernel: [1711173.674504] DR6: ffff0ff0 DR7: 00000400
Oct 19 14:05:35 aldebaran kernel: [1711173.674532] [ext3:generic_file_splice_read+0x161/0x1cb0] generic_file_splice_read+0x161/0x590
Oct 19 14:05:35 aldebaran kernel: [1711173.674582] [<f8970505>] find_acceptable_alias+0x15/0xe0 [exportfs]
Oct 19 14:05:35 aldebaran kernel: [1711173.674591] [<f8d910a0>] nfsd_acceptable+0x0/0xf0 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.674617] [<f897064a>] exportfs_decode_fh+0x7a/0x1e2 [exportfs]
Oct 19 14:05:35 aldebaran kernel: [1711173.674637] [irq_exit+0x51/0x80] irq_exit+0x51/0x80
Oct 19 14:05:35 aldebaran kernel: [1711173.674645] [do_IRQ+0x40/0x70] do_IRQ+0x40/0x70
Oct 19 14:05:35 aldebaran kernel: [1711173.674652] [<f8ce0498>] cache_check+0x38/0x400 [sunrpc]
Oct 19 14:05:35 aldebaran kernel: [1711173.674677] [skb_copy_datagram_iovec+0x149/0x200] skb_copy_datagram_iovec+0x149/0x200
Oct 19 14:05:35 aldebaran kernel: [1711173.674685] [sunrpc:skb_recv_datagram+0x68/0x3870] skb_recv_datagram+0x68/0x1e0
Oct 19 14:05:35 aldebaran kernel: [1711173.674701] [common_interrupt+0x23/0x28] common_interrupt+0x23/0x28
Oct 19 14:05:35 aldebaran kernel: [1711173.674724] [<f8ce007b>] cache_fresh_unlocked+0x5b/0x60 [sunrpc]
Oct 19 14:05:35 aldebaran kernel: [1711173.674757] [set_current_groups+0x25e/0x270] set_current_groups+0x25e/0x270
Oct 19 14:05:35 aldebaran kernel: [1711173.674789] [<f8d97a16>] nfsd_setuser+0x86/0x200 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.674818] [<f8d90baa>] nfsd_setuser_and_check_port+0x5a/0x70 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.674856] [do_splice_to+0x6e/0x90] do_splice_to+0x6e/0x90
O
Oct 19 14:05:35 aldebaran kernel: [1711173.674885] [<f8d927a0>] nfsd_direct_splice_actor+0x0/0x10 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.674913] [<c01b9580>] generic_file_splice_read+0x0/0x590
Oct 19 14:05:35 aldebaran kernel: [1711173.674921] [<f8d92771>] nfsd_vfs_read+0x341/0x370 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.674937] [dentry_open+0x34/0x80] dentry_open+0x34/0x80
Oct 19 14:05:35 aldebaran kernel: [1711173.674971] [<f8d92cfd>] nfsd_read+0xdd/0xf0 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.675003] [<f8d9a3fa>] nfsd3_proc_read+0xea/0x1a0 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.675039] [<f8d8e23e>] nfsd_dispatch+0x9e/0x220 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.675057] [<f8cdce89>] svcauth_unix_set_client+0x149/0x180 [sunrpc]
Oct 19 14:05:35 aldebaran kernel: [1711173.675081] [<f8d8e1a0>] nfsd_dispatch+0x0/0x220 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.675099] [<f8cd914d>] svc_process+0x43d/0x790 [sunrpc]
Oct 19 14:05:35 aldebaran kernel: [1711173.675119] [<f8cdc4d5>] svc_recv+0x285/0x430 [sunrpc]
Oct 19 14:05:35 aldebaran kernel: [1711173.675146] [common_interrupt+0x23/0x28] common_interrupt+0x23/0x28
Oct 19 14:05:35 aldebaran kernel: [1711173.675179] [<f8d8e8e2>] nfsd+0x172/0x2a0 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.675204] [<f8d8e770>] nfsd+0x0/0x2a0 [nfsd]
Oct 19 14:05:35 aldebaran kernel: [1711173.675224] [kernel_thread_helper+0x7/0x10] kernel_thread_helper+0x7/0x10
Oct 19 14:05:35 aldebaran kernel: [1711173.675243] =======================

This is the fresh dmesg, after the crash:

*********************************************************************
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 2.6.24-24-server (buildd@palmer) (gcc version 4.2.4 (Ubuntu 4.2.4-1ubuntu4)) #1 SMP Fri Sep 18 17:24:10 UTC 2009 (Ubuntu 2.6.24-24.61-server)
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000e6000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 000000003fe30000 (usable)
[ 0.000000] BIOS-e820: 000000003fe30000 - 000000003fe41422 (ACPI NVS)
[ 0.000000] BIOS-e820: 000000003fe41422 - 000000003ff30000 (usable)
[ 0.000000] BIOS-e820: 000000003ff30000 - 000000003ff40000 (ACPI data)
[ 0.000000] BIOS-e820: 000000003ff40000 - 000000003fff0000 (ACPI NVS)
[ 0.000000] BIOS-e820: 000000003fff0000 - 0000000040000000 (reserved)
[ 0.000000] BIOS-e820: 00000000fecf0000 - 00000000fecf1000 (reserved)
[ 0.000000] BIOS-e820: 00000000fed20000 - 00000000feda0000 (reserved)
[ 0.000000] 127MB HIGHMEM available.
[ 0.000000] 896MB LOWMEM available.
[ 0.000000] found SMP MP-table at 000ff780
[ 0.000000] Entering add_active_range(0, 0, 261936) 0 entries of 256 used
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0 -> 4096
[ 0.000000] Normal 4096 -> 229376
[ 0.000000] HighMem 229376 -> 261936
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[1] active PFN ranges
[ 0.000000] 0: 0 -> 261936
[ 0.000000] On node 0 totalpages: 261936
[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 4064 pages, LIFO batch:0
[ 0.000000] Normal zone: 1760 pages used for memmap
[ 0.000000] Normal zone: 223520 pages, LIFO batch:31
[ 0.000000] HighMem zone: 254 pages used for memmap
[ 0.000000] HighMem zone: 32306 pages, LIFO batch:7
[ 0.000000] Movable zone: 0 pages used for memmap
[ 0.000000] DMI 2.3 present.
[ 0.000000] ACPI: RSDP signature @ 0xC00F6190 checksum 0
[ 0.000000] ACPI: RSDP 000F6190, 0014 (r0 ACPIAM)
[ 0.000000] ACPI: RSDT 3FF30000, 0030 (r1 INTEL S875PWP4 20031015 MSFT 97)
[ 0.000000] ACPI: FACP 3FF30200, 0081 (r2 INTEL S875PWP4 20031015 MSFT 97)
[ 0.000000] ACPI: DSDT 3FF30370, 424E (r1 INTEL S875PWP4 1 MSFT 100000D)
[ 0.000000] ACPI: FACS 3FF40000, 0040
[ 0.000000] ACPI: APIC 3FF30300, 0068 (r1 INTEL S875PWP4 20031015 MSFT 97)
[ 0.000000] ACPI: WDDT 3FF345C0, 0040 (r1 INTEL OEMWDDT 1 MSFT 100000D)
[ 0.000000] ACPI: PM-Timer IO Port: 0x408
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
[ 0.000000] Processor #0 15:2 APIC version 20
[ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[ 0.000000] Processor #1 15:2 APIC version 20
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] dfl dfl lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] ACPI: IRQ0 used by override.
[ 0.000000] ACPI: IRQ2 used by override.
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] Enabling APIC mode: Flat. Using 1 I/O APICs
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] Allocating PCI resources starting at 50000000 (gap: 40000000:becf0000)
[ 0.000000] swsusp: Registered nosave memory region: 000000000009f000 - 00000000000a0000
[ 0.000000] swsusp: Registered nosave memory region: 00000000000a0000 - 00000000000e6000
[ 0.000000] swsusp: Registered nosave memory region: 00000000000e6000 - 0000000000100000
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 259890
[ 0.000000] Kernel command line: root=UUID=1673a4d4-7178-4a62-a8cb-b15b583832c6 ro quiet splash
[ 0.000000] mapped APIC to ffffb000 (fee00000)
[ 0.000000] mapped IOAPIC to ffffa000 (fec00000)
[ 0.000000] Enabling fast FPU save and restore... done.
[ 0.000000] Enabling unmasked SIMD FPU exception support... done.
[ 0.000000] Initializing CPU#0
[ 0.000000] PID hash table entries: 4096 (order: 12, 16384 bytes)
[ 0.000000] Detected 3192.151 MHz processor.
[ 40.303797] Console: colour VGA+ 80x25
[ 40.303800] console [tty0] enabled
[ 40.304310] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 40.304799] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 40.330146] Memory: 1026484k/1047744k available (2260k kernel code, 20532k reserved, 1035k data, 384k init, 130168k highmem)
[ 40.330156] virtual kernel memory layout:
[ 40.330157] fixmap : 0xfff4c000 - 0xfffff000 ( 716 kB)
[ 40.330157] pkmap : 0xffc00000 - 0xffe00000 (2048 kB)
[ 40.330158] vmalloc : 0xf8800000 - 0xffbfe000 ( 115 MB)
[ 40.330159] lowmem : 0xc0000000 - 0xf8000000 ( 896 MB)
[ 40.330160] .init : 0xc043e000 - 0xc049e000 ( 384 kB)
[ 40.330161] .data : 0xc0335329 - 0xc0437fe4 (1035 kB)
[ 40.330162] .text : 0xc0100000 - 0xc0335329 (2260 kB)
[ 40.330164] Checking if this processor honours the WP bit even in supervisor mode... Ok.
[ 40.330210] SLUB: Genslabs=11, HWalign=64, Order=0-1, MinObjects=4, CPUs=2, Nodes=1
[ 40.330254] Calibrating delay loop (skipped), using tsc calculated value.. 6384.30 BogoMIPS (lpj=31921510)
[ 40.330284] Security Framework initialized
[ 40.330293] SELinux: Disabled at boot.
[ 40.330308] AppArmor: AppArmor initialized
[ 40.330312] Failure registering capabilities with primary security module.
[ 40.330321] Mount-cache hash table entries: 512
[ 40.330463] Initializing cgroup subsys ns
[ 40.330468] Initializing cgroup subsys cpuacct
[ 40.330480] CPU: After generic identify, caps: bfebfbff 00000000 00000000 00000000 00004400 00000000 00000000 00000000
[ 40.330491] CPU: Trace cache: 12K uops, L1 D cache: 8K
[ 40.330493] CPU: L2 cache: 512K
[ 40.330496] CPU: Physical Processor ID: 0
[ 40.330499] CPU: After all inits, caps: bfebfbff 00000000 00000000 00043080 00004400 00000000 00000000 00000000
[ 40.330510] Compat vDSO mapped to ffffe000.
[ 40.330523] Checking 'hlt' instruction... OK.
[ 40.370488] SMP alternatives: switching to UP code
[ 40.371896] Early unpacking initramfs... done
[ 40.622581] ACPI: Core revision 20070126
[ 40.622631] ACPI: Looking for DSDT in initramfs... error, file /DSDT.aml not found.
[ 40.624441] CPU0: Intel(R) Pentium(R) 4 CPU 3.20GHz stepping 09
[ 40.624460] SMP alternatives: switching to SMP code
[ 40.625059] Booting processor 1/1 eip 3000
[ 40.635204] Initializing CPU#1
[ 40.779112] Calibrating delay using timer specific routine.. 6384.18 BogoMIPS (lpj=31920946)
[ 40.779122] CPU: After generic identify, caps: bfebfbff 00000000 00000000 00000000 00004400 00000000 00000000 00000000
[ 40.779131] CPU: Trace cache: 12K uops, L1 D cache: 8K
[ 40.779133] CPU: L2 cache: 512K
[ 40.779135] CPU: Physical Processor ID: 0
[ 40.779139] CPU: After all inits, caps: bfebfbff 00000000 00000000 00043080 00004400 00000000 00000000 00000000
[ 40.779394] CPU1: Intel(R) Pentium(R) 4 CPU 3.20GHz stepping 09
[ 40.779432] Total of 2 processors activated (12768.49 BogoMIPS).
[ 40.779560] ENABLING IO-APIC IRQs
[ 40.779729] ..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 40.998710] checking TSC synchronization [CPU#0 -> CPU#1]: passed.
[ 41.018677] Brought up 2 CPUs
[ 41.018700] CPU0 attaching sched-domain:
[ 41.018703] domain 0: span 03
[ 41.018705] groups: 01 02
[ 41.018708] domain 1: span 03
[ 41.018710] groups: 03
[ 41.018713] CPU1 attaching sched-domain:
[ 41.018714] domain 0: span 03
[ 41.018716] groups: 02 01
[ 41.018719] domain 1: span 03
[ 41.018721] groups: 03
[ 41.018999] net_namespace: 64 bytes
[ 41.019011] Booting paravirtualized kernel on bare hardware
[ 41.019521] Time: 19:54:25 Date: 10/19/09
[ 41.019554] NET: Registered protocol family 16
[ 41.019812] EISA bus registered
[ 41.019820] ACPI: bus type pci registered
[ 41.021081] PCI: PCI BIOS revision 2.10 entry at 0xf0031, last bus=3
[ 41.021083] PCI: Using configuration type 1
[ 41.021102] Setting up standard PCI resources
[ 41.033051] ACPI: EC: Look up EC in DSDT
[ 41.036063] ACPI: Interpreter enabled
[ 41.036067] ACPI: (supports S0 S1 S3 S4 S5)
[ 41.036083] ACPI: Using IOAPIC for interrupt routing
[ 41.041633] ACPI: PCI Root Bridge [PCI0] (0000:00)
[ 41.042110] Force enabled HPET at base address 0xfed00000
[ 41.042118] PCI quirk: region 0400-047f claimed by ICH4 ACPI/GPIO/TCO
[ 41.042122] PCI quirk: region 0500-053f claimed by ICH4 GPIO
[ 41.042727] PCI: Transparent bridge - 0000:00:1e.0
[ 41.042754] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 41.042839] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P1._PRT]
[ 41.042907] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P2._PRT]
[ 41.042966] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P3._PRT]
[ 41.045082] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 *5 6 7 9 10 11 12 14 15)
[ 41.045213] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 *9 10 11 12 14 15)
[ 41.045341] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 9 *10 11 12 14 15)
[ 41.045469] ACPI: PCI Interrupt Link [LNKD] (IRQs *3 4 5 6 7 9 10 11 12 14 15)
[ 41.045596] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
[ 41.045723] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[ 41.045853] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 *9 10 11 12 14 15)
[ 41.045980] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 *9 10 11 12 14 15)
[ 41.046126] ACPI: Power Resource [URP1] (off)
[ 41.046156] ACPI: Power Resource [URP2] (off)
[ 41.046184] ACPI: Power Resource [FDDP] (off)
[ 41.046213] ACPI: Power Resource [LPTP] (off)
[ 41.046276] Linux Plug and Play Support v0.97 (c) Adam Belay
[ 41.046318] pnp: PnP ACPI init
[ 41.046328] ACPI: bus type pnp registered
[ 41.049262] pnp: PnP ACPI: found 14 devices
[ 41.049266] ACPI: ACPI bus type pnp unregistered
[ 41.049270] PnPBIOS: Disabled by ACPI PNP
[ 41.049583] PCI: Using ACPI for IRQ routing
[ 41.049586] PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
[ 41.128279] NET: Registered protocol family 8
[ 41.128282] NET: Registered protocol family 20
[ 41.128330] NetLabel: Initializing
[ 41.128334] NetLabel: domain hash size = 128
[ 41.128335] NetLabel: protocols = UNLABELED CIPSOv4
[ 41.128348] NetLabel: unlabeled traffic allowed by default
[ 41.128426] hpet clockevent registered
[ 41.128430] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 41.128435] hpet0: 3 64-bit timers, 14318180 Hz
[ 41.129480] AppArmor: AppArmor Filesystem Enabled
[ 41.138230] Time: tsc clocksource has been installed.
[ 41.178192] system 00:0a: ioport range 0x4d0-0x4d1 has been reserved
[ 41.178201] system 00:0c: ioport range 0x400-0x47f has been reserved
[ 41.178203] system 00:0c: ioport range 0x680-0x6ff has been reserved
[ 41.178206] system 00:0c: ioport range 0x500-0x53f has been reserved
[ 41.178210] system 00:0c: iomem range 0xfec00000-0xfec00fff has been reserved
[ 41.178212] system 00:0c: iomem range 0xfee00000-0xfee00fff has been reserved
[ 41.178215] system 00:0c: iomem range 0xfed20000-0xfed9ffff could not be reserved
[ 41.178222] system 00:0d: iomem range 0x0-0x9ffff could not be reserved
[ 41.178225] system 00:0d: iomem range 0xc0000-0xdffff could not be reserved
[ 41.178227] system 00:0d: iomem range 0xe0000-0xfffff could not be reserved
[ 41.178230] system 00:0d: iomem range 0x100000-0x3fffffff could not be reserved
[ 41.178232] system 00:0d: iomem range 0x0-0x0 could not be reserved
[ 41.208706] PCI: Bridge: 0000:00:01.0
[ 41.208709] IO window: disabled.
[ 41.208713] MEM window: disabled.
[ 41.208716] PREFETCH window: disabled.
[ 41.208720] PCI: Bridge: 0000:00:03.0
[ 41.208723] IO window: 9000-9fff
[ 41.208727] MEM window: fc100000-fc1fffff
[ 41.208730] PREFETCH window: disabled.
[ 41.208736] PCI: Bridge: 0000:00:1e.0
[ 41.208739] IO window: a000-bfff
[ 41.208744] MEM window: fc200000-feafffff
[ 41.208748] PREFETCH window: ff100000-ff1fffff
[ 41.208767] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[ 41.208779] NET: Registered protocol family 2
[ 41.317861] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 41.318186] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[ 41.318830] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[ 41.319214] TCP: Hash tables configured (established 131072 bind 65536)
[ 41.319218] TCP reno registered
[ 41.347891] checking if image is initramfs... it is
[ 41.627157] Switched to high resolution mode on CPU 1
[ 41.636992] Switched to high resolution mode on CPU 0
[ 41.847370] Freeing initrd memory: 7188k freed
[ 41.848051] audit: initializing netlink socket (disabled)
[ 41.848065] audit(1255982065.400:1): initialized
[ 41.848310] highmem bounce pool size: 64 pages
[ 41.848315] Total HugeTLB memory allocated, 0
[ 41.850732] VFS: Disk quotas dquot_6.5.1
[ 41.850830] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 41.851059] io scheduler noop registered
[ 41.851062] io scheduler anticipatory registered
[ 41.851064] io scheduler deadline registered (default)
[ 41.851077] io scheduler cfq registered
[ 51.821812] 0000:00:1d.7 EHCI: BIOS handoff failed (BIOS bug ?) 01010001
[ 51.821946] Boot video device is 0000:03:06.0
[ 51.821951] PCI: Firmware left 0000:03:08.0 e100 interrupts enabled, disabling
[ 51.822328] isapnp: Scanning for PnP cards...
[ 52.178039] isapnp: No Plug & Play device found
[ 52.214865] Real Time Clock Driver v1.12ac
[ 52.214991] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[ 52.215115] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 52.215975] 00:08: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 52.216997] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
[ 52.217079] input: Macintosh mouse button emulation as /devices/virtual/input/input0
[ 52.217213] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
[ 52.220123] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 52.220129] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 52.270818] mice: PS/2 mouse device common for all mice
[ 52.270981] EISA: Probing bus 0 at eisa.0
[ 52.271020] EISA: Detected 0 cards.
[ 52.271024] cpuidle: using governor ladder
[ 52.271026] cpuidle: using governor menu
[ 52.271122] NET: Registered protocol family 1
[ 52.271153] Using IPI No-Shortcut mode
[ 52.271188] registered taskstats version 1
[ 52.271298] Magic number: 13:359:951
[ 52.271313] hash matches device ttya6
[ 52.271383] hash matches device tty8
[ 52.271403] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found
[ 52.272018] Freeing unused kernel memory: 384k freed
[ 52.272067] Write protecting the kernel read-only data: 824k
[ 52.291762] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[ 52.461670] fuse init (API version 7.9)
[ 52.483140] ACPI: Processor [CPU1] (supports 8 throttling states)
[ 52.483179] ACPI: Processor [CPU2] (supports 8 throttling states)
[ 52.641808] usbcore: registered new interface driver usbfs
[ 52.641858] usbcore: registered new interface driver hub
[ 52.642853] usbcore: registered new device driver usb
[ 52.652490] USB Universal Host Controller Interface driver v3.0
[ 52.652597] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 16 (level, low) -> IRQ 16
[ 52.652618] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[ 52.652623] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[ 52.652986] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1
[ 52.653028] uhci_hcd 0000:00:1d.0: irq 16, io base 0x0000cc00
[ 52.653230] usb usb1: configuration #1 chosen from 1 choice
[ 52.653274] hub 1-0:1.0: USB hub found
[ 52.653285] hub 1-0:1.0: 2 ports detected
[ 52.762124] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 19 (level, low) -> IRQ 17
[ 52.762139] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[ 52.762144] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[ 52.762177] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 2
[ 52.762213] uhci_hcd 0000:00:1d.1: irq 17, io base 0x0000d000
[ 52.762379] usb usb2: configuration #1 chosen from 1 choice
[ 52.762413] hub 2-0:1.0: USB hub found
[ 52.762421] hub 2-0:1.0: 2 ports detected
[ 52.871856] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18
[ 52.871872] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[ 52.871878] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[ 52.871910] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 3
[ 52.871939] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000d400
[ 52.872098] usb usb3: configuration #1 chosen from 1 choice
[ 52.872134] hub 3-0:1.0: USB hub found
[ 52.872143] hub 3-0:1.0: 2 ports detected
[ 52.920261] Intel(R) PRO/1000 Network Driver - version 7.3.20-k2-NAPI
[ 52.920268] Copyright (c) 1999-2006 Intel Corporation.
[ 52.981586] ACPI: PCI Interrupt 0000:00:1d.3[A] -> GSI 16 (level, low) -> IRQ 16
[ 52.981601] PCI: Setting latency timer of device 0000:00:1d.3 to 64
[ 52.981606] uhci_hcd 0000:00:1d.3: UHCI Host Controller
[ 52.981639] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 4
[ 52.981666] uhci_hcd 0000:00:1d.3: irq 16, io base 0x0000d800
[ 52.981820] usb usb4: configuration #1 chosen from 1 choice
[ 52.981855] hub 4-0:1.0: USB hub found
[ 52.981863] hub 4-0:1.0: 2 ports detected
[ 53.027284] SCSI subsystem initialized
[ 53.041311] usb 1-2: new full speed USB device using uhci_hcd and address 2
[ 53.049628] Fusion MPT base driver 3.04.06
[ 53.049634] Copyright (c) 1999-2007 LSI Corporation
[ 53.080403] libata version 3.00 loaded.
[ 53.088949] Fusion MPT SPI Host driver 3.04.06
[ 53.091421] ACPI: PCI Interrupt 0000:00:1d.7[D] -> GSI 23 (level, low) -> IRQ 19
[ 53.091445] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[ 53.091450] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[ 53.091500] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 5
[ 53.095417] ehci_hcd 0000:00:1d.7: debug port 1
[ 53.095426] PCI: cache line size of 128 is not supported by device 0000:00:1d.7
[ 53.095445] ehci_hcd 0000:00:1d.7: irq 19, io mem 0xfebffc00
[ 53.102788] e100: Intel(R) PRO/100 Network Driver, 3.5.23-k4-NAPI
[ 53.102799] e100: Copyright(c) 1999-2006 Intel Corporation
[ 53.106043] Floppy drive(s): fd0 is 1.44M
[ 53.121146] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[ 53.121446] usb usb5: configuration #1 chosen from 1 choice
[ 53.121506] hub 5-0:1.0: USB hub found
[ 53.121518] hub 5-0:1.0: 8 ports detected
[ 53.122477] FDC 0 is a post-1991 82077
[ 53.231245] ACPI: PCI Interrupt 0000:03:01.0[A] -> GSI 22 (level, low) -> IRQ 20
[ 53.231273] mptbase: ioc0: Initiating bringup
[ 53.507691] usb 5-2: new high speed USB device using ehci_hcd and address 2
[ 54.445321] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[ 55.227334] scsi0 : ioc0: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=20
[ 55.782145] ACPI: PCI Interrupt 0000:02:01.0[A] -> GSI 18 (level, low) -> IRQ 18
[ 55.782171] PCI: Setting latency timer of device 0000:02:01.0 to 64
[ 55.801646] e1000: 0000:02:01.0: e1000_probe: (PCI:33MHz:32-bit) 00:0c:f1:ad:30:3d
[ 55.809199] scsi 0:0:0:0: Sequential-Access SONY SDT-11000 0200 PQ: 0 ANSI: 2
[ 55.809214] target0:0:0: Beginning Domain Validation
[ 55.816960] target0:0:0: Domain Validation skipping write tests
[ 55.816963] target0:0:0: Ending Domain Validation
[ 55.817019] target0:0:0: FAST-20 WIDE SCSI 40.0 MB/s ST (50 ns, offset 15)
[ 56.145771] e1000: eth0: e1000_probe: Intel(R) PRO/1000 Network Connection
[ 56.145861] ACPI: PCI Interrupt 0000:03:08.0[A] -> GSI 20 (level, low) -> IRQ 21
[ 56.168588] e100: eth1: e100_probe: addr 0xfeafd000, irq 21, MAC addr 00:0c:f1:ad:30:3f
[ 56.168640] PCI: Enabling device 0000:00:1f.1 (0005 -> 0007)
[ 56.168653] ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 18 (level, low) -> IRQ 18
[ 56.168712] PCI: Setting latency timer of device 0000:00:1f.1 to 64
[ 56.168730] ACPI: PCI interrupt for device 0000:00:1f.1 disabled
[ 56.168754] ACPI: PCI Interrupt 0000:00:1f.2[A] -> GSI 18 (level, low) -> IRQ 18
[ 56.168785] PCI: Setting latency timer of device 0000:00:1f.2 to 64
[ 56.168804] ACPI: PCI interrupt for device 0000:00:1f.2 disabled
[ 56.169174] sata_promise 0000:03:07.0: version 2.11
[ 56.169199] ACPI: PCI Interrupt 0000:03:07.0[A] -> GSI 17 (level, low) -> IRQ 22
[ 56.181116] scsi1 : sata_promise
[ 56.181369] scsi2 : sata_promise
[ 56.181567] scsi3 : sata_promise
[ 56.181752] scsi4 : sata_promise
[ 56.181802] ata1: SATA max UDMA/133 mmio m4096@0xfeafe000 port 0xfeafe200 irq 22
[ 56.181807] ata2: SATA max UDMA/133 mmio m4096@0xfeafe000 port 0xfeafe280 irq 22
[ 56.181811] ata3: SATA max UDMA/133 mmio m4096@0xfeafe000 port 0xfeafe300 irq 22
[ 56.181815] ata4: SATA max UDMA/133 mmio m4096@0xfeafe000 port 0xfeafe380 irq 22
[ 56.669848] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 56.710191] ata1.00: ATA-8: SAMSUNG HD501LJ, CR100-10, max UDMA7
[ 56.710195] ata1.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
[ 56.732626] ata1.00: configured for UDMA/133
[ 57.058863] ata2: SATA link down (SStatus 0 SControl 0)
[ 57.388046] ata3: SATA link down (SStatus 0 SControl 0)
[ 57.727212] ata4: SATA link down (SStatus 0 SControl 300)
[ 57.727267] scsi: waiting for bus probes to complete ...
[ 58.030095] usb 5-2: configuration #1 chosen from 1 choice
[ 58.040562] usbcore: registered new interface driver libusual
[ 58.046748] Initializing USB Mass Storage driver...
[ 58.046862] scsi5 : SCSI emulation for USB Mass Storage devices
[ 58.046978] usbcore: registered new interface driver usb-storage
[ 58.046983] USB Mass Storage support registered.
[ 58.047218] usb-storage: device found at 2
[ 58.047222] usb-storage: waiting for device to settle before scanning
[ 59.316362] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10 PQ: 0 ANSI: 5
[ 59.321669] ata_piix 0000:00:1f.1: version 2.12
[ 59.321695] ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 18 (level, low) -> IRQ 18
[ 59.321761] PCI: Setting latency timer of device 0000:00:1f.1 to 64
[ 59.323071] scsi6 : ata_piix
[ 59.323192] scsi7 : ata_piix
[ 59.324272] ata5: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xffa0 irq 14
[ 59.324280] ata6: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xffa8 irq 15
[ 59.351666] scsi 0:0:0:0: Attached scsi generic sg0 type 1
[ 59.351697] scsi 1:0:0:0: Attached scsi generic sg1 type 0
[ 59.358780] Driver 'sd' needs updating - please use bus_type methods
[ 59.358899] sd 1:0:0:0: [sda] 976773168 512-byte hardware sectors (500108 MB)
[ 59.358918] sd 1:0:0:0: [sda] Write Protect is off
[ 59.358921] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 59.358952] sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 59.359024] sd 1:0:0:0: [sda] 976773168 512-byte hardware sectors (500108 MB)
[ 59.359040] sd 1:0:0:0: [sda] Write Protect is off
[ 59.359044] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 59.359071] sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 59.359077] sda:<6>st: Version 20070203, fixed bufsize 32768, s/g segs 256
[ 59.364408] Driver 'st' needs updating - please use bus_type methods
[ 59.364672] st 0:0:0:0: Attached scsi tape st0
[ 59.364679] st 0:0:0:0: st0: try direct i/o: yes (alignment 512 B)
[ 59.426462] sda1 sda2 sda3 sda4 < sda5 sda6 sda7 sda8 sda9 >
[ 59.493245] sd 1:0:0:0: [sda] Attached SCSI disk
[ 59.496977] osst :I: Tape driver with OnStream support version 0.99.4
[ 59.496981] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $
[ 59.497014] Driver 'osst' needs updating - please use bus_type methods
[ 59.652569] ata_piix 0000:00:1f.2: MAP [ P0 -- P1 -- ]
[ 59.652595] ACPI: PCI Interrupt 0000:00:1f.2[A] -> GSI 18 (level, low) -> IRQ 18
[ 59.652638] PCI: Setting latency timer of device 0000:00:1f.2 to 64
[ 59.652702] scsi8 : ata_piix
[ 59.652987] scsi9 : ata_piix
[ 59.653683] ata7: SATA max UDMA/133 cmd 0xec00 ctl 0xe800 bmdma 0xdc00 irq 18
[ 59.653687] ata8: SATA max UDMA/133 cmd 0xe400 ctl 0xe000 bmdma 0xdc08 irq 18
[ 60.056364] Attempting manual resume
[ 60.056368] swsusp: Resume From Partition 8:2
[ 60.056370] PM: Checking swsusp image.
[ 60.056546] PM: Resume from disk failed.
[ 60.071250] EXT3-fs: INFO: recovery required on readonly filesystem.
[ 60.071257] EXT3-fs: write access will be enabled during recovery.
[ 60.092925] kjournald starting. Commit interval 5 seconds
[ 60.092938] EXT3-fs: recovery complete.
[ 60.093202] EXT3-fs: mounted filesystem with ordered data mode.
[ 61.847204] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[ 61.888148] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 61.943467] Intel 82802 RNG detected
[ 61.952046] input: PC Speaker as /devices/platform/pcspkr/input/input2
[ 62.036728] EDAC MC: Ver: 2.1.0 Sep 18 2009
[ 62.086585] iTCO_vendor_support: vendor-support=0
[ 62.136433] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.02 (26-Jul-2007)
[ 62.136544] iTCO_wdt: Found a ICH5 or ICH5R TCO device (Version=1, TCOBASE=0x0460)
[ 62.136584] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[ 62.166421] EDAC i82875p: i82875p init one
[ 62.166679] PCI: Unable to reserve mem region #1:1000@fecf0000 for device 0000:00:06.0
[ 62.166764] EDAC MC0: Giving out device to 'i82875p_edac' 'i82875p': DEV 0000:00:00.0
[ 62.166799] EDAC PCI0: Giving out device to module 'i82875p_edac' controller 'EDAC PCI controller': DEV '0000:00:00.0' (POLLED)
[ 62.241835] Linux agpgart interface v0.102
[ 62.395919] input: Power Button (FF) as /devices/virtual/input/input3
[ 62.465559] ACPI: Power Button (FF) [PWRF]
[ 62.465714] input: Sleep Button (CM) as /devices/virtual/input/input4
[ 62.545317] ACPI: Sleep Button (CM) [SLPB]
[ 63.064100] usb-storage: device scan complete
[ 63.068379] scsi 5:0:0:0: Direct-Access ST325082 0AS PQ: 0 ANSI: 2
[ 63.069963] sd 5:0:0:0: [sdb] 488397168 512-byte hardware sectors (250059 MB)
[ 63.072208] sd 5:0:0:0: [sdb] Write Protect is off
[ 63.072213] sd 5:0:0:0: [sdb] Mode Sense: 38 00 00 00
[ 63.072216] sd 5:0:0:0: [sdb] Assuming drive cache: write through
[ 63.073453] sd 5:0:0:0: [sdb] 488397168 512-byte hardware sectors (250059 MB)
[ 63.075699] sd 5:0:0:0: [sdb] Write Protect is off
[ 63.075702] sd 5:0:0:0: [sdb] Mode Sense: 38 00 00 00
[ 63.075705] sd 5:0:0:0: [sdb] Assuming drive cache: write through
[ 63.075754] sdb:<6>udev: renamed network interface eth1 to eth0
[ 63.327029] udev: renamed network interface eth0_rename to eth1
[ 63.335052] input: PS/2 Generic Mouse as /devices/platform/i8042/serio1/input/input5
[ 63.403273] e100: eth0: e100_watchdog: link up, 100Mbps, full-duplex
[ 63.705506] parport_pc 00:09: reported by Plug and Play ACPI
[ 63.705540] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE,EPP]
[ 65.200014] loop: module loaded
[ 65.215574] lp0: using parport0 (interrupt-driven).
[ 65.375253] Adding 3903784k swap on /dev/sda2. Priority:-1 extents:1 across:3903784k
[ 65.813224] EXT3 FS on sda1, internal journal
[ 68.190446] sdb1
[ 68.190546] sd 5:0:0:0: [sdb] Attached SCSI disk
[ 68.190631] sd 5:0:0:0: Attached scsi generic sg2 type 0
[ 95.684846] kjournald starting. Commit interval 5 seconds
[ 95.685064] EXT3 FS on sda7, internal journal
[ 95.685069] EXT3-fs: mounted filesystem with ordered data mode.
[ 95.724907] kjournald starting. Commit interval 5 seconds
[ 95.725190] EXT3 FS on sda9, internal journal
[ 95.725195] EXT3-fs: mounted filesystem with ordered data mode.
[ 95.758103] kjournald starting. Commit interval 5 seconds
[ 95.758429] EXT3 FS on sda8, internal journal
[ 95.758434] EXT3-fs: mounted filesystem with ordered data mode.
[ 95.790241] kjournald starting. Commit interval 5 seconds
[ 95.790396] EXT3 FS on sda3, internal journal
[ 95.790401] EXT3-fs: mounted filesystem with ordered data mode.
[ 95.823442] kjournald starting. Commit interval 5 seconds
[ 95.823658] EXT3 FS on sda5, internal journal
[ 95.823662] EXT3-fs: mounted filesystem with ordered data mode.
[ 95.853212] kjournald starting. Commit interval 5 seconds
[ 95.853385] EXT3 FS on sda6, internal journal
[ 95.853389] EXT3-fs: mounted filesystem with ordered data mode.
[ 96.704085] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 98.248730] RPC: Registered udp transport module.
[ 98.248737] RPC: Registered tcp transport module.
[ 100.056411] Installing knfsd (copyright (C) 1996 <email address hidden>).
[ 100.274839] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ 100.292661] NFSD: starting 90-second grace period

if you need more information I can send it for you.

Thank you

ProblemType: Bug
Architecture: i386
Date: Mon Oct 19 20:04:06 2009
DistroRelease: Ubuntu 8.04
Package: nfs-kernel-server 1:1.1.2-2ubuntu2.2
PackageArchitecture: i386
ProcEnviron:
 SHELL=/bin/bash
 PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
 LANG=en_US.UTF-8
 LANGUAGE=en_US.UTF-8
SourcePackage: nfs-utils
Uname: Linux 2.6.24-24-server i686

Tags: apport-bug
Revision history for this message
Rodrigo Ap. F. Tomazini (raftomazini) wrote :
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.