[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

Bug#409011: marked as done (When booting: 38 sec. delay at "Attempting manual resume")

Your message dated Thu, 18 Dec 2008 21:54:40 +0100
with message-id <20081218205439.GA4274@galadriel.inutil.org>
and subject line Re: When booting: 38 sec. delay at "Attempting manual resume"
has caused the Debian Bug report #409011,
regarding When booting: 38 sec. delay at "Attempting manual resume"
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact owner@bugs.debian.org
immediately.)


-- 
409011: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=409011
Debian Bug Tracking System
Contact owner@bugs.debian.org with problems
--- Begin Message --- 
Package: linux-image-2.6.17-2-486
Version: 2.6.17-9_i386

Please merge my bug report 365266 into this as they are at least
partially the same bug.  When I submitted the 365266 bug report I
thought that the delay in booting was due to probing ide0 which
is broken on my PC.  But now it doesn't seem to probe ide0 anymore but
the delay still exists.

Here's from dmesg at the point of delay.  Note that I've put "debug" and
"time" on the kernel command line (using lilo).

[   46.324557] hdd: cache flushes not supported
[   46.325131]  hdd: hdd1 hdd2 hdd3
[   84.007425] Attempting manual resume
[   84.353163] kjournald starting.  Commit interval 5 seconds

What is going on?  "resume" must mean resume from a suspend, but there
is no suspend in dmesg.  The "Attempting manual resume" message is
found in /linux-2.6.x/kernel/power/disk.c in the function
resume_store().  I don't have source code on my PC but found it on the
Internet at:
http://www.gelato.unsw.edu.au/lxr/source/kernel/power/disk.c#L12

According to this site, the function name that issues this message
(resume_store) only appears in the definition of this function,  In
other words the function is defined but never used (if this site
indexing is correct).

cat /sys/power/resume results in 22:67 which is hdd3, the swap
partition on my "backup" drive.  Apparently the image being restored
comes from there and it happens just after hdd3 prints in the dmesg
output.  Yet I don't see S1SUSPEND on hdd3 which is the SWSUSP_SIG
(sinature).

There is a document, swsusp, in the power directory of the kernel
documentation and it says that it's not good to use suspend if the ide
driver is a module (as it is with this package).  But is suspend being
used at all? 
			David Lawyer
-----------------------------------------------------------------------
Here's the output of dmesg for my Pentium I PC.  There are several
other places where there is excessive delay but those will be the
subject of future bug reports.

Linux version 2.6.17-2-486 (Debian 2.6.17-9) (waldi@debian.org) (gcc version 4.1.2 20060901 (prerelease) (Debian 4.1.1-13)) #1 Wed Sep 13 15:56:30 UTC 2006
BIOS-provided physical RAM map:
 BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
 BIOS-e820: 0000000000100000 - 0000000003000000 (usable)
 BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserved)
48MB LOWMEM available.
On node 0 totalpages: 12288
  DMA zone: 4096 pages, LIFO batch:0
  Normal zone: 8192 pages, LIFO batch:1
DMI not present or invalid.
ACPI: Unable to locate RSDP
Allocating PCI resources starting at 10000000 (gap: 03000000:fcfc0000)
Built 1 zonelists
Kernel command line: auto BOOT_IMAGE=Linux2.6.17hdc ro plip=timid root=/dev/hdc2 ide0=noprobe hda=none hdb=none acpi=none debug time
[17179569.184000] No local APIC present or hardware disabled
[17179569.184000] mapped APIC to ffffd000 (01061000)
[17179569.184000] Initializing CPU#0
[17179569.184000] PID hash table entries: 256 (order: 8, 1024 bytes)
[    0.000000] Detected 99.730 MHz processor.
[   26.819180] Using tsc for high-res timesource
[   26.820993] Console: colour VGA+ 80x25
[   26.823420] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[   26.824536] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[   26.850290] Memory: 41704k/49152k available (1456k kernel code, 7040k reserved, 566k data, 252k init, 0k highmem)
[   26.850547] Checking if this processor honours the WP bit even in supervisor mode... Ok.
[   26.930830] Calibrating delay using timer specific routine.. 200.13 BogoMIPS (lpj=400265)
[   26.931668] Security Framework v1.0.0 initialized
[   26.931853] SELinux:  Disabled at boot.
[   26.931999] Capability LSM initialized
[   26.932366] Mount-cache hash table entries: 512
[   26.934600] CPU: After generic identify, caps: 000001bf 00000000 00000000 00000000 00000000 00000000 00000000
[   26.935222] CPU: After vendor identify, caps: 000001bf 00000000 00000000 00000000 00000000 00000000 00000000
[   26.935758] Intel Pentium with F0 0F bug - workaround enabled.
[   26.935894] 
[   26.935994] CPU: After all inits, caps: 000001bf 00000000 00000000 00000000 00000000 00000000 00000000
[   26.936690] CPU: Intel Pentium 75 - 200 stepping 06
[   26.936960] Checking 'hlt' instruction... OK.
[   26.951054] SMP alternatives: switching to UP code
[   26.951195] Freeing SMP alternatives: 0k freed
[   26.954201] checking if image is initramfs... it is
[   37.801654] Freeing initrd memory: 4070k freed
[   37.807719] NET: Registered protocol family 16
[   37.808617] EISA bus registered
[   37.814229] PCI: PCI BIOS revision 2.10 entry at 0xfd121, last bus=0
[   37.814379] Setting up standard PCI resources
[   37.830991] ACPI: Subsystem revision 20060127
[   37.831135] ACPI: Interpreter disabled.
[   37.831264] Linux Plug and Play Support v0.97 (c) Adam Belay
[   37.831695] pnp: PnP ACPI: disabled
[   37.831831] PnPBIOS: Scanning system for PnP BIOS support...
[   37.832702] PnPBIOS: Found PnP BIOS installation structure at 0xb00fa660
[   37.832875] PnPBIOS: PnP BIOS version 1.0, entry 0xf0000:0xa760, dseg 0x400
[   37.850931] PnPBIOS: 15 nodes reported by PnP BIOS; 15 recorded by driver
[   37.852220] PCI: Probing PCI hardware
[   37.852372] PCI: Probing PCI hardware (bus 00)
[   37.859618] Boot video device is 0000:00:0e.0
[   37.884090] NET: Registered protocol family 2
[   37.922035] IP route cache hash table entries: 512 (order: -1, 2048 bytes)
[   37.923983] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[   37.924337] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[   37.924578] TCP: Hash tables configured (established 2048 bind 1024)
[   37.924718] TCP reno registered
[   37.941404] audit: initializing netlink socket (disabled)
[   37.941701] audit(1170085219.312:1): initialized
[   37.944073] VFS: Disk quotas dquot_6.5.1
[   37.944549] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[   37.946062] Initializing Cryptographic API
[   37.946244] io scheduler noop registered
[   37.946486] io scheduler anticipatory registered (default)
[   37.946774] io scheduler deadline registered
[   37.947116] io scheduler cfq registered
[   37.947293] Limiting direct PCI/PCI transfers.
[   37.951068] isapnp: Scanning for PnP cards...
[   38.307172] isapnp: No Plug & Play device found
[   39.083219] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[   39.084588] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[   39.087051] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[   39.089517] serial8250: ttyS2 at I/O 0x3e8 (irq = 4) is a 16550A
[   39.110636] RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize
[   39.115203] PNP: PS/2 Controller [PNP0303,PNP0f13] at 0x60,0x64 irq 1,12
[   39.119189] serio: i8042 AUX port at 0x60,0x64 irq 12
[   39.119463] serio: i8042 KBD port at 0x60,0x64 irq 1
[   39.124732] EISA: Probing bus 0 at eisa.0
[   39.125172] EISA: Detected 0 cards.
[   39.127005] TCP bic registered
[   39.127227] NET: Registered protocol family 1
[   39.127422] NET: Registered protocol family 17
[   39.127590] NET: Registered protocol family 8
[   39.127718] NET: Registered protocol family 20
[   39.128244] Using IPI Shortcut mode
[   39.134430] Freeing unused kernel memory: 252k freed
[   39.167863] input: AT Translated Set 2 keyboard as /class/input/input0
[   45.174927] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[   45.175144] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[   45.194798] PIIXa: IDE controller at PCI slot 0000:00:07.0
[   45.195139] PIIXa: chipset revision 2
[   45.195259] PIIXa: bad irq (0): will probe later
[   45.195428] PIIXa: neither IDE port enabled (BIOS)
[   45.195641] PIIXb: IDE controller at PCI slot 0000:00:07.1
[   45.195955] PIIXb: chipset revision 2
[   45.196170] PIIXb: not 100% native mode: will probe irqs later
[   45.196420]     ide1: BM-DMA at 0xffa8-0xffaf, BIOS settings: hdc:pio, hdd:pio
[   45.196774] Probing IDE interface ide1...
[   45.612268] hdc: QUANTUM FIREBALL EX3.2A, ATA DISK drive
[   45.892147] hdd: Maxtor 90340D2, ATA DISK drive
[   45.954343] ide1 at 0x170-0x177,0x376 on irq 15
[   46.240918] hdc: max request size: 128KiB
[   46.276112] hdc: 6306048 sectors (3228 MB) w/418KiB Cache, CHS=6256/16/63, (U)DMA
[   46.276509] hdc: cache flushes not supported
[   46.277209]  hdc: hdc1 hdc2 hdc3
[   46.315997] hdd: max request size: 128KiB
[   46.324191] hdd: 6640704 sectors (3400 MB) w/512KiB Cache, CHS=6588/16/63, (U)DMA
[   46.324557] hdd: cache flushes not supported
[   46.325131]  hdd: hdd1 hdd2 hdd3
[   84.007425] Attempting manual resume
[   84.353163] kjournald starting.  Commit interval 5 seconds
[   84.353480] EXT3-fs: mounted filesystem with ordered data mode.
[  120.806555] input: PC Speaker as /class/input/input1
[  120.907224] Real Time Clock Driver v1.12ac
[  120.986779] 0000:00:0d.0: ttyS3 at I/O 0xfff0 (irq = 9) is a 16550A
[  123.716782] Floppy drive(s): fd0 is 1.44M
[  123.733931] FDC 0 is a National Semiconductor PC87306
[  128.702396] parport: PnPBIOS parport detected.
[  128.702678] parport0: PC-style at 0x378, irq 7 [PCSPP]
[  130.025170] 00:0c: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[  130.028429] 00:0d: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[  132.324720] input: PS/2 Generic Mouse as /class/input/input2
[  152.804578] ts: Compaq touchscreen protocol output
[  154.989975] mice: PS/2 mouse device common for all mice
[  163.812274] Adding 40308k swap on /dev/hdc3.  Priority:-1 extents:1 across:40308k
[  163.818910] Adding 45352k swap on /dev/hdd3.  Priority:-2 extents:1 across:45352k
[  164.401039] EXT3 FS on hdc2, internal journal
[  169.193658] lp0: using parport0 (interrupt-driven).
[  173.318028] kjournald starting.  Commit interval 5 seconds
[  173.318769] EXT3 FS on hdc1, internal journal
[  173.318974] EXT3-fs: mounted filesystem with ordered data mode.
[  191.150038] NET: Registered protocol family 10
[  191.151808] lo: Disabled Privacy Extensions
[  191.153596] IPv6 over IPv4 tunneling driver
[  372.796857] kjournald starting.  Commit interval 5 seconds
[  372.801267] EXT3 FS on hdd2, internal journal
[  372.801582] EXT3-fs: mounted filesystem with ordered data mode.
[ 1225.912795] CSLIP: code copyright 1989 Regents of the University of California
[ 1225.964089] PPP generic driver version 2.4.2
[ 1228.354340] PPP BSD Compression module registered
[ 1229.488708] PPP Deflate Compression module registered

--- End Message ---
--- Begin Message --- 
On Wed, Dec 17, 2008 at 09:00:44PM +0100, Moritz Muehlenhoff wrote:
> On Mon, Jan 29, 2007 at 04:16:24PM -0800, David Lawyer wrote:
> > Package: linux-image-2.6.17-2-486
> > Version: 2.6.17-9_i386
> > 
> > Please merge my bug report 365266 into this as they are at least
> > partially the same bug.  When I submitted the 365266 bug report I
> > thought that the delay in booting was due to probing ide0 which
> > is broken on my PC.  But now it doesn't seem to probe ide0 anymore but
> > the delay still exists.
> > 
> > Here's from dmesg at the point of delay.  Note that I've put "debug" and
> > "time" on the kernel command line (using lilo).
> > 
> > [   46.324557] hdd: cache flushes not supported
> > [   46.325131]  hdd: hdd1 hdd2 hdd3
> > [   84.007425] Attempting manual resume
> > [   84.353163] kjournald starting.  Commit interval 5 seconds
> > 
> > What is going on?  "resume" must mean resume from a suspend, but there
> > is no suspend in dmesg.  The "Attempting manual resume" message is
> > found in /linux-2.6.x/kernel/power/disk.c in the function
> > resume_store().  I don't have source code on my PC but found it on the
> > Internet at:
> > http://www.gelato.unsw.edu.au/lxr/source/kernel/power/disk.c#L12
> > 
> > According to this site, the function name that issues this message
> > (resume_store) only appears in the definition of this function,  In
> > other words the function is defined but never used (if this site
> > indexing is correct).
> > 
> > cat /sys/power/resume results in 22:67 which is hdd3, the swap
> > partition on my "backup" drive.  Apparently the image being restored
> > comes from there and it happens just after hdd3 prints in the dmesg
> > output.  Yet I don't see S1SUSPEND on hdd3 which is the SWSUSP_SIG
> > (sinature).
> > 
> > There is a document, swsusp, in the power directory of the kernel
> > documentation and it says that it's not good to use suspend if the ide
> > driver is a module (as it is with this package).  But is suspend being
> > used at all? 
> > 			David Lawyer
> 
> Does this error still occur with more recent kernel versions?

David confirmed in off-bug communication that this bug has been fixed
since about a year.

Cheers,
       Moritz

--- End Message ---
Reply to: