Bug 907609 - comm: kaffeine Not tainted 3.7.3-101.fc17.i686.PAE
Summary: comm: kaffeine Not tainted 3.7.3-101.fc17.i686.PAE
Keywords:
Status: CLOSED WONTFIX
Alias: None
Product: Fedora
Classification: Fedora
Component: kernel
Version: 17
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Mauro Carvalho Chehab
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2013-02-04 20:10 UTC by Marc Muehlfeld
Modified: 2013-07-04 22:59 UTC (History)
6 users (show)

Fixed In Version:
Clone Of:
Environment:
Last Closed: 2013-06-03 22:55:34 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)
dmesg output (20.12 KB, text/plain)
2013-02-06 17:32 UTC, Marc Muehlfeld
no flags Details

Description Marc Muehlfeld 2013-02-04 20:10:58 UTC
Description of problem:
My Hauppauge Nova-S Plus TV card isn't working with kernel 3.7.3-101. Kaffeine says "device" not found.



Version-Release number of selected component (if applicable):
kernel-3.7.3-101.fc17.i686.PAE



How reproducible:
Always



Steps to Reproduce:
1. Boot kernel 3.7.3-101.fc17.i686.PAE
2. Start kaffeine
3. Try watching TV


  
Actual results:
Message "device not found" in kaffeine.



Expected results:
TV should work.



Additional info:
With kernel-PAE-3.5.6-1.fc17.i686 and kernel-PAE-3.3.4-5.fc17.i686 the TV card works fine.

In dmesg I see the following errors when I try to watch TV in kaffeine:
[21269.640014] vmap allocation for size 1929216 failed: use vmalloc=<size> to increase size.
[21269.640020] vmalloc: allocation failure: 1925120 bytes
[21269.640022] kaffeine: page allocation failure: order:0, mode:0xd2
[21269.640026] Pid: 11850, comm: kaffeine Not tainted 3.7.3-101.fc17.i686.PAE #1
[21269.640028] Call Trace:
[21269.640037]  [<c050c2ed>] warn_alloc_failed+0xad/0xf0
[21269.640042]  [<c0538759>] __vmalloc_node_range+0x179/0x1e0
[21269.640048]  [<c047e4de>] ? try_to_wake_up+0x18e/0x230
[21269.640061]  [<f8138aa0>] ? dvb_register_frontend+0x1a0/0x1a0 [dvb_core]
[21269.640065]  [<c0538822>] __vmalloc_node+0x62/0x70
[21269.640071]  [<f812fb68>] ? dvb_dvr_open+0x68/0x170 [dvb_core]
[21269.640075]  [<c05388f8>] vmalloc+0x38/0x40
[21269.640081]  [<f812fb68>] ? dvb_dvr_open+0x68/0x170 [dvb_core]
[21269.640086]  [<f812fb68>] dvb_dvr_open+0x68/0x170 [dvb_core]
[21269.640092]  [<f812f11e>] dvb_device_open+0x7e/0x100 [dvb_core]
[21269.640096]  [<c055d290>] chrdev_open+0x80/0x140
[21269.640101]  [<c05578e8>] do_dentry_open+0x1b8/0x230
[21269.640105]  [<c0562f08>] ? __inode_permission+0x48/0xb0
[21269.640108]  [<c055d210>] ? cdev_put+0x20/0x20
[21269.640112]  [<c0557992>] finish_open+0x32/0x50
[21269.640116]  [<c0565deb>] do_last+0x61b/0xbd0
[21269.640120]  [<c056301a>] ? link_path_walk+0x5a/0x750
[21269.640124]  [<c056397b>] ? path_init+0x19b/0x390
[21269.640128]  [<c0566442>] path_openat+0xa2/0x3c0
[21269.640133]  [<c0566971>] do_filp_open+0x31/0x80
[21269.640138]  [<c0558ad3>] do_sys_open+0xe3/0x1b0
[21269.640142]  [<c0558bd2>] sys_open+0x32/0x40
[21269.640146]  [<c09899cd>] sysenter_do_call+0x12/0x28
[21269.640151]  [<c0980000>] ? check_tnode.part.14+0x3/0x14
[21269.640153] Mem-Info:
[21269.640155] DMA per-cpu:
[21269.640157] CPU    0: hi:    0, btch:   1 usd:   0
[21269.640159] CPU    1: hi:    0, btch:   1 usd:   0
[21269.640161] CPU    2: hi:    0, btch:   1 usd:   0
[21269.640163] CPU    3: hi:    0, btch:   1 usd:   0
[21269.640164] Normal per-cpu:
[21269.640166] CPU    0: hi:  186, btch:  31 usd: 151
[21269.640168] CPU    1: hi:  186, btch:  31 usd:  81
[21269.640170] CPU    2: hi:  186, btch:  31 usd: 178
[21269.640172] CPU    3: hi:  186, btch:  31 usd:  41
[21269.640173] HighMem per-cpu:
[21269.640175] CPU    0: hi:  186, btch:  31 usd: 154
[21269.640177] CPU    1: hi:  186, btch:  31 usd: 155
[21269.640179] CPU    2: hi:  186, btch:  31 usd: 175
[21269.640180] CPU    3: hi:  186, btch:  31 usd: 189
[21269.640185] active_anon:95452 inactive_anon:90587 isolated_anon:0
[21269.640185]  active_file:386083 inactive_file:190764 isolated_file:0
[21269.640185]  unevictable:0 dirty:1125 writeback:0 unstable:0
[21269.640185]  free:196064 slab_reclaimable:38385 slab_unreclaimable:9946
[21269.640185]  mapped:44463 shmem:8507 pagetables:2947 bounce:0
[21269.640185]  free_cma:0
[21269.640194] DMA free:6436kB min:784kB low:980kB high:1176kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15796kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:32kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[21269.640196] lowmem_reserve[]: 0 863 4027 4027
[21269.640205] Normal free:450312kB min:43912kB low:54888kB high:65868kB active_anon:20480kB inactive_anon:0kB active_file:107484kB inactive_file:23276kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:883912kB mlocked:0kB dirty:644kB writeback:0kB mapped:4kB shmem:0kB slab_reclaimable:153540kB slab_unreclaimable:39752kB kernel_stack:2560kB pagetables:0kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[21269.640207] lowmem_reserve[]: 0 0 25315 25315
[21269.640215] HighMem free:327508kB min:512kB low:40760kB high:81008kB active_anon:361328kB inactive_anon:362348kB active_file:1436848kB inactive_file:739780kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:3240436kB mlocked:0kB dirty:3856kB writeback:0kB mapped:177848kB shmem:34028kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:11788kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[21269.640217] lowmem_reserve[]: 0 0 0 0
[21269.640221] DMA: 3*4kB 3*8kB 2*16kB 3*32kB 4*64kB 3*128kB 4*256kB 1*512kB 2*1024kB 1*2048kB 0*4096kB = 6436kB
[21269.640230] Normal: 3760*4kB 2878*8kB 2905*16kB 1346*32kB 718*64kB 284*128kB 135*256kB 84*512kB 51*1024kB 18*2048kB 18*4096kB = 450304kB
[21269.640239] HighMem: 2*4kB 2*8kB 1028*16kB 542*32kB 315*64kB 112*128kB 16*256kB 164*512kB 167*1024kB 0*2048kB 0*4096kB = 327384kB
[21269.640248] 585352 total pagecache pages
[21269.640249] 0 pages in swap cache
[21269.640251] Swap cache stats: add 0, delete 0, find 0/0
[21269.640252] Free swap  = 1048572kB
[21269.640254] Total swap = 1048572kB
[21269.651076] 1177584 pages RAM
[21269.651079] 950786 pages HighMem
[21269.651081] 147542 pages reserved
[21269.651082] 621113 pages shared
[21269.651083] 585187 pages non-shared

Comment 1 Marc Muehlfeld 2013-02-06 17:32:51 UTC
Created attachment 694059 [details]
dmesg output

I have now installed F18. With the default kernel that cames with the DVD (3.6.10-4.fc18.i686.PAE), my TV card works fine.

If I update and boot kernel 3.7.5-201.fc18.i686.PAE, then it's the same like on F18.

So it's not only related to F17! 


I attached the dmesg output that appears, when I start kaffeine. It's the same like on F17 (only the versions differ).

Comment 2 Marc Muehlfeld 2013-02-10 19:06:07 UTC
Today I got a kernel update. Problem is still there with the new kernel:


[ 9300.619427] vmap allocation for size 1929216 failed: use vmalloc=<size> to increase size.
[ 9300.619430] vmalloc: allocation failure: 1925120 bytes
[ 9300.619432] kaffeine: page allocation failure: order:0, mode:0xd2
[ 9300.619441] Pid: 4115, comm: kaffeine Not tainted 3.7.6-201.fc18.i686.PAE #1
[ 9300.619442] Call Trace:
[ 9300.619448]  [<c050c8ad>] warn_alloc_failed+0xad/0xf0
[ 9300.619451]  [<c0537bf9>] __vmalloc_node_range+0x179/0x1e0
[ 9300.619459]  [<f809caa0>] ? dvb_register_frontend+0x1a0/0x1a0 [dvb_core]
[ 9300.619461]  [<c0537cc2>] __vmalloc_node+0x62/0x70
[ 9300.619464]  [<f8093b68>] ? dvb_dvr_open+0x68/0x170 [dvb_core]
[ 9300.619473]  [<c0537d98>] vmalloc+0x38/0x40
[ 9300.619475]  [<f8093b68>] ? dvb_dvr_open+0x68/0x170 [dvb_core]
[ 9300.619478]  [<f8093b68>] dvb_dvr_open+0x68/0x170 [dvb_core]
[ 9300.619480]  [<f809311e>] dvb_device_open+0x7e/0x100 [dvb_core]
[ 9300.619483]  [<c0557c50>] chrdev_open+0x80/0x140
[ 9300.619485]  [<c05522a8>] do_dentry_open+0x1b8/0x230
[ 9300.619487]  [<c055d8d8>] ? __inode_permission+0x48/0xb0
[ 9300.619489]  [<c0557bd0>] ? cdev_put+0x20/0x20
[ 9300.619490]  [<c0552352>] finish_open+0x32/0x50
[ 9300.619492]  [<c05607bb>] do_last+0x61b/0xbd0
[ 9300.619494]  [<c055d9ea>] ? link_path_walk+0x5a/0x750
[ 9300.619496]  [<c055e34b>] ? path_init+0x19b/0x390
[ 9300.619497]  [<c0560e12>] path_openat+0xa2/0x3c0
[ 9300.619500]  [<c052b2b4>] ? handle_pte_fault+0x94/0xad0
[ 9300.619501]  [<c0561341>] do_filp_open+0x31/0x80
[ 9300.619503]  [<c0553493>] do_sys_open+0xe3/0x1b0
[ 9300.619505]  [<c0553592>] sys_open+0x32/0x40
[ 9300.619507]  [<c098ab0d>] sysenter_do_call+0x12/0x28
[ 9300.619510]  [<c0980000>] ? cleanup_entry_list+0x34/0x3d
[ 9300.619511] Mem-Info:
[ 9300.619512] DMA per-cpu:
[ 9300.619513] CPU    0: hi:    0, btch:   1 usd:   0
[ 9300.619514] CPU    1: hi:    0, btch:   1 usd:   0
[ 9300.619514] CPU    2: hi:    0, btch:   1 usd:   0
[ 9300.619515] CPU    3: hi:    0, btch:   1 usd:   0
[ 9300.619516] Normal per-cpu:
[ 9300.619517] CPU    0: hi:  186, btch:  31 usd:  86
[ 9300.619518] CPU    1: hi:  186, btch:  31 usd: 174
[ 9300.619518] CPU    2: hi:  186, btch:  31 usd: 179
[ 9300.619519] CPU    3: hi:  186, btch:  31 usd:  67
[ 9300.619520] HighMem per-cpu:
[ 9300.619520] CPU    0: hi:  186, btch:  31 usd:  96
[ 9300.619521] CPU    1: hi:  186, btch:  31 usd: 161
[ 9300.619522] CPU    2: hi:  186, btch:  31 usd: 152
[ 9300.619523] CPU    3: hi:  186, btch:  31 usd:  82
[ 9300.619525] active_anon:61838 inactive_anon:6235 isolated_anon:0
 active_file:299780 inactive_file:129974 isolated_file:0
 unevictable:0 dirty:25 writeback:0 unstable:0
 free:474095 slab_reclaimable:32115 slab_unreclaimable:7109
 mapped:36488 shmem:6711 pagetables:2111 bounce:0
 free_cma:0
[ 9300.619529] DMA free:6432kB min:64kB low:80kB high:96kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15796kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:16kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[ 9300.619530] lowmem_reserve[]: 0 863 4027 4027
[ 9300.619534] Normal free:464420kB min:3724kB low:4652kB high:5584kB active_anon:0kB inactive_anon:0kB active_file:121588kB inactive_file:52424kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:883912kB mlocked:0kB dirty:48kB writeback:0kB mapped:4kB shmem:0kB slab_reclaimable:128460kB slab_unreclaimable:28420kB kernel_stack:2032kB pagetables:0kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[ 9300.619535] lowmem_reserve[]: 0 0 25315 25315
[ 9300.619538] HighMem free:1425528kB min:512kB low:3924kB high:7340kB active_anon:247352kB inactive_anon:24940kB active_file:1077532kB inactive_file:467472kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:3240436kB mlocked:0kB dirty:52kB writeback:0kB mapped:145948kB shmem:26844kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:8444kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[ 9300.619539] lowmem_reserve[]: 0 0 0 0
[ 9300.619541] DMA: 4*4kB 0*8kB 3*16kB 1*32kB 3*64kB 2*128kB 3*256kB 0*512kB 1*1024kB 2*2048kB 0*4096kB = 6432kB
[ 9300.619545] Normal: 369*4kB 949*8kB 2111*16kB 1068*32kB 629*64kB 346*128kB 127*256kB 60*512kB 34*1024kB 28*2048kB 36*4096kB = 464412kB
[ 9300.619549] HighMem: 183*4kB 48*8kB 1364*16kB 1211*32kB 1504*64kB 556*128kB 205*256kB 70*512kB 54*1024kB 24*2048kB 245*4096kB = 1425404kB
[ 9300.619553] 436446 total pagecache pages
[ 9300.619553] 0 pages in swap cache
[ 9300.619554] Swap cache stats: add 0, delete 0, find 0/0
[ 9300.619555] Free swap  = 1048572kB
[ 9300.619555] Total swap = 1048572kB
[ 9300.624587] 1177584 pages RAM
[ 9300.624589] 950786 pages HighMem
[ 9300.624589] 147547 pages reserved
[ 9300.624590] 729611 pages shared
[ 9300.624591] 433176 pages non-shared

Comment 3 Mauro Carvalho Chehab 2013-02-11 03:05:24 UTC
(In reply to comment #2)
> Today I got a kernel update. Problem is still there with the new kernel:
> 
> 
> [ 9300.619427] vmap allocation for size 1929216 failed: use vmalloc=<size>
> to increase size.
> [ 9300.619430] vmalloc: allocation failure: 1925120 bytes

This problem doesn't seem to be related to kaffeine or to the DVB driver. Instead, I suspect that your GPU driver is allocating too much memory with vmalloc.

As the above message says, you need to boot your kernel with a "vmalloc=" parameter on it.

So, you need to pass it via your /boot/grub2/grub.cfg for your booted kernel to add a new vmalloc size there. For example, here I use a virtual desktop of 4880 x 1080 x 32 bits. So, I need a very big vmalloc size for my GPU:

	linux /vmlinuz-3.3.7-1.fc17.x86_64 ro root=/dev/root ... vmalloc=536870912

You could try a smaller value than that, like 256MB (256*1024*1024):
        vmalloc = 268435456

Comment 4 Marc Muehlfeld 2013-02-11 19:14:35 UTC
It maybe sounds a bit weird: Until yesterday, the only working kernel (without modifying the 'linux' line in grub.conf) was the shipped one (3.6.10-4.fc18.i686.PAE).

Then today, after your comment, I tried again with the latest kernel (3.7.6-201.fc18.i686.PAE): Device not found and the messages in dmesg. So problem is still there.

Then I add your 'vmalloc = 268435456' to the linux line for 3.7.6-201.fc18.i686.PAE in grub.cfg and rebootet. Problem was gone. I rebootet twice more and checked. Worked both times.

But then I wanted to re-check and removed the 'vmalloc = 268435456' line from 3.7.6-201.fc18.i686.PAE again and rebooted. But it still works! Also the 3.7.5-201.fc18.i686.PAE kernel, where I haven't temporary added the 'vmalloc' part, works now!?

The only thing that changed, before I start with your hint, was applying the latest updates:
Feb 11 18:25:51 Updated: selinux-policy-3.11.1-76.fc18.noarch
Feb 11 18:25:52 Updated: selinux-policy-targeted-3.11.1-76.fc18.noarch
Feb 11 18:25:54 Updated: selinux-policy-devel-3.11.1-76.fc18.noarch
Feb 11 18:25:56 Updated: selinux-policy-doc-3.11.1-76.fc18.noarch
Feb 11 18:25:56 Updated: xorg-x11-drv-synaptics-1.6.3-1.fc18.i686
I haven't rebootet after installing them and rechecked only with this updates. But I'm not sure, if this was anything that could fix this issue.



I'll recheck it during the next days and give another feedback then.

Comment 5 Marc Muehlfeld 2013-02-11 19:54:16 UTC
25 minutes after my last post: Device not found - same problem (without the 'vmalloc' entry). Then I reboot (still again without the 'vmalloc' entry) and directly opened kaffeine. And TV works.

I have no more idea on that...

Comment 6 Josh Boyer 2013-06-03 18:55:06 UTC
Are you still seeing this with the 3.9.4 kernel in updates-testing?

Comment 7 Marc Muehlfeld 2013-06-03 22:07:35 UTC
Sorry. I can't say, if the problem is still existing.

I had so massive problems with the DVB card since F18 (system hangs 95% on shutdown, this bug, etc.). And because of that, I finally removed the card and sold it.

Comment 8 Josh Boyer 2013-06-03 22:55:34 UTC
Sorry to hear that.  Without having someone for recreate and testing, we'll have to close this out.


Note You need to log in before you can comment on or make changes to this bug.