Project

General

Profile

Actions

Bug #3617

closed

crash (tvh:dvr invoked oom-killer)

Added by g siviero about 9 years ago. Updated about 9 years ago.

Status:
Fixed
Priority:
Normal
Assignee:
-
Category:
Crashes
Target version:
-
Start date:
2016-03-09
Due date:
% Done:

0%

Estimated time:
Found in version:
HTS Tvheadend 4.1-1628~ga44061c
Affected Versions:

Description

In the last two days (after I updated to 4.1-1628, before with 4.1-1576 never happened) the application has crashed every evening.

at some point I find some EOVERFLOW errors

Mar  9 18:56:32 raspberrypi tvheadend[9249]: linuxdvb: DiBcom 7000PC : DVB-T #0 - read() EOVERFLOW
Mar  9 18:56:33 raspberrypi tvheadend[9249]: linuxdvb: DiBcom 7000PC : DVB-T #0 - read() EOVERFLOW
Mar  9 18:56:33 raspberrypi tvheadend[9249]: TS: DVB-T Italy/546MHz/Rai 5: MPEG2VIDEO @ #234 Continuity counter error (total 2)
Mar  9 18:56:33 raspberrypi tvheadend[9249]: TS: DVB-T Italy/546MHz/Rai 5: MPEG2AUDIO @ #254 Continuity counter error (total 1)
Mar  9 18:56:33 raspberrypi tvheadend[9249]: TS: DVB-T Italy/514MHz/Rai Scuola: MPEG2VIDEO @ #436 Continuity counter error (total 32)
Mar  9 18:56:33 raspberrypi tvheadend[9249]: TS: DVB-T Italy/514MHz/Rai Scuola: MPEG2AUDIO @ #447 Continuity counter error (total 5)
Mar  9 18:56:33 raspberrypi tvheadend[9249]: TS: DVB-T Italy/514MHz/Rai Scuola: TELETEXT @ #588 Continuity counter error (total 2)
Mar  9 18:56:33 raspberrypi tvheadend[9249]: linuxdvb: DiBcom 7000PC : DVB-T #0 - read() EOVERFLOW
Mar  9 18:56:33 raspberrypi tvheadend[9249]: TS: DVB-T Italy/690MHz/frisbee: MPEG2VIDEO @ #1457 Continuity counter error (total 11)
Mar  9 18:56:33 raspberrypi tvheadend[9249]: TS: DVB-T Italy/690MHz/frisbee: TELETEXT @ #1460 Continuity counter error (total 1)
Mar  9 18:56:34 raspberrypi tvheadend[9249]: eit: 546MHz in DVB-T Italy: invalid checksum (len 217, errors 1)

then at some point something "bad" happens and it looks like tvh gets killed for an out-of memory error:

Mar  9 18:58:51 raspberrypi tvheadend[9249]: subscription: 0E1D: "epggrab" subscribing to mux "506MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
Mar  9 18:58:52 raspberrypi tvheadend[9249]: TS: DVB-T Italy/514MHz/Rai Scuola: MPEG2VIDEO @ #436 Continuity counter error (total 33)
Mar  9 18:58:56 raspberrypi tvheadend[9249]: mpegts: 506MHz in DVB-T Italy scan complete
Mar  9 19:00:11 raspberrypi kernel: [70100.018574] tvh:dvr invoked oom-killer: gfp_mask=0x10200da, order=0, oom_score_adj=0
Mar  9 19:00:11 raspberrypi kernel: [70100.018602] tvh:dvr cpuset=/ mems_allowed=0
Mar  9 19:00:11 raspberrypi kernel: [70100.018637] CPU: 2 PID: 14424 Comm: tvh:dvr Not tainted 4.1.18-v7+ #846
Mar  9 19:00:11 raspberrypi kernel: [70100.018648] Hardware name: BCM2709
Mar  9 19:00:11 raspberrypi kernel: [70100.018700] [<800184b8>] (unwind_backtrace) from [<80013ee0>] (show_stack+0x20/0x24)
Mar  9 19:00:11 raspberrypi kernel: [70100.018730] [<80013ee0>] (show_stack) from [<80569038>] (dump_stack+0xd4/0x118)
Mar  9 19:00:11 raspberrypi kernel: [70100.018765] [<80569038>] (dump_stack) from [<80568550>] (dump_header+0xc0/0x1ec)
Mar  9 19:00:11 raspberrypi kernel: [70100.018792] [<80568550>] (dump_header) from [<800f99b8>] (oom_kill_process+0x23c/0x460)
Mar  9 19:00:11 raspberrypi kernel: [70100.018817] [<800f99b8>] (oom_kill_process) from [<800f9ed4>] (__out_of_memory+0x278/0x3a8)
Mar  9 19:00:11 raspberrypi kernel: [70100.018839] [<800f9ed4>] (__out_of_memory) from [<800fa1ec>] (out_of_memory+0x68/0x80)
Mar  9 19:00:11 raspberrypi kernel: [70100.018865] [<800fa1ec>] (out_of_memory) from [<800febc4>] (__alloc_pages_nodemask+0x858/0x8f0)
Mar  9 19:00:11 raspberrypi kernel: [70100.018890] [<800febc4>] (__alloc_pages_nodemask) from [<800f6028>] (pagecache_get_page+0x100/0x1cc)
Mar  9 19:00:11 raspberrypi kernel: [70100.018914] [<800f6028>] (pagecache_get_page) from [<800f6650>] (grab_cache_page_write_begin+0x2c/0x40)
Mar  9 19:00:11 raspberrypi kernel: [70100.018943] [<800f6650>] (grab_cache_page_write_begin) from [<80237988>] (nfs_write_begin+0x60/0x20c)
Mar  9 19:00:11 raspberrypi kernel: [70100.018969] [<80237988>] (nfs_write_begin) from [<800f67d8>] (generic_perform_write+0xd0/0x1c8)
Mar  9 19:00:11 raspberrypi kernel: [70100.018992] [<800f67d8>] (generic_perform_write) from [<800f7db0>] (__generic_file_write_iter+0x19c/0x1ec)
Mar  9 19:00:11 raspberrypi kernel: [70100.019016] [<800f7db0>] (__generic_file_write_iter) from [<800f7f54>] (generic_file_write_iter+0x154/0x29c)
Mar  9 19:00:11 raspberrypi kernel: [70100.019041] [<800f7f54>] (generic_file_write_iter) from [<80237ba4>] (nfs_file_write+0x70/0x184)
Mar  9 19:00:11 raspberrypi kernel: [70100.019067] [<80237ba4>] (nfs_file_write) from [<80148ec4>] (__vfs_write+0xb8/0xe8)
Mar  9 19:00:11 raspberrypi kernel: [70100.019092] [<80148ec4>] (__vfs_write) from [<80149738>] (vfs_write+0xa0/0x1a8)
Mar  9 19:00:11 raspberrypi kernel: [70100.019115] [<80149738>] (vfs_write) from [<8014a0a8>] (SyS_write+0x54/0xb0)
Mar  9 19:00:11 raspberrypi kernel: [70100.019141] [<8014a0a8>] (SyS_write) from [<8000fa20>] (ret_fast_syscall+0x0/0x54)
Mar  9 19:00:11 raspberrypi kernel: [70100.019153] Mem-Info:
Mar  9 19:00:11 raspberrypi kernel: [70100.019185] active_anon:111591 inactive_anon:111607 isolated_anon:0
Mar  9 19:00:11 raspberrypi kernel: [70100.019185]  active_file:246 inactive_file:219 isolated_file:0
Mar  9 19:00:11 raspberrypi kernel: [70100.019185]  unevictable:0 dirty:0 writeback:0 unstable:0
Mar  9 19:00:11 raspberrypi kernel: [70100.019185]  slab_reclaimable:2561 slab_unreclaimable:1978
Mar  9 19:00:11 raspberrypi kernel: [70100.019185]  mapped:892 shmem:521 pagetables:625 bounce:0
Mar  9 19:00:11 raspberrypi kernel: [70100.019185]  free:2036 free_pcp:518 free_cma:0
Mar  9 19:00:11 raspberrypi kernel: [70100.019249] Normal free:8144kB min:8192kB low:10240kB high:12288kB active_anon:446364kB inactive_anon:446428kB active_file:984kB inactive_file:876kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:966656kB managed:948060kB mlocked:0kB dirty:0kB writeback:0kB mapped:3568kB shmem:2084kB slab_reclaimable:10244kB slab_unreclaimable:7912kB kernel_stack:1000kB pagetables:2500kB unstable:0kB bounce:0kB free_pcp:2072kB local_pcp:532kB free_cma:0kB writeback_tmp:0kB pages_scanned:13152 all_unreclaimable? yes
Mar  9 19:00:11 raspberrypi kernel: [70100.019263] lowmem_reserve[]: 0 0
Mar  9 19:00:11 raspberrypi kernel: [70100.019282] Normal: 111*4kB (U) 1*8kB (R) 2*16kB (R) 2*32kB (R) 3*64kB (R) 0*128kB 1*256kB (R) 0*512kB 1*1024kB (R) 1*2048kB (R) 1*4096kB (R) = 8164kB
Mar  9 19:00:11 raspberrypi kernel: [70100.019377] 2392 total pagecache pages
Mar  9 19:00:11 raspberrypi kernel: [70100.019391] 1294 pages in swap cache
Mar  9 19:00:11 raspberrypi kernel: [70100.019404] Swap cache stats: add 40840, delete 39546, find 8917/11861
Mar  9 19:00:11 raspberrypi kernel: [70100.019414] Free swap  = 0kB
Mar  9 19:00:11 raspberrypi kernel: [70100.019423] Total swap = 102396kB
Mar  9 19:00:11 raspberrypi kernel: [70100.019432] 241664 pages RAM
Mar  9 19:00:11 raspberrypi kernel: [70100.019441] 0 pages HighMem/MovableOnly
Mar  9 19:00:11 raspberrypi kernel: [70100.019450] 2601 pages reserved
Mar  9 19:00:11 raspberrypi kernel: [70100.019459] 2048 pages cma reserved
Mar  9 19:00:11 raspberrypi kernel: [70100.019469] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
Mar  9 19:00:11 raspberrypi kernel: [70100.019501] [  104]     0   104     2487      419       9       0       30             0 systemd-journal
Mar  9 19:00:11 raspberrypi kernel: [70100.019520] [  106]     0   106     2829       25       7       0       88         -1000 systemd-udevd
Mar  9 19:00:11 raspberrypi kernel: [70100.019538] [  328]     0   328     1268       11       6       0       39             0 cron
Mar  9 19:00:11 raspberrypi kernel: [70100.019557] [  329]     0   329      849       21       7       0       30             0 systemd-logind
Mar  9 19:00:11 raspberrypi kernel: [70100.019575] [  334]   106   334     1002       62       5       0       22             0 avahi-daemon
Mar  9 19:00:11 raspberrypi kernel: [70100.019594] [  336]   105   336     1373       48       7       0       37          -900 dbus-daemon
Mar  9 19:00:11 raspberrypi kernel: [70100.019612] [  357] 65534   357      573        5       6       0       25             0 thd
Mar  9 19:00:11 raspberrypi kernel: [70100.019631] [  367]   106   367      969       13       5       0       43             0 avahi-daemon
Mar  9 19:00:11 raspberrypi kernel: [70100.019649] [  406]     0   406     8036       54      10       0      118             0 rsyslogd
Mar  9 19:00:11 raspberrypi kernel: [70100.019668] [  568]     0   568      641       25       5       0       37             0 dhcpcd
Mar  9 19:00:11 raspberrypi kernel: [70100.019718] [  569]     0   569     1965       26       7       0       88         -1000 sshd
Mar  9 19:00:11 raspberrypi kernel: [70100.019736] [  589]   107   589     1442       29       8       0       78             0 ntpd
Mar  9 19:00:11 raspberrypi kernel: [70100.019754] [  590]     0   590     1013        1       5       0       32             0 agetty
Mar  9 19:00:11 raspberrypi kernel: [70100.019773] [  591]     0   591      968        1       7       0       32             0 agetty
Mar  9 19:00:11 raspberrypi kernel: [70100.019793] [ 9249]   109  9249   327672   220814     524       0    19302             0 tvheadend
Mar  9 19:00:11 raspberrypi kernel: [70100.019811] Out of memory: Kill process 9249 (tvheadend) score 916 or sacrifice child
Mar  9 19:00:11 raspberrypi kernel: [70100.019835] Killed process 9249 (tvheadend) total-vm:1310688kB, anon-rss:883256kB, file-rss:0kB

I don't know the cause or how to troubleshoot the problem.
Any suggestions?

Something else that could be connected:
A 0MHz and some 100MHz muxes are automatically added but they are obviously out of range for DVB-T.

[   11.539266] usb 1-1.5: DVB: adapter 3 frontend 0 frequency 0 out of range (45000000..860000000)
[   11.595587] usb 1-1.5: DVB: adapter 2 frontend 0 frequency 0 out of range (45000000..860000000)
[   11.633413] usb 1-1.3: DVB: adapter 1 frontend 0 frequency 0 out of range (45000000..860000000)
[   11.689494] usb 1-1.3: DVB: adapter 0 frontend 0 frequency 0 out of range (45000000..860000000)
[   12.378707] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   12.387221] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC1E1
[  489.427142] usb 1-1.3: DVB: adapter 0 frontend 0 frequency 100 out of range (45000000..860000000)

I disabled them manually, but I did not notice this behaviour in the previous releases (4.1-1576 for example).

lsusb

Bus 001 Device 005: ID 1415:0003 Nam Tai E&E Products Ltd. or OmniVision Technologies, Inc.
Bus 001 Device 004: ID 1415:0003 Nam Tai E&E Products Ltd. or OmniVision Technologies, Inc.
Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter
Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp.
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub


Files

Actions

Also available in: Atom PDF