[Pkg-utopia-maintainers] Bug#320790: Hal won't halt on swsusp

john.lindgren at tds.net john.lindgren at tds.net
Tue Dec 30 04:18:06 UTC 2008


Hello,

I can reproduce this in fresh-installed Lenny on a Dell Inspiron 1200,
except that it's now hald-addon-stor that hangs, not hald itself.

Here are the kernel messages that are emitted when the process hangs
(hdb is the CD drive).  I don't know what causes the error, since I'm
sure I wasn't doing anything with the CD drive:

   [ 2441.864232] hdb: request sense failure: status=0x51 { DriveReady SeekComplete Error }
   [ 2441.864232] hdb: request sense failure: error=0x04 { AbortedCommand }

top shows that the process becomes uninterruptible:

   top - 22:45:46 up 51 min,  1 user,  load average: 1.21, 1.05, 0.81
   Tasks:  46 total,   1 running,  45 sleeping,   0 stopped,   0 zombie
   Cpu(s): 13.9%us,  2.0%sy,  0.0%ni, 78.9%id,  4.7%wa,  0.4%hi,  0.0%si,  0.0%st
   Mem:    507996k total,   178160k used,   329836k free,     2084k buffers
   Swap:   498004k total,    12708k used,   485296k free,   101168k cached

     PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM    TIME+  COMMAND            
...
    2081 root      20   0  3384  652  600 D  0.0  0.1   0:00.84 hald-addon-stor    

Here are the kernel messages during the (attempted) s2disk itself:

   [ 3043.280961] Syncing filesystems ... done.
   [ 3043.280961] Freezing user space processes ... 
   [ 3063.284012] Freezing of tasks failed after 20.00 seconds (1 tasks refusing to freeze):
   [ 3063.284016]   task                PC stack   pid father
...
   [ 3063.287695] hald-addon-st D e00abaa0     0  2081   2024
   [ 3063.287699]        ddbee6e0 00000082 0000000c e00abaa0 c01e1625 ddbee86c c13f9fa0 00000000 
   [ 3063.287706]        00000000 00082b68 e00911ca de41fb73 00000000 00000000 00000000 000000ff 
   [ 3063.287713]        7fffffff 7fffffff de41fbe8 00000002 c02b84b1 e00abaa0 00000000 00000086 
   [ 3063.287720] Call Trace:
   [ 3063.287724]  [<c01e1625>] __delay+0x6/0x7
   [ 3063.287733]  [<e00911ca>] cdrom_do_newpc_cont+0x0/0x2c [ide_cd_mod]
   [ 3063.287747]  [<c02b84b1>] schedule_timeout+0x13/0x86
   [ 3063.287754]  [<c02b84b1>] schedule_timeout+0x13/0x86
   [ 3063.287763]  [<c02b7bd5>] wait_for_common+0xaf/0x10f
   [ 3063.287770]  [<c011b682>] default_wake_function+0x0/0x8
   [ 3063.287779]  [<e0099f42>] ide_do_drive_cmd+0xd6/0xf2 [ide_core]
   [ 3063.287807]  [<e008fc65>] ide_cd_queue_pc+0x35/0xb4 [ide_cd_mod]
   [ 3063.287818]  [<e008fc65>] ide_cd_queue_pc+0x35/0xb4 [ide_cd_mod]
   [ 3063.287825]  [<e008fc65>] ide_cd_queue_pc+0x35/0xb4 [ide_cd_mod]
   [ 3063.287834]  [<e008fc65>] ide_cd_queue_pc+0x35/0xb4 [ide_cd_mod]
   [ 3063.287847]  [<e008ff44>] cdrom_check_status+0x5a/0x65 [ide_cd_mod]
   [ 3063.287880]  [<c01d4d5b>] blk_end_sync_rq+0x0/0x25
   [ 3063.287888]  [<e009195b>] ide_cdrom_drive_status+0x1d/0x8f [ide_cd_mod]
   [ 3063.287900]  [<e0090046>] ide_cd_read_toc+0xf7/0x3b2 [ide_cd_mod]
   [ 3063.287913]  [<e0088bbf>] cdrom_ioctl+0x25/0xb0e [cdrom]
   [ 3063.287923]  [<e00891cf>] cdrom_ioctl+0x635/0xb0e [cdrom]
   [ 3063.287933]  [<e009084b>] idecd_revalidate_disk+0x10/0x16 [ide_cd_mod]
   [ 3063.287945]  [<c01760d3>] get_super+0x15/0x7a
   [ 3063.287953]  [<e008f472>] idecd_ioctl+0x12f/0x144 [ide_cd_mod]
   [ 3063.287963]  [<e0089f2a>] cdrom_open+0x882/0x8f8 [cdrom]
   [ 3063.287971]  [<c017a4d8>] do_lookup+0x53/0x153
   [ 3063.287978]  [<c0182592>] dput+0x15/0xbb
   [ 3063.287982]  [<c017c408>] __link_path_walk+0x9ff/0xb0b
   [ 3063.287990]  [<c01d593b>] blkdev_driver_ioctl+0x4b/0x5b
   [ 3063.287998]  [<c01d5f64>] blkdev_ioctl+0x619/0x669
   [ 3063.288015]  [<e008f4f1>] idecd_open+0x44/0x6b [ide_cd_mod]
   [ 3063.288024]  [<c019478b>] do_open+0x205/0x28f
   [ 3063.288034]  [<c0193d4f>] bd_claim+0x10/0x56
   [ 3063.288039]  [<c01949ca>] blkdev_open+0x3a/0x4d
   [ 3063.288045]  [<c0172dca>] __dentry_open+0x130/0x1fc
   [ 3063.288052]  [<c0172eb2>] nameidata_to_filp+0x1c/0x2c
   [ 3063.288059]  [<c017d5ae>] do_filp_open+0x34f/0x684
   [ 3063.288068]  [<e0087322>] cdrom_release+0x179/0x1ae [cdrom]
   [ 3063.288076]  [<c013604c>] getnstimeofday+0x37/0xbc
   [ 3063.288088]  [<c0193fde>] block_ioctl+0x13/0x16
   [ 3063.288093]  [<c0193fcb>] block_ioctl+0x0/0x16
   [ 3063.288098]  [<c017e3f0>] vfs_ioctl+0x1c/0x5d
   [ 3063.288104]  [<c017e67b>] do_vfs_ioctl+0x24a/0x261
   [ 3063.288109]  [<c0172c2c>] do_sys_open+0xa8/0xb0
   [ 3063.288115]  [<c017e6d3>] sys_ioctl+0x41/0x5a
   [ 3063.288122]  [<c0103853>] sysenter_past_esp+0x78/0xb1
   [ 3063.288131]  [<c02b0000>] acpi_pci_root_add+0x165/0x296
   [ 3063.288139]  =======================
...
   [ 3063.289890] Sched Debug Version: v0.07, 2.6.26-1-686 #1
   [ 3063.289894] now at 3063289.889057 msecs
   [ 3063.289897]   .sysctl_sched_latency                    : 20.000000
   [ 3063.289900]   .sysctl_sched_min_granularity            : 4.000000
   [ 3063.289903]   .sysctl_sched_wakeup_granularity         : 10.000000
   [ 3063.289906]   .sysctl_sched_child_runs_first           : 0.000001
   [ 3063.289909]   .sysctl_sched_features                   : 895
   [ 3063.289912] 
   [ 3063.289913] cpu#0, 1396.568 MHz
   [ 3063.289915]   .nr_running                    : 1
   [ 3063.289918]   .load                          : 1024
   [ 3063.289920]   .nr_switches                   : 2808101
   [ 3063.289923]   .nr_load_updates               : 408883
   [ 3063.289925]   .nr_uninterruptible            : 28
   [ 3063.289928]   .jiffies                       : 690822
   [ 3063.289931]   .next_balance                  : 0.690866
   [ 3063.289934]   .curr->pid                     : 2687
   [ 3063.289937]   .clock                         : 3063288.006441
   [ 3063.289939]   .cpu_load[0]                   : 1024
   [ 3063.289942]   .cpu_load[1]                   : 1024
   [ 3063.289944]   .cpu_load[2]                   : 1024
   [ 3063.289946]   .cpu_load[3]                   : 1024
   [ 3063.289949]   .cpu_load[4]                   : 1024
   [ 3063.289952] 
   [ 3063.289953] cfs_rq[0]:
   [ 3063.289955]   .exec_clock                    : 0.000000
   [ 3063.289958]   .MIN_vruntime                  : 0.000001
   [ 3063.289961]   .min_vruntime                  : 334217.305879
   [ 3063.289964]   .max_vruntime                  : 0.000001
   [ 3063.289966]   .spread                        : 0.000000
   [ 3063.289969]   .spread0                       : 0.000000
   [ 3063.289971]   .nr_running                    : 1
   [ 3063.289974]   .load                          : 1024
   [ 3063.289976]   .nr_spread_over                : 0
   [ 3063.289980] 
   [ 3063.289981] runnable tasks:
   [ 3063.289982]             task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
   [ 3063.289985] ----------------------------------------------------------------------------------------------------------
   [ 3063.289992] R         s2disk  2687    334221.303950        70   120               0               0               0.000000               0.000000               0.000000 /
   [ 3063.290002] 
   [ 3063.290007]  hald-addon-stor
   [ 3063.290011] 
   [ 3063.290013] Restarting tasks ... done.

hald-addon-stor also blocks ACPI suspend and fails to terminate during shutdown.

This is also a known problem in Ubuntu:

   https://bugs.launchpad.net/ubuntu/+source/hal/+bug/62239

-- John Lindgren





More information about the Pkg-utopia-maintainers mailing list