Re: [discuss] 2.6.19-rc5: known regressions (v2)
From: Rafael J. Wysocki
Date: Sat Nov 11 2006 - 05:52:34 EST
On Saturday, 11 November 2006 10:25, Paolo Ornati wrote:
> On Sat, 11 Nov 2006 10:08:37 +0100
> "Rafael J. Wysocki" <rjw@xxxxxxx> wrote:
>
> > > Subject : BUG: scheduling while atomic: events/0/0x00000001/4
> > > after resume
> > > References : http://lkml.org/lkml/2006/11/2/209
> > > Submitter : Paolo Ornati <ornati@xxxxxxxxxxxxx>
> > > Status : unknown
> >
> > I couldn't find anything in the report that would indicate the problem occured
> > after a resume. Was it really the case?
>
> Ahh, I've written that in another email but I trimmed LKML from CC by
> mistake ;)
>
>
> Relevant portion of that mail follows... anyway it seems that "-rc5" is
> _OK_ since I'm running it by 2 days and it survived 9 suspend/resume
> cycles.
Okay, please let us know if it survives the next several cycles.
OTOH, the problem may be hiding.
> ------------------------------------------------------------------
>
> I've reproduced it (with rc4-g4b1c46a3), and I think it is
> suspend/resume related sice the messages start flooding dmesg just
> after a resume...
>
> I'll see if it is reproducible just doing suspend/resume a couple of
> times... and if so I'll try with -rc5.
>
>
> dmesg (stripped at the end):
>
> [ 0.000000] Linux version 2.6.19-rc4-g4b1c46a3 (paolo@tux) (gcc version 4.1.1 (Gentoo 4.1.1)) #17 PREEMPT Wed Nov 1 18:36:28 CET 2006
> [ 0.000000] Command line: root=/dev/sda6 elevator=cfq video=radeonfb:1024x768@60
> [ 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: 00000000000e4000 - 0000000000100000 (reserved)
> [ 0.000000] BIOS-e820: 0000000000100000 - 000000001ff30000 (usable)
> [ 0.000000] BIOS-e820: 000000001ff30000 - 000000001ff40000 (ACPI data)
> [ 0.000000] BIOS-e820: 000000001ff40000 - 000000001fff0000 (ACPI NVS)
> [ 0.000000] BIOS-e820: 000000001fff0000 - 0000000020000000 (reserved)
> [ 0.000000] BIOS-e820: 00000000fff80000 - 0000000100000000 (reserved)
> [ 0.000000] Entering add_active_range(0, 0, 159) 0 entries of 256 used
> [ 0.000000] Entering add_active_range(0, 256, 130864) 1 entries of 256 used
> [ 0.000000] end_pfn_map = 1048576
> [ 0.000000] DMI 2.3 present.
> [ 0.000000] ACPI: RSDP (v000 ACPIAM ) @ 0x00000000000fa850
> [ 0.000000] ACPI: RSDT (v001 A M I OEMRSDT 0x06000517 MSFT 0x00000097) @ 0x000000001ff30000
> [ 0.000000] ACPI: FADT (v001 A M I OEMFACP 0x06000517 MSFT 0x00000097) @ 0x000000001ff30200
> [ 0.000000] ACPI: MADT (v001 A M I OEMAPIC 0x06000517 MSFT 0x00000097) @ 0x000000001ff30390
> [ 0.000000] ACPI: OEMB (v001 A M I OEMBIOS 0x06000517 MSFT 0x00000097) @ 0x000000001ff40040
> [ 0.000000] ACPI: DSDT (v001 A0058 A0058002 0x00000002 MSFT 0x0100000d) @ 0x0000000000000000
> [ 0.000000] Entering add_active_range(0, 0, 159) 0 entries of 256 used
> [ 0.000000] Entering add_active_range(0, 256, 130864) 1 entries of 256 used
> [ 0.000000] Zone PFN ranges:
> [ 0.000000] DMA 0 -> 4096
> [ 0.000000] DMA32 4096 -> 1048576
> [ 0.000000] Normal 1048576 -> 1048576
> [ 0.000000] early_node_map[2] active PFN ranges
> [ 0.000000] 0: 0 -> 159
> [ 0.000000] 0: 256 -> 130864
> [ 0.000000] On node 0 totalpages: 130767
> [ 0.000000] DMA zone: 56 pages used for memmap
> [ 0.000000] DMA zone: 1183 pages reserved
> [ 0.000000] DMA zone: 2760 pages, LIFO batch:0
> [ 0.000000] DMA32 zone: 1733 pages used for memmap
> [ 0.000000] DMA32 zone: 125035 pages, LIFO batch:31
> [ 0.000000] Normal zone: 0 pages used for memmap
> [ 0.000000] ACPI: PM-Timer IO Port: 0x808
> [ 0.000000] ACPI: Local APIC address 0xfee00000
> [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
> [ 0.000000] Processor #0 (Bootup-CPU)
> [ 0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
> [ 0.000000] IOAPIC[0]: apic_id 1, address 0xfec00000, GSI 0-23
> [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
> [ 0.000000] ACPI: IRQ0 used by override.
> [ 0.000000] ACPI: IRQ2 used by override.
> [ 0.000000] ACPI: IRQ9 used by override.
> [ 0.000000] Setting APIC routing to flat
> [ 0.000000] Using ACPI (MADT) for SMP configuration information
> [ 0.000000] Nosave address range: 000000000009f000 - 00000000000a0000
> [ 0.000000] Nosave address range: 00000000000a0000 - 00000000000e4000
> [ 0.000000] Nosave address range: 00000000000e4000 - 0000000000100000
> [ 0.000000] Allocating PCI resources starting at 30000000 (gap: 20000000:dff80000)
> [ 0.000000] Built 1 zonelists. Total pages: 127795
> [ 0.000000] Kernel command line: root=/dev/sda6 elevator=cfq video=radeonfb:1024x768@60
> [ 0.000000] Initializing CPU#0
> [ 0.000000] PID hash table entries: 2048 (order: 11, 16384 bytes)
> [ 32.727602] time.c: Using 3.579545 MHz WALL PM GTOD PIT/TSC timer.
> [ 32.727605] time.c: Detected 2202.943 MHz processor.
> [ 32.730265] Console: colour VGA+ 80x25
> [ 32.733073] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
> [ 32.733509] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
> [ 32.733646] Checking aperture...
> [ 32.733705] CPU 0: aperture @ f8000000 size 64 MB
> [ 32.740581] Memory: 507900k/523456k available (2708k kernel code, 14716k reserved, 1343k data, 200k init)
> [ 32.800438] Calibrating delay using timer specific routine.. 4409.08 BogoMIPS (lpj=2204542)
> [ 32.800591] Mount-cache hash table entries: 256
> [ 32.800771] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
> [ 32.800833] CPU: L2 Cache: 512K (64 bytes/line)
> [ 32.800913] CPU: AMD Athlon(tm) 64 Processor 3200+ stepping 00
> [ 32.801063] ACPI: Core revision 20060707
> [ 32.814145] Using local APIC timer interrupts.
> [ 32.859518] result 12516743
> [ 32.859573] Detected 12.516 MHz APIC timer.
> [ 32.860328] testing NMI watchdog ... OK.
> [ 32.870515] checking if image is initramfs...it isn't (bad gzip magic numbers); looks like an initrd
> [ 32.873517] Freeing initrd memory: 2000k freed
> [ 32.875609] NET: Registered protocol family 16
> [ 32.875754] ACPI: bus type pci registered
> [ 32.875818] PCI: Using configuration type 1
> [ 32.881215] ACPI: Interpreter enabled
> [ 32.881276] ACPI: Using IOAPIC for interrupt routing
> [ 32.882250] ACPI: PCI Root Bridge [PCI0] (0000:00)
> [ 32.882315] PCI: Probing PCI hardware (bus 00)
> [ 32.884489] PCI: enabled onboard AC97/MC97 devices
> [ 32.884765] Boot video device is 0000:01:00.0
> [ 32.884843] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
> [ 32.896169] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 7 10 *11 14 15)
> [ 32.896831] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 7 *10 11 14 15)
> [ 32.897488] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 *7 10 11 14 15)
> [ 32.898143] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 *5 7 10 11 14 15)
> [ 32.898809] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
> [ 32.899560] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
> [ 32.900312] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
> [ 32.901052] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
> [ 32.901700] Linux Plug and Play Support v0.97 (c) Adam Belay
> [ 32.901768] pnp: PnP ACPI init
> [ 32.904855] pnp: PnP ACPI: found 13 devices
> [ 32.905045] SCSI subsystem initialized
> [ 32.905158] usbcore: registered new interface driver usbfs
> [ 32.905247] usbcore: registered new interface driver hub
> [ 32.905335] usbcore: registered new device driver usb
> [ 32.905452] PCI: Using ACPI for IRQ routing
> [ 32.905511] PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
> [ 32.905592] PCI: Cannot allocate resource region 0 of device 0000:00:00.0
> [ 32.905754] agpgart: Detected AGP bridge 0
> [ 32.908913] agpgart: AGP aperture is 64M @ 0xf8000000
> [ 32.908997] PCI-DMA: Disabling IOMMU.
> [ 32.909754] PCI: Bridge: 0000:00:01.0
> [ 32.909812] IO window: a000-afff
> [ 32.909871] MEM window: fd100000-fd6fffff
> [ 32.909931] PREFETCH window: d5000000-f4ffffff
> [ 32.910006] PCI: Setting latency timer of device 0000:00:01.0 to 64
> [ 32.910026] NET: Registered protocol family 2
> [ 32.918232] IP route cache hash table entries: 4096 (order: 3, 32768 bytes)
> [ 32.918368] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
> [ 32.918516] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
> [ 32.918624] TCP: Hash tables configured (established 16384 bind 8192)
> [ 32.918684] TCP reno registered
> [ 32.919334] io scheduler noop registered
> [ 32.919440] io scheduler cfq registered (default)
> [ 32.920310] ACPI: PCI Interrupt 0000:01:00.0[A] -> GSI 16 (level, low) -> IRQ 16
> [ 32.920477] radeonfb: Found Intel x86 BIOS ROM Image
> [ 32.920539] radeonfb: Retrieved PLL infos from BIOS
> [ 32.920598] radeonfb: Reference=27.00 MHz (RefDiv=12) Memory=200.00 Mhz, System=166.00 MHz
> [ 32.920671] radeonfb: PLL min 20000 max 40000
> [ 32.921735] radeonfb: Monitor 1 type CRT found
> [ 32.921793] radeonfb: Monitor 2 type no found
> [ 32.955438] Console: switching to colour frame buffer device 128x48
> [ 32.975175] radeonfb (0000:01:00.0): ATI Radeon Yd
> [ 32.975352] ACPI: Power Button (FF) [PWRF]
> [ 32.975477] ACPI: Power Button (CM) [PWRB]
> [ 32.975586] ACPI: Sleep Button (CM) [SLPB]
> [ 32.977202] Real Time Clock Driver v1.12ac
> [ 32.977313] Linux agpgart interface v0.101 (c) Dave Jones
> [ 32.977457] [drm] Initialized drm 1.0.1 20051102
> [ 32.978126] [drm] Initialized radeon 1.25.0 20060524 on minor 0
> [ 32.978294] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
> [ 32.978587] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
> [ 32.978834] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
> [ 32.979259] 00:0a: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
> [ 32.979511] 00:0b: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
> [ 32.979773] Floppy drive(s): fd0 is 1.44M
> [ 32.994906] FDC 0 is a post-1991 82077
> [ 32.996382] RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
> [ 32.996766] loop: loaded (max 8 devices)
> [ 32.996906] ACPI: PCI Interrupt 0000:00:0a.0[A] -> GSI 17 (level, low) -> IRQ 17
> [ 32.999471] skge 1.9 addr 0xfdc00000 irq 17 chip Yukon-Lite rev 9
> [ 33.001981] skge eth0: addr 00:11:d8:1c:a0:7a
> [ 33.004520] 8139too Fast Ethernet driver 0.9.28
> [ 33.007047] ACPI: PCI Interrupt 0000:00:0e.0[A] -> GSI 19 (level, low) -> IRQ 19
> [ 33.010258] eth1: RealTek RTL8139 at 0xffffc20000004000, 00:e0:4c:f0:ab:b8, IRQ 19
> [ 33.013135] eth1: Identified 8139 chip type 'RTL-8139C'
> [ 33.013151] Linux video capture interface: v2.00
> [ 33.016118] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
> [ 33.019236] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
> [ 33.022583] VP_IDE: IDE controller at PCI slot 0000:00:0f.1
> [ 33.026030] ACPI: PCI Interrupt 0000:00:0f.1[A] -> GSI 20 (level, low) -> IRQ 20
> [ 33.029676] VP_IDE: chipset revision 6
> [ 33.033379] VP_IDE: not 100% native mode: will probe irqs later
> [ 33.037248] VP_IDE: VIA vt8237 (rev 00) IDE UDMA133 controller on pci0000:00:0f.1
> [ 33.041286] ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:DMA, hdb:pio
> [ 33.045502] ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:DMA, hdd:pio
> [ 33.049751] Probing IDE interface ide0...
> [ 33.841448] hda: HL-DT-ST DVDRAM GSA-4167B, ATAPI CD/DVD-ROM drive
> [ 34.152000] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
> [ 34.156490] Probing IDE interface ide1...
> [ 34.948226] hdc: HL-DT-ST GCE-8400B, ATAPI CD/DVD-ROM drive
> [ 35.257686] ide1 at 0x170-0x177,0x376 on irq 15
> [ 35.264947] hda: ATAPI 48X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache, UDMA(33)
> [ 35.269964] Uniform CD-ROM driver Revision: 3.20
> [ 35.283252] hdc: ATAPI 40X CD-ROM CD-R/RW drive, 2048kB Cache, DMA
> [ 35.289395] libata version 2.00 loaded.
> [ 35.289419] sata_via 0000:00:0f.0: version 2.0
> [ 35.289430] ACPI: PCI Interrupt 0000:00:0f.0[B] -> GSI 20 (level, low) -> IRQ 20
> [ 35.294798] sata_via 0000:00:0f.0: routed to hard irq line 10
> [ 35.300148] ata1: SATA max UDMA/133 cmd 0xE800 ctl 0xE402 bmdma 0xD400 irq 20
> [ 35.305530] ata2: SATA max UDMA/133 cmd 0xE000 ctl 0xD802 bmdma 0xD408 irq 20
> [ 35.310844] scsi0 : sata_via
> [ 35.515983] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> [ 35.673125] ata1.00: ATA-6, max UDMA/133, 156301488 sectors: LBA48 NCQ (depth 0/32)
> [ 35.678757] ata1.00: ata1: dev 0 multi count 16
> [ 35.686006] ata1.00: configured for UDMA/133
> [ 35.691568] scsi1 : sata_via
> [ 35.897218] ata2: SATA link down 1.5 Gbps (SStatus 0 SControl 300)
> [ 35.913718] ATA: abnormal status 0x7F on port 0xE007
> [ 35.919332] scsi 0:0:0:0: Direct-Access ATA ST380817AS 3.42 PQ: 0 ANSI: 5
> [ 35.925134] SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
> [ 35.930882] sda: Write Protect is off
> [ 35.936662] sda: Mode Sense: 00 3a 00 00
> [ 35.936675] SCSI device sda: drive cache: write back
> [ 35.942505] SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
> [ 35.948370] sda: Write Protect is off
> [ 35.954136] sda: Mode Sense: 00 3a 00 00
> [ 35.954148] SCSI device sda: drive cache: write back
> [ 35.959889] sda: sda1 sda2 < sda5 sda6 sda7 sda8 >
> [ 36.027032] sd 0:0:0:0: Attached scsi disk sda
> [ 36.032710] sd 0:0:0:0: Attached scsi generic sg0 type 0
> [ 36.038359] ACPI: PCI Interrupt 0000:00:10.4[C] -> GSI 21 (level, low) -> IRQ 21
> [ 36.044338] ehci_hcd 0000:00:10.4: EHCI Host Controller
> [ 36.050131] ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 1
> [ 36.055834] ehci_hcd 0000:00:10.4: irq 21, io mem 0xfd900000
> [ 36.061409] ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
> [ 36.067109] usb usb1: configuration #1 chosen from 1 choice
> [ 36.072686] hub 1-0:1.0: USB hub found
> [ 36.078141] hub 1-0:1.0: 8 ports detected
> [ 36.183732] USB Universal Host Controller Interface driver v3.0
> [ 36.189136] ACPI: PCI Interrupt 0000:00:10.0[A] -> GSI 21 (level, low) -> IRQ 21
> [ 36.194595] uhci_hcd 0000:00:10.0: UHCI Host Controller
> [ 36.200026] uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 2
> [ 36.205457] uhci_hcd 0000:00:10.0: irq 21, io base 0x0000b000
> [ 36.211000] usb usb2: configuration #1 chosen from 1 choice
> [ 36.216430] hub 2-0:1.0: USB hub found
> [ 36.221776] hub 2-0:1.0: 2 ports detected
> [ 36.327443] ACPI: PCI Interrupt 0000:00:10.1[A] -> GSI 21 (level, low) -> IRQ 21
> [ 36.332864] uhci_hcd 0000:00:10.1: UHCI Host Controller
> [ 36.338237] uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 3
> [ 36.343600] uhci_hcd 0000:00:10.1: irq 21, io base 0x0000b400
> [ 36.348985] usb usb3: configuration #1 chosen from 1 choice
> [ 36.354262] hub 3-0:1.0: USB hub found
> [ 36.359430] hub 3-0:1.0: 2 ports detected
> [ 36.465147] ACPI: PCI Interrupt 0000:00:10.2[B] -> GSI 21 (level, low) -> IRQ 21
> [ 36.470440] uhci_hcd 0000:00:10.2: UHCI Host Controller
> [ 36.475698] uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 4
> [ 36.480965] uhci_hcd 0000:00:10.2: irq 21, io base 0x0000b800
> [ 36.486227] usb usb4: configuration #1 chosen from 1 choice
> [ 36.491386] hub 4-0:1.0: USB hub found
> [ 36.496528] hub 4-0:1.0: 2 ports detected
> [ 36.601875] ACPI: PCI Interrupt 0000:00:10.3[B] -> GSI 21 (level, low) -> IRQ 21
> [ 36.607110] uhci_hcd 0000:00:10.3: UHCI Host Controller
> [ 36.612333] uhci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 5
> [ 36.617549] uhci_hcd 0000:00:10.3: irq 21, io base 0x0000c000
> [ 36.622729] usb usb5: configuration #1 chosen from 1 choice
> [ 36.627812] hub 5-0:1.0: USB hub found
> [ 36.632845] hub 5-0:1.0: 2 ports detected
> [ 37.161683] usbcore: registered new interface driver cdc_acm
> [ 37.166690] drivers/usb/class/cdc-acm.c: v0.25:USB Abstract Control Model driver for USB modems and ISDN adapters
> [ 37.171947] usbcore: registered new interface driver usblp
> [ 37.177212] drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
> [ 37.182561] Initializing USB Mass Storage driver...
> [ 37.187966] usbcore: registered new interface driver usb-storage
> [ 37.193343] USB Mass Storage support registered.
> [ 37.198710] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
> [ 37.204404] serio: i8042 KBD port at 0x60,0x64 irq 1
> [ 37.209839] serio: i8042 AUX port at 0x60,0x64 irq 12
> [ 37.215222] mice: PS/2 mouse device common for all mice
> [ 37.220524] i2c /dev entries driver
> [ 37.226206] Advanced Linux Sound Architecture Driver Version 1.0.13 (Sun Oct 22 08:56:16 2006 UTC).
> [ 37.231946] ACPI: PCI Interrupt 0000:00:11.5[C] -> GSI 22 (level, low) -> IRQ 22
> [ 37.239539] PCI: Setting latency timer of device 0000:00:11.5 to 64
> [ 37.251526] input: AT Translated Set 2 keyboard as /class/input/input0
> [ 37.751566] codec_read: codec 0 is not valid [0xfe0000]
> [ 37.764869] codec_read: codec 0 is not valid [0xfe0000]
> [ 37.778121] codec_read: codec 0 is not valid [0xfe0000]
> [ 37.791242] codec_read: codec 0 is not valid [0xfe0000]
> [ 37.808500] ALSA device list:
> [ 37.813631] #0: VIA 8237 with AD1980 at 0xec00, irq 22
> [ 37.818899] oprofile: using NMI interrupt.
> [ 37.824105] TCP cubic registered
> [ 37.829169] NET: Registered protocol family 1
> [ 37.834229] NET: Registered protocol family 17
> [ 37.839196] NET: Registered protocol family 15
> [ 37.844134] ACPI: (supports S0 S1 S3 S4 S5)
> [ 37.961630] input: ImPS/2 Logitech Wheel Mouse as /class/input/input1
> [ 38.050012] RAMDISK: ext2 filesystem found at block 0
> [ 38.054928] RAMDISK: Loading 2000KiB [1 disk] into ram disk... |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|done.
> [ 38.066647] VFS: Mounted root (ext2 filesystem).
> [ 38.129633] kjournald starting. Commit interval 5 seconds
> [ 38.134550] EXT3-fs: mounted filesystem with ordered data mode.
> [ 38.139493] VFS: Mounted root (ext3 filesystem) readonly.
> [ 38.144469] Trying to move old root to /initrd ... /initrd does not exist. Ignored.
> [ 38.149670] Unmounting old root
> [ 38.154701] Trying to free ramdisk memory ... okay
> [ 38.159878] Freeing unused kernel memory: 200k freed
> [ 38.164962] Write protecting the kernel read-only data: 560k
> [ 40.454619] warning: process `touch' used the removed sysctl system call
> [ 40.890041] warning: process `sleep' used the removed sysctl system call
> [ 40.999232] warning: process `sleep' used the removed sysctl system call
> [ 41.104730] warning: process `sleep' used the removed sysctl system call
> [ 41.873966] warning: process `sleep' used the removed sysctl system call
> [ 43.453372] EXT3 FS on sda6, internal journal
> [ 43.999154] kjournald starting. Commit interval 5 seconds
> [ 43.999164] EXT3-fs warning: maximal mount count reached, running e2fsck is recommended
> [ 43.999323] EXT3 FS on sda8, internal journal
> [ 43.999328] EXT3-fs: mounted filesystem with ordered data mode.
> [ 44.117933] Adding 1004016k swap on /dev/sda7. Priority:-1 extents:1 across:1004016k
> [ 50.175883] skge eth0: enabling interface
> [ 62.993341] agpgart: Found an AGP 3.5 compliant device at 0000:00:00.0.
> [ 62.993361] agpgart: Putting AGP V3 device at 0000:00:00.0 into 4x mode
> [ 62.993436] agpgart: Putting AGP V3 device at 0000:01:00.0 into 4x mode
> [ 63.288516] [drm] Setting GART location based on new memory map
> [ 63.288604] [drm] Loading R200 Microcode
> [ 63.288694] [drm] writeback test succeeded in 1 usecs
> [ 79.495845] eth1: link up, 100Mbps, full-duplex, lpa 0x45E1
> [ 81.127324] ip_tables: (C) 2000-2006 Netfilter Core Team
> [ 81.170715] ip_conntrack version 2.4 (2044 buckets, 16352 max) - 248 bytes per conntrack
> [ 180.334016] Installing knfsd (copyright (C) 1996 okir@xxxxxxxxxxxx).
> [ 306.826671] agpgart: Found an AGP 3.5 compliant device at 0000:00:00.0.
> [ 306.826693] agpgart: Putting AGP V3 device at 0000:00:00.0 into 4x mode
> [ 306.826768] agpgart: Putting AGP V3 device at 0000:01:00.0 into 4x mode
> [ 306.826780] [drm] Loading R200 Microcode
> [ 340.827814] Stopping tasks: ==========================================================================================================================================|
> [ 340.828658] Shrinking memory... -\|/-done (51711 pages freed)
> [ 340.925349] Suspending console(s)
> [ 341.809883] pnp: Device 00:0b disabled.
> [ 341.810124] pnp: Device 00:0a disabled.
> [ 341.810148] radeonfb (0000:01:00.0): suspending for event: 1...
> [ 341.887207] skge eth0: disabling interface
> [ 341.899219] pci_set_power_state(): 0000:00:00.0: state=3, current state=5
> [ 341.912910] swsusp: Need to copy 63368 pages
> [ 26.062737] APIC error on CPU0: 00(00)
> [ 26.062827] PCI: Setting latency timer of device 0000:00:01.0 to 64
> [ 26.146592] PM: Writing back config space on device 0000:00:0a.0 at offset f (was 1f170100, writing 1f17010a)
> [ 26.146599] PM: Writing back config space on device 0000:00:0a.0 at offset c (was 0, writing fdb00000)
> [ 26.146609] PM: Writing back config space on device 0000:00:0a.0 at offset 5 (was 1, writing c801)
> [ 26.146614] PM: Writing back config space on device 0000:00:0a.0 at offset 4 (was 0, writing fdc00000)
> [ 26.146619] PM: Writing back config space on device 0000:00:0a.0 at offset 3 (was 0, writing 4010)
> [ 26.146626] PM: Writing back config space on device 0000:00:0a.0 at offset 1 (was 2b00000, writing 2b00117)
> [ 26.146658] skge eth0: enabling interface
> [ 26.160202] eth1: link up, 100Mbps, full-duplex, lpa 0x45E1
> [ 26.171176] ACPI: PCI Interrupt 0000:00:10.0[A] -> GSI 21 (level, low) -> IRQ 21
> [ 26.171218] usb usb2: root hub lost power or was reset
> [ 26.182150] ACPI: PCI Interrupt 0000:00:10.1[A] -> GSI 21 (level, low) -> IRQ 21
> [ 26.182188] usb usb3: root hub lost power or was reset
> [ 26.193128] ACPI: PCI Interrupt 0000:00:10.2[B] -> GSI 21 (level, low) -> IRQ 21
> [ 26.193165] usb usb4: root hub lost power or was reset
> [ 26.204106] ACPI: PCI Interrupt 0000:00:10.3[B] -> GSI 21 (level, low) -> IRQ 21
> [ 26.204143] usb usb5: root hub lost power or was reset
> [ 26.215084] ACPI: PCI Interrupt 0000:00:10.4[C] -> GSI 21 (level, low) -> IRQ 21
> [ 26.215109] usb usb1: root hub lost power or was reset
> [ 26.215127] ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
> [ 26.226082] ACPI: PCI Interrupt 0000:00:11.5[C] -> GSI 22 (level, low) -> IRQ 22
> [ 26.226088] PCI: Setting latency timer of device 0000:00:11.5 to 64
> [ 26.229463] radeonfb (0000:01:00.0): resuming from state: 1...
> [ 26.247263] pnp: Failed to activate device 00:03.
> [ 26.247391] pnp: Failed to activate device 00:04.
> [ 26.248318] pnp: Device 00:0a activated.
> [ 26.249004] pnp: Device 00:0b activated.
> [ 27.134110] Restarting tasks... done
> [ 27.565554] agpgart: Found an AGP 3.5 compliant device at 0000:00:00.0.
> [ 27.565593] agpgart: Putting AGP V3 device at 0000:00:00.0 into 4x mode
> [ 27.565670] agpgart: Putting AGP V3 device at 0000:01:00.0 into 4x mode
> [ 27.565682] [drm] Loading R200 Microcode
> [ 28.443446] ip_conntrack version 2.4 (2044 buckets, 16352 max) - 248 bytes per conntrack
> [ 752.692523] Stopping tasks: ======================================================================================================================================|
> [ 752.693363] Shrinking memory... -\|/-\done (58183 pages freed)
> [ 756.669812] Suspending console(s)
> [ 757.578446] pnp: Device 00:0b disabled.
> [ 757.578702] pnp: Device 00:0a disabled.
> [ 757.578727] radeonfb (0000:01:00.0): suspending for event: 1...
> [ 757.655322] skge eth0: disabling interface
> [ 757.695225] swsusp: Need to copy 58533 pages
> [ 25.139916] APIC error on CPU0: 00(00)
> [ 25.293551] PCI: Setting latency timer of device 0000:00:01.0 to 64
> [ 25.377319] PM: Writing back config space on device 0000:00:0a.0 at offset f (was 1f170100, writing 1f17010a)
> [ 25.377326] PM: Writing back config space on device 0000:00:0a.0 at offset c (was 0, writing fdb00000)
> [ 25.377338] PM: Writing back config space on device 0000:00:0a.0 at offset 5 (was 1, writing c801)
> [ 25.377343] PM: Writing back config space on device 0000:00:0a.0 at offset 4 (was 0, writing fdc00000)
> [ 25.377348] PM: Writing back config space on device 0000:00:0a.0 at offset 3 (was 0, writing 4010)
> [ 25.377353] PM: Writing back config space on device 0000:00:0a.0 at offset 1 (was 2b00000, writing 2b00117)
> [ 25.377384] skge eth0: enabling interface
> [ 25.382084] BUG: scheduling while atomic: events/0/0x00000001/4
> [ 25.382086]
> [ 25.382087] Call Trace:
> [ 25.382097] [<ffffffff8049fafb>] __sched_text_start+0x5b/0x4cc
> [ 25.382102] [<ffffffff802f34b6>] list_add+0xc/0xe
> [ 25.382107] [<ffffffff80236519>] worker_thread+0x0/0x11b
> [ 25.382110] [<ffffffff802365ce>] worker_thread+0xb5/0x11b
> [ 25.382115] [<ffffffff802233e2>] default_wake_function+0x0/0xf
> [ 25.382119] [<ffffffff80236519>] worker_thread+0x0/0x11b
> [ 25.382124] [<ffffffff80239269>] kthread+0xce/0x101
> [ 25.382128] [<ffffffff802234b1>] schedule_tail+0x30/0xa2
> [ 25.382132] [<ffffffff8020a238>] child_rip+0xa/0x12
> [ 25.382137] [<ffffffff8023919b>] kthread+0x0/0x101
> [ 25.382140] [<ffffffff8020a22e>] child_rip+0x0/0x12
Apparently, the kernel thinks that worker_thread() is running in the atomic
context, so there may be a problem with preempt_count(), for example.
Is preemption enabled in your kernel(s)?
Greetings,
Rafael
--
You never change things by fighting the existing reality.
R. Buckminster Fuller
-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/