2.6.25 hald trace?

2.6.25 hald trace?

[Udo van den Heuvel]

Hello,

I found multiple sets of the type off logging that is below this text.
This is on my VIA Epia EN12000 running Fedora 8 and a custom 2.6.25 kernel.

What is the trace good for?
hald is polling an empty CD/DVD drive.
Is this a bug? Or a feature? Normal?
Is hald the cause?
Or something besides hald?
How can I fix it?

Please let us know.

Thanks,
Udo

May  7 00:28:40 recorder kernel: hda: ide_cd_check_ireason: wrong
transfer direction!
May  7 00:37:39 recorder kernel: INFO: task hald-addon-stor:2285 blocked
for more than 120 seconds.
May  7 00:37:39 recorder kernel: "echo 0 >
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
May  7 00:37:39 recorder kernel: hald-addon-st D f721c680     0  2285   2229
May  7 00:37:39 recorder kernel:        f7363b94 00000082 f4f5ae4c
f721c680 c0254cd3 c02563fe 7fffffff 7fffffff
May  7 00:37:39 recorder kernel:        f7363bf0 f7363bfc c02fd43c
c024c42e ffffffff f7025cc0 c044e8e0 00000000
May  7 00:37:39 recorder kernel:        00000086 ffffffff f7025cc0
00000286 00000000 00000000 00000000 f7363c08
May  7 00:37:39 recorder kernel: Call Trace:
May  7 00:37:39 recorder kernel:  [<c0254cd3>]
cdrom_start_packet_command+0xf0/0xf7
May  7 00:37:39 recorder kernel:  [<c02563fe>] cdrom_do_newpc_cont+0x0/0x2c
May  7 00:37:39 recorder kernel:  [<c02fd43c>] schedule_timeout+0x13/0x84
May  7 00:37:39 recorder kernel:  [<c024c42e>] ide_do_request+0x5c4/0x800
May  7 00:37:39 recorder kernel:  [<c02fd2c4>] wait_for_common+0xd4/0x144
May  7 00:37:39 recorder kernel:  [<c0113ac0>] default_wake_function+0x0/0xc
May  7 00:37:39 recorder kernel:  [<c024c81c>] ide_do_drive_cmd+0xcb/0xe7
May  7 00:37:39 recorder kernel:  [<c025547f>] ide_cd_queue_pc+0x35/0xa6
May  7 00:37:39 recorder kernel:  [<c02556fe>] cdrom_check_status+0x55/0x60
May  7 00:37:39 recorder kernel:  [<c01d6b56>] blk_end_sync_rq+0x0/0x26
May  7 00:37:39 recorder kernel:  [<c0257134>]
ide_cdrom_drive_status+0x1d/0x8f
May  7 00:37:39 recorder kernel:  [<c02fd2c4>] wait_for_common+0xd4/0x144
May  7 00:37:39 recorder kernel:  [<c0277116>] cdrom_ioctl+0x25/0xb03
May  7 00:37:39 recorder kernel:  [<c027771b>] cdrom_ioctl+0x62a/0xb03
May  7 00:37:39 recorder kernel:  [<c024c82e>] ide_do_drive_cmd+0xdd/0xe7
May  7 00:37:39 recorder kernel:  [<c025547f>] ide_cd_queue_pc+0x35/0xa6
May  7 00:37:39 recorder kernel:  [<c0254b1c>] idecd_ioctl+0x12f/0x144
May  7 00:37:39 recorder kernel:  [<c015fc01>] __d_lookup+0x10b/0x115
May  7 00:37:39 recorder kernel:  [<c015746a>] do_lookup+0x53/0x145
May  7 00:37:39 recorder kernel:  [<c01d76b7>] blkdev_driver_ioctl+0x4b/0x5b
May  7 00:37:39 recorder kernel:  [<c01d7d0c>] blkdev_ioctl+0x645/0x695
May  7 00:37:39 recorder kernel:  [<c01dbed8>] kobject_get+0xf/0x13
May  7 00:37:39 recorder kernel:  [<c01d7f5e>] get_disk+0x50/0x67
May  7 00:37:39 recorder kernel:  [<c01d7f7c>] exact_lock+0x7/0xd
May  7 00:37:39 recorder kernel:  [<c0234243>] kobj_lookup+0x103/0x12d
May  7 00:37:39 recorder kernel:  [<c0254bcf>] idecd_open+0x78/0x8c
May  7 00:37:39 recorder kernel:  [<c0170778>] do_open+0x1c8/0x247
May  7 00:37:39 recorder kernel:  [<c01709ca>] blkdev_open+0x3a/0x4d
May  7 00:37:39 recorder kernel:  [<c014fb35>] __dentry_open+0x10a/0x196
May  7 00:37:39 recorder kernel:  [<c014fbdd>] nameidata_to_filp+0x1c/0x2c
May  7 00:37:39 recorder kernel:  [<c014fc18>] do_filp_open+0x2b/0x31
May  7 00:37:39 recorder kernel:  [<c017007a>] block_ioctl+0x10/0x13
May  7 00:37:39 recorder kernel:  [<c017006a>] block_ioctl+0x0/0x13
May  7 00:37:39 recorder kernel:  [<c015b110>] vfs_ioctl+0x1c/0x5d
May  7 00:37:39 recorder kernel:  [<c015b38a>] do_vfs_ioctl+0x239/0x24c
May  7 00:37:39 recorder kernel:  [<c014fccb>] do_sys_open+0xad/0xb5
May  7 00:37:39 recorder kernel:  [<c015b3de>] sys_ioctl+0x41/0x5b
May  7 00:37:39 recorder kernel:  [<c0102996>] sysenter_past_esp+0x5f/0x89
May  7 00:37:39 recorder kernel:  =======================

Re: 2.6.25 hald trace?

[Udo van den Heuvel]

Hello,

Udo van den Heuvel wrote:
> I found multiple sets of the type off logging that is below this text.
> This is on my VIA Epia EN12000 running Fedora 8 and a custom 2.6.25 kernel.

2.6.26 now..

> What is the trace good for?
> hald is polling an empty CD/DVD drive.
> Is this a bug? Or a feature? Normal?
> Is hald the cause?
> Or something besides hald?
> How can I fix it?
> 
> Please let us know.

Aug 14 19:11:01 recorder kernel: INFO: task hald-addon-stor:1522 blocked
for more than 120 seconds.
Aug 14 19:11:01 recorder kernel: "echo 0 >
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 14 19:11:01 recorder kernel: hald-addon-st D c04581d8     0  1522   1475
Aug 14 19:11:01 recorder kernel:       ef83bbb4 00000086 0000000c
c04581d8 00000246 f6df6e40 f6df6fa8 f6df6fa8
Aug 14 19:11:01 recorder kernel:       00000004 c025c709 ef83bc2c
7fffffff ef83bc14 ef83bc20 c0306dd6 ef83bca4
Aug 14 19:11:01 recorder kernel:       c0253f15 ffffffff f7b5bb40
c04581d8 00000000 00000096 ffffffff f7b5bb40
Aug 14 19:11:01 recorder kernel: Call Trace:
Aug 14 19:11:01 recorder kernel: [<c025c709>]
cdrom_start_packet_command+0xb6/0xbd
Aug 14 19:11:01 recorder kernel: [<c0306dd6>] schedule_timeout+0x14/0xaf
Aug 14 19:11:01 recorder kernel: [<c0253f15>] ide_do_request+0x605/0x813
Aug 14 19:11:01 recorder kernel: [<c0306cdb>] wait_for_common+0xb6/0x11d
Aug 14 19:11:01 recorder kernel: [<c011444b>] default_wake_function+0x0/0xc
Aug 14 19:11:01 recorder kernel: [<c02541ec>] ide_do_drive_cmd+0xc9/0xe5
Aug 14 19:11:01 recorder kernel: [<c025cd1c>] ide_cd_queue_pc+0x35/0xa1
Aug 14 19:11:01 recorder kernel: [<c025cd1c>] ide_cd_queue_pc+0x35/0xa1
Aug 14 19:11:01 recorder kernel: [<c025cd1c>] ide_cd_queue_pc+0x35/0xa1
Aug 14 19:11:01 recorder kernel: [<c0306cdb>] wait_for_common+0xb6/0x11d
Aug 14 19:11:01 recorder kernel: [<c01db796>] __blk_put_request+0x1d/0x70
Aug 14 19:11:01 recorder kernel: [<c025dafe>] cdrom_read_tocentry+0xbf/0xca
Aug 14 19:11:01 recorder kernel: [<c01dddd1>] blk_end_sync_rq+0x0/0x23
Aug 14 19:11:01 recorder kernel: [<c025dc6b>] ide_cd_read_toc+0xf1/0x3f8
Aug 14 19:11:01 recorder kernel: [<c025e4fc>]
idecd_revalidate_disk+0x10/0x18
Aug 14 19:11:01 recorder kernel: [<c01754b2>] __invalidate_device+0x2e/0x34
Aug 14 19:11:01 recorder kernel: [<c01754f1>] check_disk_change+0x39/0x5c
Aug 14 19:11:01 recorder kernel: [<c0280718>] cdrom_open+0x879/0x8c9
Aug 14 19:11:01 recorder kernel: [<c015adeb>] do_lookup+0x53/0x145
Aug 14 19:11:01 recorder kernel: [<c01e353f>] kobject_get+0xf/0x13
Aug 14 19:11:01 recorder kernel: [<c01df7be>] get_disk+0x53/0x6a
Aug 14 19:11:01 recorder kernel: [<c01df7dc>] exact_lock+0x7/0xd
Aug 14 19:11:01 recorder kernel: [<c023cfac>] kobj_lookup+0x108/0x134
Aug 14 19:11:01 recorder kernel: [<c025c63f>] idecd_open+0x4f/0x63
Aug 14 19:11:01 recorder kernel: [<c0175c20>] do_open+0x84/0x23c
Aug 14 19:11:01 recorder kernel: [<c0175dd8>] blkdev_open+0x0/0x4d
Aug 14 19:11:01 recorder kernel: [<c0175dfd>] blkdev_open+0x25/0x4d
Aug 14 19:11:01 recorder kernel: [<c01533c6>] __dentry_open+0x129/0x211
Aug 14 19:11:01 recorder kernel: [<c015352b>] nameidata_to_filp+0x1c/0x2c
Aug 14 19:11:01 recorder kernel: [<c015dee4>] do_filp_open+0x319/0x6d6
Aug 14 19:11:01 recorder kernel: [<c012a6e7>] ktime_get+0xf/0x2b
Aug 14 19:11:01 recorder kernel: [<c027d682>] cdrom_release+0x179/0x1ae
Aug 14 19:11:01 recorder kernel: [<c0113356>] hrtick_start_fair+0xd0/0x11a
Aug 14 19:11:01 recorder kernel: [<c012bf6a>] getnstimeofday+0x32/0xb8
Aug 14 19:11:01 recorder kernel: [<c0153116>] get_unused_fd_flags+0x5e/0xc9
Aug 14 19:11:01 recorder kernel: [<c01531be>] do_sys_open+0x3d/0xb6
Aug 14 19:11:01 recorder kernel: [<c015327b>] sys_open+0x1e/0x23
Aug 14 19:11:01 recorder kernel: [<c0102ad1>] sysenter_past_esp+0x6a/0x99
Aug 14 19:11:01 recorder kernel: [<c0174332>] bio_fs_destructor+0x0/0xb
Aug 14 19:11:01 recorder kernel: =======================


Kind regards,
Udo