[mythtv-users] HD-PVR IR blaster

Victor Brunini vbrunini at mit.edu
Wed Sep 16 18:05:40 UTC 2009


It's recognizing the device again properly in 2.6.31 with that patch, but
I'm still getting the instability problem. Got through 2 recordings fine
last night, then on the third the file ends about 5 minutes in and dmesg is
filled with:

lirc_zilog: i2c_master_send failed with -110
lirc_zilog: polling the IR receiver chip failed, trying reset
lirc_zilog: i2c_master_send failed with -110
lirc_zilog: polling the IR receiver chip failed, trying reset
lirc_zilog: i2c_master_send failed with -110
lirc_zilog: polling the IR receiver chip failed, trying reset
lirc_zilog: i2c_master_send failed with -110
lirc_zilog: unable to read from the IR chip after 3 resets, giving up

repeating until i turned off my hdpvr. Then there is:

usb 2-2: USB disconnect, address 5
lirc_zilog: i2c_master_send failed with -108
lirc_zilog: polling the IR receiver chip failed, trying reset
lirc_zilog: i2c_master_send failed with -19
lirc_zilog: polling the IR receiver chip failed, trying reset
lirc_zilog: poll called
lirc_zilog: poll result = POLLIN|POLLRDNORM
INFO: task khubd:28 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
khubd         D c201d91c     0    28      2 0x00000000
 f6db3ce8 00000046 f6dab624 c201d91c c0a24734 c0a29140 f6dab624 c0a29140
 c0a29140 013bcbb9 00000000 00000000 8ac07894 00001318 c2012140 f6dab380
 f31e6a80 00000000 00000000 00000000 f331c460 f6dab380 f331c464 f6db3d1c
Call Trace:
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<fc5e603b>] ir_remove+0x1b/0x132 [lirc_zilog]
 [<c0515262>] ? sysfs_hash_and_remove+0x42/0x55
 [<f85171f1>] i2c_device_remove+0x40/0x7b [i2c_core]
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635599>] device_del+0xf1/0x132
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635608>] device_unregister+0x2e/0x53
 [<f8517eb8>] i2c_unregister_device+0x10/0x12 [i2c_core]
 [<f8518109>] __unregister_client+0x1b/0x1f [i2c_core]
 [<c0634fb9>] device_for_each_child+0x2d/0x57
 [<f85182b0>] i2c_del_adapter+0xa9/0x170 [i2c_core]
 [<f7d81514>] hdpvr_disconnect+0xc4/0x107 [hdpvr]
 [<c06810a9>] usb_unbind_interface+0x50/0xc0
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<c0635599>] device_del+0xf1/0x132
 [<c067e711>] usb_disable_device+0xab/0x177
 [<c0679761>] usb_disconnect+0xcc/0x167
 [<c067ab9e>] hub_events+0x508/0xea5
 [<c043000a>] ? tg_shares_up+0x64/0x1bb
 [<c067b562>] hub_thread+0x27/0x165
 [<c067b562>] ? hub_thread+0x27/0x165
 [<c045234d>] ? autoremove_wake_function+0x0/0x34
 [<c067b53b>] ? hub_thread+0x0/0x165
 [<c04520af>] kthread+0x64/0x69
 [<c045204b>] ? kthread+0x0/0x69
 [<c0403eb3>] kernel_thread_helper+0x7/0x10
INFO: task lirc_zilog:2527 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
lirc_zilog    D 00000000     0  2527      2 0x00000080
 f24cfec8 00000046 f6514fe4 00000000 c0a24734 c0a29140 f6514fe4 c0a29140
 c0a29140 013bcbf2 00000001 00000001 8ac06432 00001318 c2022140 f6514d40
 00000000 00000000 00000000 00000000 f331ede4 f6514d40 f331ede8 f24cfefc
Call Trace:
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<f7d81e2c>] hdpvr_transfer+0x2c/0x96 [hdpvr]
 [<f85174d7>] i2c_transfer+0x6f/0xe0 [i2c_core]
 [<f8517d33>] i2c_master_send+0x39/0x48 [i2c_core]
 [<fc5e6f90>] lirc_thread+0xe0/0x2ec [lirc_zilog]
 [<fc5e6eb0>] ? lirc_thread+0x0/0x2ec [lirc_zilog]
 [<c04520af>] kthread+0x64/0x69
 [<c045204b>] ? kthread+0x0/0x69
 [<c0403eb3>] kernel_thread_helper+0x7/0x10
INFO: task khubd:28 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
khubd         D c201d91c     0    28      2 0x00000000
 f6db3ce8 00000046 f6dab624 c201d91c c0a24734 c0a29140 f6dab624 c0a29140
 c0a29140 013bcbb9 00000000 00000000 8ac07894 00001318 c2012140 f6dab380
 f31e6a80 00000000 00000000 00000000 f331c460 f6dab380 f331c464 f6db3d1c
Call Trace:
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<fc5e603b>] ir_remove+0x1b/0x132 [lirc_zilog]
 [<c0515262>] ? sysfs_hash_and_remove+0x42/0x55
 [<f85171f1>] i2c_device_remove+0x40/0x7b [i2c_core]
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635599>] device_del+0xf1/0x132
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635608>] device_unregister+0x2e/0x53
 [<f8517eb8>] i2c_unregister_device+0x10/0x12 [i2c_core]
 [<f8518109>] __unregister_client+0x1b/0x1f [i2c_core]
 [<c0634fb9>] device_for_each_child+0x2d/0x57
 [<f85182b0>] i2c_del_adapter+0xa9/0x170 [i2c_core]
 [<f7d81514>] hdpvr_disconnect+0xc4/0x107 [hdpvr]
 [<c06810a9>] usb_unbind_interface+0x50/0xc0
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<c0635599>] device_del+0xf1/0x132
 [<c067e711>] usb_disable_device+0xab/0x177
 [<c0679761>] usb_disconnect+0xcc/0x167
 [<c067ab9e>] hub_events+0x508/0xea5
 [<c043000a>] ? tg_shares_up+0x64/0x1bb
 [<c067b562>] hub_thread+0x27/0x165
 [<c067b562>] ? hub_thread+0x27/0x165
 [<c045234d>] ? autoremove_wake_function+0x0/0x34
 [<c067b53b>] ? hub_thread+0x0/0x165
 [<c04520af>] kthread+0x64/0x69
 [<c045204b>] ? kthread+0x0/0x69
 [<c0403eb3>] kernel_thread_helper+0x7/0x10
INFO: task lircd:1526 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
lircd         D f31bac90     0  1526      1 0x00000084
 f429fefc 00000086 f3854304 f31bac90 c0a24734 c0a29140 f3854304 c0a29140
 c0a29140 00000202 c057b249 00000000 d31fc476 0000131e c2012140 f3854060
 f429fefc 00000286 f24d6060 00000286 f331c460 f3854060 f331c464 f429ff30
Call Trace:
 [<c057b249>] ? selinux_inode_free_security+0x55/0x5a
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<fc5e6d1f>] close+0x2d/0x47 [lirc_zilog]
 [<c04cdf72>] __fput+0xef/0x17f
 [<c04ce01a>] fput+0x18/0x1a
 [<c04ca811>] filp_close+0x56/0x60
 [<c04ca87a>] sys_close+0x5f/0x8d
 [<c0403298>] sysenter_do_call+0x12/0x2d
INFO: task lirc_zilog:2527 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
lirc_zilog    D 00000000     0  2527      2 0x00000080
 f24cfec8 00000046 f6514fe4 00000000 c0a24734 c0a29140 f6514fe4 c0a29140
 c0a29140 013bcbf2 00000001 00000001 8ac06432 00001318 c2022140 f6514d40
 00000000 00000000 00000000 00000000 f331ede4 f6514d40 f331ede8 f24cfefc
Call Trace:
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<f7d81e2c>] hdpvr_transfer+0x2c/0x96 [hdpvr]
 [<f85174d7>] i2c_transfer+0x6f/0xe0 [i2c_core]
 [<f8517d33>] i2c_master_send+0x39/0x48 [i2c_core]
 [<fc5e6f90>] lirc_thread+0xe0/0x2ec [lirc_zilog]
 [<fc5e6eb0>] ? lirc_thread+0x0/0x2ec [lirc_zilog]
 [<c04520af>] kthread+0x64/0x69
 [<c045204b>] ? kthread+0x0/0x69
 [<c0403eb3>] kernel_thread_helper+0x7/0x10
INFO: task khubd:28 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
khubd         D c201d91c     0    28      2 0x00000000
 f6db3ce8 00000046 f6dab624 c201d91c c0a24734 c0a29140 f6dab624 c0a29140
 c0a29140 013bcbb9 00000000 00000000 8ac07894 00001318 c2012140 f6dab380
 f31e6a80 00000000 00000000 00000000 f331c460 f6dab380 f331c464 f6db3d1c
Call Trace:
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<fc5e603b>] ir_remove+0x1b/0x132 [lirc_zilog]
 [<c0515262>] ? sysfs_hash_and_remove+0x42/0x55
 [<f85171f1>] i2c_device_remove+0x40/0x7b [i2c_core]
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635599>] device_del+0xf1/0x132
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635608>] device_unregister+0x2e/0x53
 [<f8517eb8>] i2c_unregister_device+0x10/0x12 [i2c_core]
 [<f8518109>] __unregister_client+0x1b/0x1f [i2c_core]
 [<c0634fb9>] device_for_each_child+0x2d/0x57
 [<f85182b0>] i2c_del_adapter+0xa9/0x170 [i2c_core]
 [<f7d81514>] hdpvr_disconnect+0xc4/0x107 [hdpvr]
 [<c06810a9>] usb_unbind_interface+0x50/0xc0
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<c0635599>] device_del+0xf1/0x132
 [<c067e711>] usb_disable_device+0xab/0x177
 [<c0679761>] usb_disconnect+0xcc/0x167
 [<c067ab9e>] hub_events+0x508/0xea5
 [<c043000a>] ? tg_shares_up+0x64/0x1bb
 [<c067b562>] hub_thread+0x27/0x165
 [<c067b562>] ? hub_thread+0x27/0x165
 [<c045234d>] ? autoremove_wake_function+0x0/0x34
 [<c067b53b>] ? hub_thread+0x0/0x165
 [<c04520af>] kthread+0x64/0x69
 [<c045204b>] ? kthread+0x0/0x69
 [<c0403eb3>] kernel_thread_helper+0x7/0x10
INFO: task lircd:1526 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
lircd         D f31bac90     0  1526      1 0x00000084
 f429fefc 00000086 f3854304 f31bac90 c0a24734 c0a29140 f3854304 c0a29140
 c0a29140 00000202 c057b249 00000000 d31fc476 0000131e c2012140 f3854060
 f429fefc 00000286 f24d6060 00000286 f331c460 f3854060 f331c464 f429ff30
Call Trace:
 [<c057b249>] ? selinux_inode_free_security+0x55/0x5a
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<fc5e6d1f>] close+0x2d/0x47 [lirc_zilog]
 [<c04cdf72>] __fput+0xef/0x17f
 [<c04ce01a>] fput+0x18/0x1a
 [<c04ca811>] filp_close+0x56/0x60
 [<c04ca87a>] sys_close+0x5f/0x8d
 [<c0403298>] sysenter_do_call+0x12/0x2d
INFO: task lirc_zilog:2527 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
lirc_zilog    D 00000000     0  2527      2 0x00000080
 f24cfec8 00000046 f6514fe4 00000000 c0a24734 c0a29140 f6514fe4 c0a29140
 c0a29140 013bcbf2 00000001 00000001 8ac06432 00001318 c2022140 f6514d40
 00000000 00000000 00000000 00000000 f331ede4 f6514d40 f331ede8 f24cfefc
Call Trace:
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<f7d81e2c>] hdpvr_transfer+0x2c/0x96 [hdpvr]
 [<f85174d7>] i2c_transfer+0x6f/0xe0 [i2c_core]
 [<f8517d33>] i2c_master_send+0x39/0x48 [i2c_core]
 [<fc5e6f90>] lirc_thread+0xe0/0x2ec [lirc_zilog]
 [<fc5e6eb0>] ? lirc_thread+0x0/0x2ec [lirc_zilog]
 [<c04520af>] kthread+0x64/0x69
 [<c045204b>] ? kthread+0x0/0x69
 [<c0403eb3>] kernel_thread_helper+0x7/0x10
INFO: task khubd:28 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
khubd         D c201d91c     0    28      2 0x00000000
 f6db3ce8 00000046 f6dab624 c201d91c c0a24734 c0a29140 f6dab624 c0a29140
 c0a29140 013bcbb9 00000000 00000000 8ac07894 00001318 c2012140 f6dab380
 f31e6a80 00000000 00000000 00000000 f331c460 f6dab380 f331c464 f6db3d1c
Call Trace:
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<fc5e603b>] ir_remove+0x1b/0x132 [lirc_zilog]
 [<c0515262>] ? sysfs_hash_and_remove+0x42/0x55
 [<f85171f1>] i2c_device_remove+0x40/0x7b [i2c_core]
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635599>] device_del+0xf1/0x132
 [<f85180ee>] ? __unregister_client+0x0/0x1f [i2c_core]
 [<c0635608>] device_unregister+0x2e/0x53
 [<f8517eb8>] i2c_unregister_device+0x10/0x12 [i2c_core]
 [<f8518109>] __unregister_client+0x1b/0x1f [i2c_core]
 [<c0634fb9>] device_for_each_child+0x2d/0x57
 [<f85182b0>] i2c_del_adapter+0xa9/0x170 [i2c_core]
 [<f7d81514>] hdpvr_disconnect+0xc4/0x107 [hdpvr]
 [<c06810a9>] usb_unbind_interface+0x50/0xc0
 [<c06377e3>] __device_release_driver+0x4a/0x80
 [<c06378b6>] device_release_driver+0x1d/0x28
 [<c0636d9b>] bus_remove_device+0x95/0xbe
 [<c0635599>] device_del+0xf1/0x132
 [<c067e711>] usb_disable_device+0xab/0x177
 [<c0679761>] usb_disconnect+0xcc/0x167
 [<c067ab9e>] hub_events+0x508/0xea5
 [<c043000a>] ? tg_shares_up+0x64/0x1bb
 [<c067b562>] hub_thread+0x27/0x165
 [<c067b562>] ? hub_thread+0x27/0x165
 [<c045234d>] ? autoremove_wake_function+0x0/0x34
 [<c067b53b>] ? hub_thread+0x0/0x165
 [<c04520af>] kthread+0x64/0x69
 [<c045204b>] ? kthread+0x0/0x69
 [<c0403eb3>] kernel_thread_helper+0x7/0x10
INFO: task lircd:1526 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
lircd         D f31bac90     0  1526      1 0x00000084
 f429fefc 00000086 f3854304 f31bac90 c0a24734 c0a29140 f3854304 c0a29140
 c0a29140 00000202 c057b249 00000000 d31fc476 0000131e c2012140 f3854060
 f429fefc 00000286 f24d6060 00000286 f331c460 f3854060 f331c464 f429ff30
Call Trace:
 [<c057b249>] ? selinux_inode_free_security+0x55/0x5a
 [<c0772fb9>] __mutex_lock_common+0xdd/0x12a
 [<c077301d>] __mutex_lock_slowpath+0x17/0x1a
 [<c077310c>] ? mutex_lock+0x30/0x3e
 [<c077310c>] mutex_lock+0x30/0x3e
 [<fc5e6d1f>] close+0x2d/0x47 [lirc_zilog]
 [<c04cdf72>] __fput+0xef/0x17f
 [<c04ce01a>] fput+0x18/0x1a
 [<c04ca811>] filp_close+0x56/0x60
 [<c04ca87a>] sys_close+0x5f/0x8d
 [<c0403298>] sysenter_do_call+0x12/0x2d

Let me know if you'd like me to run any further tests for more information
or if the
contents of another log file would be helpful.

Thanks,
Victor

------------------------------------------------------------
On 09/14/2009 05:24 PM, Victor Brunini wrote:
> I've been trying to test the IR blaster functionality on my HD-PVR with
this patch,
> unfortunately I'm running into some issues. First I tried option 1, the
prebuilt
> Fedora kernel package, and using that lirc_zilog recognizes the hd-pvr's
ir
>
>
> chip perfectly and I've been able to successfully use it to change
channels on my
> cable box. However, using that version causes the hd-pvr to lock up
randomly 5-10
> minutes into a recording, and attempting to modprobe -r either the hdpvr
or
>
>
> lirc_zilog modules hangs and eventually leads to a kernel oops after a
minute or
> so.
>
> I decided to try to follow your 2nd suggestion and pull your 2.6.31 git
tree by cloning
> Linus'  and then adding you as a remote to see if it was any more stable.
I also applied
>
>
> the/http://wilsonet.com/jarod/junk/hdpvr-ir/hdpvr-ir-enable.patch/  as you
said to.
> The kernel compiles and boots fine and the hdpvr records without any of
the instability

Sorry, you actually want this for 2.6.31, instead of the above:

http://wilsonet.com/jarod/junk/hdpvr-ir/hdpvr-ir-2.6.31.patch

Lockups recording sound very... Not good. I have to admit, I've not
actually tried recording and using the IR part on mine at the same time
yet...


--
Jarod Wilson
jarod at wilsonet.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mythtv.org/pipermail/mythtv-users/attachments/20090916/124fae8c/attachment.htm>


More information about the mythtv-users mailing list