Project

General

Profile

Bug #3617

crash (tvh:dvr invoked oom-killer)

Added by g siviero over 4 years ago. Updated over 4 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

History

#1

Updated by g siviero over 4 years ago

Again tonight:

Mar 10 21:14:02 raspberrypi tvheadend[17128]: mpegts: 594MHz in DVB-T Italy scan complete
Mar 10 21:14:45 raspberrypi kernel: [85731.266777] ntpd invoked oom-killer: gfp_mask=0x201da, order=0, oom_score_adj=0
Mar 10 21:14:45 raspberrypi kernel: [85731.266806] ntpd cpuset=/ mems_allowed=0
Mar 10 21:14:45 raspberrypi kernel: [85731.266839] CPU: 1 PID: 627 Comm: ntpd Not tainted 4.1.18-v7+ #846
Mar 10 21:14:45 raspberrypi kernel: [85731.266853] Hardware name: BCM2709
Mar 10 21:14:45 raspberrypi kernel: [85731.266902] [<800184b8>] (unwind_backtrace) from [<80013ee0>] (show_stack+0x20/0x24)
Mar 10 21:14:45 raspberrypi kernel: [85731.266931] [<80013ee0>] (show_stack) from [<80569038>] (dump_stack+0xd4/0x118)
Mar 10 21:14:45 raspberrypi kernel: [85731.266957] [<80569038>] (dump_stack) from [<80568550>] (dump_header+0xc0/0x1ec)
Mar 10 21:14:45 raspberrypi kernel: [85731.266984] [<80568550>] (dump_header) from [<800f99b8>] (oom_kill_process+0x23c/0x460)
Mar 10 21:14:45 raspberrypi kernel: [85731.267014] [<800f99b8>] (oom_kill_process) from [<800f9ed4>] (__out_of_memory+0x278/0x3a8)
Mar 10 21:14:45 raspberrypi kernel: [85731.267037] [<800f9ed4>] (__out_of_memory) from [<800fa1ec>] (out_of_memory+0x68/0x80)
Mar 10 21:14:45 raspberrypi kernel: [85731.267062] [<800fa1ec>] (out_of_memory) from [<800febc4>] (__alloc_pages_nodemask+0x858/0x8f0)
Mar 10 21:14:45 raspberrypi kernel: [85731.267087] [<800febc4>] (__alloc_pages_nodemask) from [<800f847c>] (filemap_fault+0x238/0x4e0)
Mar 10 21:14:45 raspberrypi kernel: [85731.267114] [<800f847c>] (filemap_fault) from [<8011dcec>] (__do_fault+0x4c/0xb4)
Mar 10 21:14:45 raspberrypi kernel: [85731.267141] [<8011dcec>] (__do_fault) from [<80121458>] (handle_mm_fault+0x840/0x10c0)
Mar 10 21:14:45 raspberrypi kernel: [85731.267165] [<80121458>] (handle_mm_fault) from [<8056edec>] (do_page_fault+0x2e4/0x398)
Mar 10 21:14:45 raspberrypi kernel: [85731.267191] [<8056edec>] (do_page_fault) from [<800092a8>] (do_PrefetchAbort+0x44/0xa8)
Mar 10 21:14:45 raspberrypi kernel: [85731.267213] [<800092a8>] (do_PrefetchAbort) from [<8056e9a4>] (ret_from_exception+0x0/0x1c)
Mar 10 21:14:45 raspberrypi kernel: [85731.267228] Exception stack(0xb741ffb0 to 0xb741fff8)
Mar 10 21:14:45 raspberrypi kernel: [85731.267246] ffa0:                                     fffffffc 7ede6c5c 00000000 00000000
Mar 10 21:14:45 raspberrypi kernel: [85731.267265] ffc0: 00000000 000dcdf0 000dd914 0000008e 00000001 000dd914 000dc9a0 000dcde4
Mar 10 21:14:45 raspberrypi kernel: [85731.267283] ffe0: 00000000 7ede6c38 00021834 76ccca40 40000010 ffffffff
Mar 10 21:14:45 raspberrypi kernel: [85731.267294] Mem-Info:
Mar 10 21:14:45 raspberrypi kernel: [85731.267325] active_anon:111668 inactive_anon:111712 isolated_anon:0
Mar 10 21:14:45 raspberrypi kernel: [85731.267325]  active_file:79 inactive_file:96 isolated_file:20
Mar 10 21:14:45 raspberrypi kernel: [85731.267325]  unevictable:0 dirty:0 writeback:2 unstable:0
Mar 10 21:14:45 raspberrypi kernel: [85731.267325]  slab_reclaimable:2444 slab_unreclaimable:2108
Mar 10 21:14:45 raspberrypi kernel: [85731.267325]  mapped:87 shmem:294 pagetables:619 bounce:0
Mar 10 21:14:45 raspberrypi kernel: [85731.267325]  free:2029 free_pcp:565 free_cma:0
Mar 10 21:14:45 raspberrypi kernel: [85731.267389] Normal free:8116kB min:8192kB low:10240kB high:12288kB active_anon:446672kB inactive_anon:446848kB active_file:316kB inactive_file:384kB unevictable:0kB isolated(anon):0kB isolated(file):80kB present:966656kB managed:948060kB mlocked:0kB dirty:0kB writeback:8kB mapped:348kB shmem:1176kB slab_reclaimable:9776kB slab_unreclaimable:8432kB kernel_stack:1000kB pagetables:2476kB unstable:0kB bounce:0kB free_pcp:2260kB local_pcp:592kB free_cma:0kB writeback_tmp:0kB pages_scanned:4904 all_unreclaimable? yes
Mar 10 21:14:45 raspberrypi kernel: [85731.267402] lowmem_reserve[]: 0 0
Mar 10 21:14:45 raspberrypi kernel: [85731.267420] Normal: 153*4kB (U) 2*8kB (R) 1*16kB (R) 1*32kB (R) 1*64kB (R) 2*128kB (R) 2*256kB (R) 1*512kB (R) 0*1024kB 1*2048kB (R) 1*4096kB (R) = 8164kB
Mar 10 21:14:45 raspberrypi kernel: [85731.267522] 579 total pagecache pages
Mar 10 21:14:45 raspberrypi kernel: [85731.267536] 4 pages in swap cache
Mar 10 21:14:45 raspberrypi kernel: [85731.267549] Swap cache stats: add 30380, delete 30376, find 3017/3263
Mar 10 21:14:45 raspberrypi kernel: [85731.267559] Free swap  = 0kB
Mar 10 21:14:45 raspberrypi kernel: [85731.267568] Total swap = 102396kB
Mar 10 21:14:45 raspberrypi kernel: [85731.267577] 241664 pages RAM
Mar 10 21:14:45 raspberrypi kernel: [85731.267586] 0 pages HighMem/MovableOnly
Mar 10 21:14:45 raspberrypi kernel: [85731.267596] 2601 pages reserved
Mar 10 21:14:45 raspberrypi kernel: [85731.267606] 2048 pages cma reserved
Mar 10 21:14:45 raspberrypi kernel: [85731.267616] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
Mar 10 21:14:45 raspberrypi kernel: [85731.267645] [  104]     0   104     2487       33       7       0       30             0 systemd-journal
Mar 10 21:14:45 raspberrypi kernel: [85731.267664] [  106]     0   106     2829       25       8       0       88         -1000 systemd-udevd
Mar 10 21:14:45 raspberrypi kernel: [85731.267683] [  328]     0   328     1268       10       6       0       40             0 cron
Mar 10 21:14:45 raspberrypi kernel: [85731.267702] [  330]     0   330      849       21       6       0       31             0 systemd-logind
Mar 10 21:14:45 raspberrypi kernel: [85731.267722] [  334]   106   334      969       46       7       0       22             0 avahi-daemon
Mar 10 21:14:45 raspberrypi kernel: [85731.267741] [  337]   105   337     1373       48       6       0       36          -900 dbus-daemon
Mar 10 21:14:45 raspberrypi kernel: [85731.267759] [  359] 65534   359      573        5       5       0       25             0 thd
Mar 10 21:14:45 raspberrypi kernel: [85731.267887] [  364]   106   364      969       13       5       0       43             0 avahi-daemon
Mar 10 21:14:45 raspberrypi kernel: [85731.267924] [  407]     0   407     8036       55       8       0      123             0 rsyslogd
Mar 10 21:14:45 raspberrypi kernel: [85731.267957] [  568]     0   568      641       25       5       0       37             0 dhcpcd
Mar 10 21:14:45 raspberrypi kernel: [85731.267990] [  569]     0   569     1965       23       7       0       92         -1000 sshd
Mar 10 21:14:45 raspberrypi kernel: [85731.268009] [  627]   107   627     1442       29       7       0       78             0 ntpd
Mar 10 21:14:45 raspberrypi kernel: [85731.268026] [  629]     0   629     1013        1       7       0       32             0 agetty
Mar 10 21:14:45 raspberrypi kernel: [85731.268045] [  630]     0   630      968        1       5       0       32             0 agetty
Mar 10 21:14:45 raspberrypi kernel: [85731.268065] [17128]   109 17128   327432   222481     524       0    17592             0 tvheadend
Mar 10 21:14:45 raspberrypi kernel: [85731.268082] Out of memory: Kill process 17128 (tvheadend) score 916 or sacrifice child
Mar 10 21:14:45 raspberrypi kernel: [85731.268109] Killed process 17128 (tvheadend) total-vm:1309728kB, anon-rss:889924kB, file-rss:0kB

I will try to revert back to 4.1-1576 over the weekend and see if this happen again with the old version.

#2

Updated by g siviero over 4 years ago

I installed 4.1-1659~g55db3a5 and stressed the system with multiple simultaneous recording (3 on 3 different muxes).

From what I see with top I can notice that, while CPU remains at about 60%, memory usage continues to grow as time passes:

PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND
31034 hts 20 0 691956 297900 6704 S 61,7 31,4 6:09.13 tvheadend
31034 hts 20 0 713460 318620 6704 S 62,1 33,6 6:40.27 tvheadend
31034 hts 20 0 726772 331772 6600 S 58,8 35,0 6:58.53 tvheadend
31034 hts 20 0 735988 342416 6600 S 56,6 36,1 7:13.02 tvheadend
31034 hts 20 0 755444 360116 6600 S 61,4 38,0 7:36.86 tvheadend
31034 hts 20 0 792308 398152 6600 S 60,1 42,0 8:28.30 tvheadend
31034 hts 20 0 803572 408080 6328 S 59,4 43,0 8:44.66 tvheadend
31034 hts 20 0 823028 426252 5168 S 59,1 45,0 9:16.91 tvheadend
31034 hts 20 0 844532 445876 4208 S 59,4 47,0 9:44.76 tvheadend
31034 hts 20 0 863988 464132 2460 S 60,4 49,0 10:14.03 tvheadend
31034 hts 20 0 884468 493012 2460 S 57,9 52,0 10:48.87 tvheadend
31034 hts 20 0 915188 522188 2100 S 63,1 55,1 11:23.88 tvheadend
31034 hts 20 0 963316 569836 1228 S 61,7 60,1 12:23.59 tvheadend
31034 hts 20 0 1009396 615984 1228 S 58,7 65,0 13:23.39 tvheadend
31034 hts 20 0 1057524 663304 1140 S 61,1 70,0 14:32.45 tvheadend
31034 hts 20 0 1152756 758672 1032 S 62,4 80,0 16:41.64 tvheadend
31034 hts 20 0 1247900 853660 1708 S 59,4 90,0 18:17.45 tvheadend
31034 hts 20 0 1338908 886596 0 S 62,1 93,5 19:27.89 tvheadend

and ... Boom. Out of memory. :(

#3

Updated by g siviero over 4 years ago

Actually this time the Pi 2 has become completely unresponsive and I'm afraid a hard reboot (unplug/re-plug) will be needed.

#4

Updated by Jaroslav Kysela over 4 years ago

You may try to look which thread is busy with 'top -H -p <pid>' . The RSS should not increase during recording. Do you see the RSS changes only for one stream?

#5

Updated by Jaroslav Kysela over 4 years ago

By the way, stressing latest TVH here with 6 streams (5 muxes) and the RSS is constant - about 72MB now.... 2 live streams, 4 DVR.

#6

Updated by g siviero over 4 years ago

It looks like the values increase even with one stream (still 4.1-1659~g55db3a5):

top - 16:03:39 up  2:46,  1 user,  load average: 1,19, 0,99, 0,49
Threads:  42 total,   0 running,  42 sleeping,   0 stopped,   0 zombie
%Cpu(s):  2,4 us, 10,8 sy,  0,0 ni, 71,5 id, 11,3 wa,  0,0 hi,  4,0 si,  0,0 st
KiB Mem:    948060 total,   386648 used,   561412 free,    27360 buffers
KiB Swap:   102396 total,        0 used,   102396 free.   220288 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND                                                                                                           
 2943 hts       20   0  471588  94592   6156 D 16,4 10,0   0:40.59 tvh:dvr                                                                                                           
 2944 hts       20   0  471588  94592   6156 S  6,6 10,0   0:17.80 tvh:lnxdvb-fron                                                                                                   
 2931 hts       20   0  471588  94592   6156 S  5,9 10,0   0:15.98 tvh:mi-main                                                                                                       
 2940 hts       20   0  471588  94592   6156 S  1,0 10,0   0:02.52 tvh:tcp-start                                                                                                     
 2677 hts       20   0  471588  94592   6156 S  0,0 10,0   0:03.07 tvheadend                                                                                                         
 2678 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.07 tvh:log                                                                                                           
 2679 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.02 tvh:notify                                                                                                        
 2680 hts       35  15  471588  94592   6156 S  0,0 10,0   0:00.04 tvh:save                                                                                                          
 2681 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.04 tvheadend                                                                                                         
 2697 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.14 tvh:mtick                                                                                                         
 2698 hts       39  19  471588  94592   6156 S  0,0 10,0   0:03.68 tvh:tasklet                                                                                                       
 2699 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:fsmonitor                                                                                                     
 2700 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:imagecache                                                                                                    
 2701 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:httpc                                                                                                         
 2702 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:service                                                                                                       
 2703 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:iptv                                                                                                          
 2708 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.05 tvheadend                                                                                                         
 2709 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.08 tvh:hdhm-disc                                                                                                     
 2710 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:tshift-reap                                                                                                   
 2711 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:tcp-loop                                                                                                      
 2712 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.05 tvh:upnp                                                                                                          
 2713 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:svcmap                                                                                                        
 2914 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:epggrabso                                                                                                     
 2915 hts       20   0  471588  94592   6156 S  0,0 10,0   0:02.11 tvh:epggrabi                                                                                                      
 2916 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:dvr-inotify                                                                                                   
 2917 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.08 tvh:satip-rtcp                                                                                                    
 2918 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:avahi                                                                                                         
 2919 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.83 tvh:mtimer                                                                                                        
 2920 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.15 tvh:tcp-start                                                                                                     
 2922 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.36 tvh:mi-table                                                                                                      
 2923 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.16 tvh:mi-main                                                                                                       
 2924 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.26 tvh:mi-table                                                                                                      
 2925 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.15 tvh:mi-main                                                                                                       
 2926 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:mi-table                                                                                                      
 2927 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:mi-main                                                                                                       
 2928 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.32 tvh:mi-table                                                                                                      
 2929 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.15 tvh:mi-main                                                                                                       
 2930 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.72 tvh:mi-table                                                                                                      
 2936 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.10 tvh:tcp-start                                                                                                     
 2938 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:tcp-start                                                                                                     
 2939 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.00 tvh:tcp-start                                                                                                     
 2941 hts       20   0  471588  94592   6156 S  0,0 10,0   0:00.03 tvh:tcp-start
#7

Updated by g siviero over 4 years ago

With 4.1-1576~g52ed773 the values increase but at a much much slower rate.
After the same amount of time (approx 16 mins) the mem usage is just 2,6% and stable compared to the 10% and growing with 4.1-1659~g55db3a5.

top - 16:15:58 up  2:58,  1 user,  load average: 0,31, 0,52, 0,57
Threads:  35 total,   2 running,  33 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0,3 us,  2,4 sy,  0,0 ni, 95,6 id,  1,0 wa,  0,0 hi,  0,7 si,  0,0 st
KiB Mem:    948060 total,   771216 used,   176844 free,    28040 buffers
KiB Swap:   102396 total,        0 used,   102396 free.   676716 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND                                                                                                           
 3397 hts       20   0  359568  24232   6572 R  4,0  2,6   0:21.43 tvh:lnxdvb-fron                                                                                                   
 3390 hts       20   0  359568  24232   6572 R  2,0  2,6   0:11.40 tvh:dvr                                                                                                           
 3321 hts       20   0  359568  24232   6572 S  1,3  2,6   0:05.86 tvh:mi-main                                                                                                       
 3399 hts       20   0  359568  24232   6572 S  0,3  2,6   0:02.69 tvh:tcp-start                                                                                                     
 3096 hts       20   0  359568  24232   6572 S  0,0  2,6   0:06.38 tvheadend                                                                                                         
 3097 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.04 tvh:log                                                                                                           
 3098 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.03 tvh:notify                                                                                                        
 3099 hts       35  15  359568  24232   6572 S  0,0  2,6   0:00.38 tvh:save                                                                                                          
 3100 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.07 tvheadend                                                                                                         
 3259 hts       39  19  359568  24232   6572 S  0,0  2,6   0:00.03 tvh:tasklet                                                                                                       
 3260 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:fsmonitor                                                                                                     
 3265 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:imagecache                                                                                                    
 3266 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:httpc                                                                                                         
 3268 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:service                                                                                                       
 3310 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:mi-table                                                                                                      
 3311 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:mi-main                                                                                                       
 3312 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:iptv                                                                                                          
 3314 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.21 tvh:mi-table                                                                                                      
 3315 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.04 tvh:mi-main                                                                                                       
 3317 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.33 tvh:mi-table                                                                                                      
 3318 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.04 tvh:mi-main                                                                                                       
 3320 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.93 tvh:mi-table                                                                                                      
 3323 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.20 tvh:mi-table                                                                                                      
 3324 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.03 tvh:mi-main                                                                                                       
 3325 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.08 tvheadend                                                                                                         
 3326 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.10 tvh:hdhm-disc                                                                                                     
 3327 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:tshift-reap                                                                                                   
 3328 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:tcp-loop                                                                                                      
 3329 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.06 tvh:upnp                                                                                                          
 3330 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:svcmap                                                                                                        
 3385 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:epggrabso                                                                                                     
 3386 hts       20   0  359568  24232   6572 S  0,0  2,6   0:02.00 tvh:epggrabi                                                                                                      
 3387 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:dvr-inotify                                                                                                   
 3388 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.11 tvh:satip-rtcp                                                                                                    
 3389 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.01 tvh:avahi
#8

Updated by g siviero over 4 years ago

The running time is about 3 minutes by the way...

#9

Updated by g siviero over 4 years ago

With 4.1-1576~g52ed773 the memory usage does not increase after it has stabilized.

top - 16:24:29 up  3:07,  1 user,  load average: 0,42, 0,36, 0,46
Threads:  35 total,   0 running,  35 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0,6 us,  3,3 sy,  0,0 ni, 93,4 id,  1,1 wa,  0,0 hi,  1,5 si,  0,0 st
KiB Mem:    948060 total,   935864 used,    12196 free,    28072 buffers
KiB Swap:   102396 total,        0 used,   102396 free.   840716 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND                                                                                                           
 3397 hts       20   0  359568  24232   6572 S  4,9  2,6   0:43.94 tvh:lnxdvb-fron                                                                                                   
 3390 hts       20   0  359568  24232   6572 S  3,0  2,6   0:23.00 tvh:dvr                                                                                                           
 3321 hts       20   0  359568  24232   6572 S  1,3  2,6   0:11.90 tvh:mi-main                                                                                                       
 3096 hts       20   0  359568  24232   6572 S  0,3  2,6   0:06.87 tvheadend                                                                                                         
 3399 hts       20   0  359568  24232   6572 S  0,3  2,6   0:04.98 tvh:tcp-start                                                                                                     
 3097 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.04 tvh:log                                                                                                           
 3098 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.04 tvh:notify                                                                                                        
 3099 hts       35  15  359568  24232   6572 S  0,0  2,6   0:00.38 tvh:save                                                                                                          
 3100 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.13 tvheadend                                                                                                         
 3259 hts       39  19  359568  24232   6572 S  0,0  2,6   0:00.07 tvh:tasklet                                                                                                       
 3260 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:fsmonitor                                                                                                     
 3265 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:imagecache                                                                                                    
 3266 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:httpc                                                                                                         
 3268 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:service                                                                                                       
 3310 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:mi-table                                                                                                      
 3311 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:mi-main                                                                                                       
 3312 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:iptv                                                                                                          
 3314 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.21 tvh:mi-table                                                                                                      
 3315 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.04 tvh:mi-main                                                                                                       
 3317 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.33 tvh:mi-table                                                                                                      
 3318 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.04 tvh:mi-main                                                                                                       
 3320 hts       20   0  359568  24232   6572 S  0,0  2,6   0:01.89 tvh:mi-table                                                                                                      
 3323 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.20 tvh:mi-table                                                                                                      
 3324 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.03 tvh:mi-main                                                                                                       
 3325 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.17 tvheadend                                                                                                         
 3326 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.21 tvh:hdhm-disc                                                                                                     
 3327 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:tshift-reap                                                                                                   
 3328 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:tcp-loop                                                                                                      
 3329 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.13 tvh:upnp                                                                                                          
 3330 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:svcmap                                                                                                        
 3385 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:epggrabso                                                                                                     
 3386 hts       20   0  359568  24232   6572 S  0,0  2,6   0:02.00 tvh:epggrabi                                                                                                      
 3387 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.00 tvh:dvr-inotify                                                                                                   
 3388 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.24 tvh:satip-rtcp                                                                                                    
 3389 hts       20   0  359568  24232   6572 S  0,0  2,6   0:00.01 tvh:avahi
#10

Updated by Jaroslav Kysela over 4 years ago

Which DVR profile do you use ? Matroska / passthrough ?

#11

Updated by Jaroslav Kysela over 4 years ago

If you do live streaming, the memory increases, too?

#12

Updated by g siviero over 4 years ago

I use TS passthrough (of mpeg2 SD channels usually).
The recordings are made on a NFS share via ethernet.
I rarely use live streaming (on android terminals), but I will try and see what happens in the next few days if needed.

#13

Updated by Jaroslav Kysela over 4 years ago

Just copy the play URL to cmd line and use curl or wget to put the stream to /dev/null like:

wget -O /dev/null URL

My favourite :-)

#14

Updated by g siviero over 4 years ago

With the streaming (using wget) the memory does not increase:

wget -O /dev/null http://192.168.1.22:9981/play/stream/channel/5c00f8ef0fef4d68d70cd983a326433f?title=WILD%20CANADA
--2016-03-12 11:12:11--  http://192.168.1.22:9981/play/stream/channel/5c00f8ef0fef4d68d70cd983a326433f?title=WILD%20CANADA
Connessione a 192.168.1.22:9981...connesso.
Richiesta HTTP inviata, in attesa di risposta... 200 OK
Lunghezza: non specificato [video/mp2t]
Salvataggio in: "/dev/null" 

/dev/null               [ <=>                  ]  38,66M   541KB/s
top - 11:13:45 up 21:56,  2 users,  load average: 0,12, 0,25, 0,21
Threads:  42 total,   0 running,  42 sleeping,   0 stopped,   0 zombie
%Cpu(s):  1,5 us,  2,7 sy,  0,0 ni, 94,8 id,  0,0 wa,  0,0 hi,  0,9 si,  0,0 st
KiB Mem:    948060 total,   930836 used,    17224 free,    21004 buffers
KiB Swap:   102396 total,        0 used,   102396 free.   835740 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND
14372 hts       20   0  382080  22152   6612 S  7,0  2,3   0:06.76 tvh:tcp-start
14373 hts       20   0  382080  22152   6612 S  6,0  2,3   0:05.80 tvh:lnxdvb-fron
14332 hts       20   0  382080  22152   6612 S  4,0  2,3   0:03.89 tvh:mi-main
14367 hts       20   0  382080  22152   6612 S  2,0  2,3   0:00.21 tvh:tcp-start
14331 hts       20   0  382080  22152   6612 S  0,3  2,3   0:00.40 tvh:mi-table
14088 hts       20   0  382080  22152   6612 S  0,0  2,3   0:03.08 tvheadend
14089 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.03 tvh:log
14090 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.02 tvh:notify
14091 hts       35  15  382080  22152   6612 S  0,0  2,3   0:00.08 tvh:save
14092 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.04 tvheadend
14292 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.12 tvh:mtick
14293 hts       39  19  382080  22152   6612 S  0,0  2,3   0:03.49 tvh:tasklet
14294 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:fsmonitor
14295 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:imagecache
14296 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:httpc
14297 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:service
14298 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:iptv
14303 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.04 tvheadend
14304 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.05 tvh:hdhm-disc
14305 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:tshift-reap
14306 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:tcp-loop
14307 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.03 tvh:upnp
14308 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:svcmap
14322 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:epggrabso
14323 hts       20   0  382080  22152   6612 S  0,0  2,3   0:02.00 tvh:epggrabi
14324 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:dvr-inotify
14325 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.06 tvh:satip-rtcp
14326 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:avahi
14327 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.64 tvh:mtimer
14328 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.02 tvh:tcp-start
14329 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.35 tvh:mi-table
14330 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.16 tvh:mi-main
14333 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:mi-table
14334 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:mi-main
14335 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.32 tvh:mi-table
14336 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.11 tvh:mi-main
14337 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.22 tvh:mi-table
14338 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.13 tvh:mi-main
14343 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.53 tvh:tcp-start
14344 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.28 tvh:tcp-start
14368 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.00 tvh:tcp-start
14369 hts       20   0  382080  22152   6612 S  0,0  2,3   0:00.22 tvh:tcp-start
#15

Updated by Jaroslav Kysela over 4 years ago

Could you compile tvh with this change? (Just for test.) V2:

diff --git a/src/dvr/dvr_rec.c b/src/dvr/dvr_rec.c
index e5868cc..ecd466e 100644
--- a/src/dvr/dvr_rec.c
+++ b/src/dvr/dvr_rec.c
@@ -1280,7 +1280,8 @@ dvr_thread(void *aux)
           epg_running = 2;
       } else if (running_stop == 0) {
         if (start_time + 2 >= gclk()) {
-          TAILQ_INSERT_TAIL(&backlog, sm, sm_link);
+          streaming_msg_free(sm);
+          //TAILQ_INSERT_TAIL(&backlog, sm, sm_link);
           continue;
         } else {
           if (TAILQ_FIRST(&backlog))
#16

Updated by Jaroslav Kysela over 4 years ago

Eventually, you may try to disable 'EPG running state' in the DVR config (I suppose that you have this flag checked).

#17

Updated by g siviero over 4 years ago

No, it's already unchecked for all my DVR profiles.

#18

Updated by Jaroslav Kysela over 4 years ago

The files are available for recordings with the adequate size ?

#19

Updated by g siviero over 4 years ago

Sometimes I found in DVR "Finished recordings" some (corrupted) files with size about 400Kb instead of for example 1.7GB if that's what you meant.
But in this particular out-of-memory case, from the tests I made, the files are correctly recorded up until when the program or system terminates/crashes.
But I can't rule out that with some particular sequence or combination of recording there would be other errors noticeable.

#20

Updated by Jaroslav Kysela over 4 years ago

I put to the latest more debugging info about the memory allocations (v4.1-1671-g41b5def) . Could you look, if the packet counters increase when DVR is used ? It's in configuration / debugging / memory info tab..

#21

Updated by g siviero over 4 years ago

Trying 4.1-1672~g3927788.

1st try:
2 recordings
recording 1 going ok
recording 2 KO (not recording correctly, 200b file)
memory not increasing, packets not increasing (all 0).

2nd try:
2 recordings
recording 1 going ok
recording 2 going ok
memory increasing, packets not increasing (all 0).

2016-03-14 10:06:17.586 subscription: 002B: "DVR: WILD ARABIA " subscribing on channel "Rai 5", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "546MHz", provider: "Rai", service: "Rai 5", profile="pass" 
2016-03-14 10:06:18.697 dvr: /mnt/tv/Record/Rai-5/WILD-ARABIA-.2016-03-14.09-54.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "546MHz", provider: "Rai", service: "Rai 5" 
2016-03-14 10:06:18.697 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 10:06:18.697 dvr:  1  MPEG2VIDEO              ?           ?                                    
2016-03-14 10:06:18.697 dvr:  2  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 10:06:18.697 dvr:  3  TELETEXT                                                                 
2016-03-14 10:06:18.698 dvr:  4  TEXTSUB           ita                                                    
2016-03-14 10:06:18.698 dvr:  5  TEXTSUB           eng                                                    
Loglevel debug: enabled
2016-03-14 10:06:40.544 epggrab: EIT: DVB Grabber - data completion timeout for 594MHz in DVB-T Italy
2016-03-14 10:06:40.544 subscription: 0004: "epggrab" unsubscribing
2016-03-14 10:06:41.566 mpegts: 626MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:06:41.569 subscription: 002D: "epggrab" subscribing to mux "626MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:06:52.175 epggrab: EIT: DVB Grabber - data completion timeout for 506MHz in DVB-T Italy
2016-03-14 10:06:52.175 subscription: 000F: "epggrab" unsubscribing
2016-03-14 10:06:53.180 mpegts: 177.5MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:06:53.181 subscription: 002F: "epggrab" subscribing to mux "177.5MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:06:54.204 TS: DVB-T Italy/546MHz/Rai 5: MPEG2VIDEO @ #234 Continuity counter error (total 1)
2016-03-14 10:07:02.013 subscription: 0029: "epggrab" unsubscribing
2016-03-14 10:07:02.986 mpegts: 514MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:07:02.987 subscription: 0031: "epggrab" subscribing to mux "514MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:07:04.090 subscription: 002F: "epggrab" unsubscribing
2016-03-14 10:07:05.090 mpegts: 658MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:07:05.093 subscription: 0033: "epggrab" subscribing to mux "658MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:07:05.836 TS: DVB-T Italy/546MHz/Rai 5: MPEG2VIDEO @ #234 Continuity counter error (total 2)
2016-03-14 10:07:15.700 subscription: 0033: "epggrab" unsubscribing
2016-03-14 10:07:16.703 mpegts: 698MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:07:16.703 subscription: 0035: "epggrab" subscribing to mux "698MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:07:17.482 TS: DVB-T Italy/546MHz/Rai 5: MPEG2VIDEO @ #234 Continuity counter error (total 3)
2016-03-14 10:07:22.584 epggrab: EIT: DVB Grabber - data completion timeout for 546MHz in DVB-T Italy
2016-03-14 10:07:33.000 /usr/bin/tv_grab_file: grab /usr/bin/tv_grab_file
2016-03-14 10:07:33.022 spawn: Executing "/usr/bin/tv_grab_file" 
2016-03-14 10:07:33.427 /usr/bin/tv_grab_file: grab took 0 seconds
2016-03-14 10:07:35.109 /usr/bin/tv_grab_file: parse took 2 seconds
2016-03-14 10:07:35.109 /usr/bin/tv_grab_file:   channels   tot=   24 new=    0 mod=    0
2016-03-14 10:07:35.109 /usr/bin/tv_grab_file:   brands     tot=    0 new=    0 mod=    0
2016-03-14 10:07:35.109 /usr/bin/tv_grab_file:   seasons    tot=    0 new=    0 mod=    0
2016-03-14 10:07:35.109 /usr/bin/tv_grab_file:   episodes   tot= 6244 new=   16 mod=   92
2016-03-14 10:07:35.109 /usr/bin/tv_grab_file:   broadcasts tot= 6244 new=   17 mod=   58
2016-03-14 10:07:45.899 dvr: entry c0976a007d95c0117a4a42b2d235d52a "Oggy e i maledetti scarafaggi" on "frisbee" starting at 2016-03-14 09:40:30, scheduled for recording by "admin" 
2016-03-14 10:07:45.900 dvr: "Oggy e i maledetti scarafaggi" on "frisbee" recorder starting
2016-03-14 10:07:45.901 mpegts: 690MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:07:45.903 subscription: 0035: "epggrab" unsubscribing
2016-03-14 10:07:45.904 subscription: 0038: "DVR: Oggy e i maledetti scarafaggi" subscribing on channel "frisbee", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee", profile="pass" 
2016-03-14 10:07:46.604 epggrab: EIT: DVB Grabber - data completion timeout for 626MHz in DVB-T Italy
2016-03-14 10:07:46.604 subscription: 002D: "epggrab" unsubscribing
2016-03-14 10:07:46.634 TS: DVB-T Italy/546MHz/Rai 5: MPEG2VIDEO @ #234 Continuity counter error (total 4)
2016-03-14 10:07:46.697 dvr: /mnt/tv/Record/frisbee/Oggy-e-i-maledetti-scarafaggi.2016-03-14.10-06.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee" 
2016-03-14 10:07:46.697 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 10:07:46.697 dvr:  1  MPEG2VIDEO              ?           ?                                    
2016-03-14 10:07:46.697 dvr:  2  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 10:07:46.697 dvr:  3  TELETEXT                                                                 
2016-03-14 10:07:46.697 dvr:  4  TEXTSUB           ita                                                    
2016-03-14 10:07:46.864 pass-eit: -: invalid checksum (len 147, errors 1)
2016-03-14 10:07:46.865 eit: 690MHz in DVB-T Italy: invalid checksum (len 147, errors 1)
2016-03-14 10:07:47.031 pat: 690MHz in DVB-T Italy: invalid checksum (len 3215, errors 1)
2016-03-14 10:07:47.571 mpegts: 698MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:07:47.575 subscription: 0039: "epggrab" subscribing to mux "698MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:07:47.745 pass-pat: -: invalid checksum (len 2091, errors 1)
2016-03-14 10:07:57.411 pass-eit: -: invalid checksum (len 84, errors 14)
2016-03-14 10:07:57.411 eit: 690MHz in DVB-T Italy: invalid checksum (len 84, errors 14)
2016-03-14 10:07:58.732 pat: 690MHz in DVB-T Italy: invalid checksum (len 2275, errors 4)
2016-03-14 10:07:58.761 pass-pat: -: invalid checksum (len 2275, errors 3)
2016-03-14 10:08:07.129 pass-sdt: -: invalid checksum (len 3, errors 1)
2016-03-14 10:08:07.129 bat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 1)
2016-03-14 10:08:07.129 sdt: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 1)
2016-03-14 10:08:07.986 epggrab: EIT: DVB Grabber - data completion timeout for 514MHz in DVB-T Italy
2016-03-14 10:08:07.986 subscription: 0031: "epggrab" unsubscribing
2016-03-14 10:08:08.986 mpegts: 602MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:08:08.987 subscription: 003B: "epggrab" subscribing to mux "602MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:08:09.436 pass-eit: -: invalid checksum (len 3, errors 22)
2016-03-14 10:08:09.614 pass-pat: -: invalid checksum (len 43, errors 8)
2016-03-14 10:08:09.784 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 22)
2016-03-14 10:08:09.785 pat: 690MHz in DVB-T Italy: invalid checksum (len 43, errors 9)
2016-03-14 10:08:12.831 cat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 1)
2016-03-14 10:08:19.773 pass-eit: -: invalid checksum (len 3, errors 28)
2016-03-14 10:08:19.773 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 28)
2016-03-14 10:08:20.617 pat: 690MHz in DVB-T Italy: invalid checksum (len 1014, errors 12)
2016-03-14 10:08:20.622 pass-pat: -: invalid checksum (len 1014, errors 11)
2016-03-14 10:08:24.933 cat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 3)
2016-03-14 10:08:28.004 TS: DVB-T Italy/690MHz/frisbee Transport error indicator (total 1)
2016-03-14 10:08:30.181 pass-eit: -: invalid checksum (len 3, errors 31)
2016-03-14 10:08:30.181 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 31)
2016-03-14 10:08:30.817 pat: 690MHz in DVB-T Italy: invalid checksum (len 1283, errors 17)
2016-03-14 10:08:30.835 pass-pat: -: invalid checksum (len 1283, errors 15)
2016-03-14 10:08:40.584 pass-eit: -: invalid checksum (len 3, errors 39)
2016-03-14 10:08:40.584 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 39)
2016-03-14 10:08:43.714 pass-pat: -: invalid checksum (len 103, errors 19)
2016-03-14 10:08:43.775 pat: 690MHz in DVB-T Italy: invalid checksum (len 103, errors 21)
2016-03-14 10:08:44.230 cat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 4)
2016-03-14 10:08:50.186 TS: DVB-T Italy/690MHz/frisbee Transport error indicator (total 3)
2016-03-14 10:08:51.791 pass-eit: -: invalid checksum (len 3, errors 50)
2016-03-14 10:08:51.791 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 50)
2016-03-14 10:08:52.571 epggrab: EIT: DVB Grabber - data completion timeout for 698MHz in DVB-T Italy
2016-03-14 10:08:52.571 subscription: 0039: "epggrab" unsubscribing
2016-03-14 10:08:53.572 mpegts: 682MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:08:53.572 subscription: 003E: "epggrab" subscribing to mux "682MHz", weight: 4, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", service: "Raw PID Subscription" 
2016-03-14 10:08:54.553 TS: DVB-T Italy/690MHz/frisbee: MPEG2VIDEO @ #1457 Continuity counter error (total 1)
2016-03-14 10:09:00.213 pass-pat: -: invalid checksum (len 3, errors 25)
2016-03-14 10:09:00.213 pat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 27)
2016-03-14 10:09:02.864 pass-eit: -: invalid checksum (len 32, errors 57)
2016-03-14 10:09:02.865 eit: 690MHz in DVB-T Italy: invalid checksum (len 32, errors 57)
2016-03-14 10:09:04.161 subscription: 003E: "epggrab" unsubscribing
2016-03-14 10:09:08.032 cat: 690MHz in DVB-T Italy: invalid checksum (len 4, errors 8)
2016-03-14 10:09:11.527 pat: 690MHz in DVB-T Italy: invalid checksum (len 2171, errors 32)
2016-03-14 10:09:11.546 pass-pat: -: invalid checksum (len 2171, errors 30)
2016-03-14 10:09:12.885 pass-eit: -: invalid checksum (len 32, errors 68)
2016-03-14 10:09:12.885 eit: 690MHz in DVB-T Italy: invalid checksum (len 32, errors 68)
2016-03-14 10:09:13.986 epggrab: EIT: DVB Grabber - data completion timeout for 602MHz in DVB-T Italy
2016-03-14 10:09:13.986 subscription: 003B: "epggrab" unsubscribing
2016-03-14 10:09:21.500 subscription: 0038: "DVR: Oggy e i maledetti scarafaggi" unsubscribing from "frisbee" 
2016-03-14 10:09:21.504 dvr: "Oggy e i maledetti scarafaggi" on "frisbee": End of program: Completed OK
2016-03-14 10:09:42.473 dvr: entry 4bac32fd157f9a2ef03633998426a9e0 "Oggy e i maledetti scarafaggi" on "frisbee" starting at 2016-03-14 09:40:30, scheduled for recording by "admin" 
2016-03-14 10:09:42.473 dvr: "Oggy e i maledetti scarafaggi" on "frisbee" recorder starting
2016-03-14 10:09:42.474 mpegts: 690MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:09:42.474 subscription: 003F: "DVR: Oggy e i maledetti scarafaggi" subscribing on channel "frisbee", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee", profile="pass" 
2016-03-14 10:09:43.231 dvr: /mnt/tv/Record/frisbee/Oggy-e-i-maledetti-scarafaggi.2016-03-14.10-06-1.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee" 
2016-03-14 10:09:43.231 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 10:09:43.231 dvr:  1  MPEG2VIDEO              ?           ?                                    
2016-03-14 10:09:43.231 dvr:  2  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 10:09:43.231 dvr:  3  TELETEXT                                                                 
2016-03-14 10:09:43.231 dvr:  4  TEXTSUB           ita                                                    
Loglevel debug: disabled
Loglevel debug: enabled
2016-03-14 10:10:22.608 subscription: 002B: "DVR: WILD ARABIA " unsubscribing from "Rai 5" 
2016-03-14 10:10:22.643 dvr: "WILD ARABIA " on "Rai 5": End of program: Completed OK
2016-03-14 10:10:26.732 subscription: 003F: "DVR: Oggy e i maledetti scarafaggi" unsubscribing from "frisbee" 
2016-03-14 10:10:26.737 dvr: "Oggy e i maledetti scarafaggi" on "frisbee": End of program: Completed OK
2016-03-14 10:10:36.907 dvr: entry 2738d4e3ef461b33ec542e6d47fe103d "WILD ARABIA " on "Rai 5" starting at 2016-03-14 09:29:19, scheduled for recording by "admin" 
2016-03-14 10:10:36.911 dvr: "WILD ARABIA " on "Rai 5" recorder starting
2016-03-14 10:10:36.915 mpegts: 546MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:10:36.915 subscription: 0040: "DVR: WILD ARABIA " subscribing on channel "Rai 5", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "546MHz", provider: "Rai", service: "Rai 5", profile="pass" 
2016-03-14 10:10:37.910 dvr: /mnt/tv/Record/Rai-5/WILD-ARABIA-.2016-03-14.09-54-1.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "546MHz", provider: "Rai", service: "Rai 5" 
2016-03-14 10:10:37.911 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 10:10:37.911 dvr:  1  MPEG2VIDEO              ?           ?                                    
2016-03-14 10:10:37.912 dvr:  2  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 10:10:37.912 dvr:  3  TELETEXT                                                                 
2016-03-14 10:10:37.912 dvr:  4  TEXTSUB           ita                                                    
2016-03-14 10:10:37.912 dvr:  5  TEXTSUB           eng                                                    
2016-03-14 10:10:44.239 dvr: entry d9b5d2f081bb5e3af5ead3acf32793b1 "Oggy e i maledetti scarafaggi" on "frisbee" starting at 2016-03-14 09:40:30, scheduled for recording by "admin" 
2016-03-14 10:10:44.239 dvr: "Oggy e i maledetti scarafaggi" on "frisbee" recorder starting
2016-03-14 10:10:44.245 mpegts: 690MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 10:10:44.245 subscription: 0041: "DVR: Oggy e i maledetti scarafaggi" subscribing on channel "frisbee", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee", profile="pass" 
2016-03-14 10:10:45.028 dvr: /mnt/tv/Record/frisbee/Oggy-e-i-maledetti-scarafaggi.2016-03-14.10-06-2.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee" 
2016-03-14 10:10:45.028 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 10:10:45.028 dvr:  1  MPEG2VIDEO              ?           ?                                    
2016-03-14 10:10:45.029 dvr:  2  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 10:10:45.029 dvr:  3  TELETEXT                                                                 
#22

Updated by Jaroslav Kysela over 4 years ago

Packets will be zero for .TS , but the 'packet buffers' should change..

#23

Updated by g siviero over 4 years ago

When I stop the recording, the memory usage decreases but does not return to the 2-2,5% value before the recordings were made.

top - 10:22:28 up 2 days, 21:05,  1 user,  load average: 0,70, 1,95, 1,64
Threads:  41 total,   0 running,  41 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0,1 us,  0,2 sy,  0,0 ni, 99,7 id,  0,0 wa,  0,0 hi,  0,0 si,  0,0 st
KiB Mem:    948060 total,   903188 used,    44872 free,     8156 buffers
KiB Swap:   102396 total,        4 used,   102392 free.   776604 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND                                                                                                           
 9983 hts       20   0  438016  54368   6620 S  0,0  5,7   0:02.89 tvheadend                                                                                                         
 9984 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.07 tvh:log                                                                                                           
 9985 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.06 tvh:notify                                                                                                        
 9986 hts       35  15  438016  54368   6620 S  0,0  5,7   0:00.09 tvh:save                                                                                                          
 9987 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.14 tvheadend                                                                                                         
10191 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.46 tvh:mtick                                                                                                         
10192 hts       39  19  438016  54368   6620 S  0,0  5,7   0:03.59 tvh:tasklet                                                                                                       
10193 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:fsmonitor                                                                                                     
10194 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:imagecache                                                                                                    
10195 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:httpc                                                                                                         
10196 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:service                                                                                                       
10197 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:iptv                                                                                                          
10203 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.16 tvheadend                                                                                                         
10204 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.26 tvh:hdhm-disc                                                                                                     
10205 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:tshift-reap                                                                                                   
10206 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.05 tvh:tcp-loop                                                                                                      
10207 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.08 tvh:upnp                                                                                                          
10208 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:svcmap                                                                                                        
10224 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:epggrabso                                                                                                     
10225 hts       20   0  438016  54368   6620 S  0,0  5,7   0:01.79 tvh:epggrabi                                                                                                      
10226 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:dvr-inotify                                                                                                   
10229 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.29 tvh:satip-rtcp                                                                                                    
10230 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:avahi                                                                                                         
10231 hts       20   0  438016  54368   6620 S  0,0  5,7   0:02.35 tvh:mtimer                                                                                                        
10233 hts       20   0  438016  54368   6620 S  0,0  5,7   0:06.32 tvh:mi-table                                                                                                      
10234 hts       20   0  438016  54368   6620 S  0,0  5,7   0:21.99 tvh:mi-main                                                                                                       
10235 hts       20   0  438016  54368   6620 S  0,0  5,7   0:03.03 tvh:mi-table                                                                                                      
10236 hts       20   0  438016  54368   6620 S  0,0  5,7   0:29.34 tvh:mi-main                                                                                                       
10237 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:mi-table                                                                                                      
10238 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.00 tvh:mi-main                                                                                                       
10239 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.94 tvh:mi-table                                                                                                      
10240 hts       20   0  438016  54368   6620 S  0,0  5,7   0:09.37 tvh:mi-main                                                                                                       
10241 hts       20   0  438016  54368   6620 S  0,0  5,7   0:01.17 tvh:mi-table                                                                                                      
10242 hts       20   0  438016  54368   6620 S  0,0  5,7   0:02.68 tvh:mi-main                                                                                                       
10254 hts       20   0  438016  54368   6620 S  0,0  5,7   0:03.03 tvh:tcp-start                                                                                                     
10435 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.05 tvh:tcp-start                                                                                                     
10436 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.04 tvh:tcp-start                                                                                                     
10437 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.59 tvh:tcp-start                                                                                                     
10438 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.26 tvh:tcp-start                                                                                                     
10440 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.06 tvh:tcp-start                                                                                                     
10441 hts       20   0  438016  54368   6620 S  0,0  5,7   0:00.01 tvh:tcp-start

If I reboot the PI (initial situation):

top - 10:27:41 up 3 min,  1 user,  load average: 0,07, 0,12, 0,05
Threads:  39 total,   0 running,  39 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0,1 us,  0,2 sy,  0,0 ni, 99,8 id,  0,0 wa,  0,0 hi,  0,0 si,  0,0 st
KiB Mem:    948060 total,   133212 used,   814848 free,    16328 buffers
KiB Swap:   102396 total,        0 used,   102396 free.    60832 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND                                                                                                           
  403 hts       20   0  389684  21092   6216 S  0,0  2,2   0:03.07 tvheadend                                                                                                         
  404 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.03 tvh:log                                                                                                           
  410 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.04 tvh:notify                                                                                                        
  411 hts       35  15  389684  21092   6216 S  0,0  2,2   0:00.09 tvh:save                                                                                                          
  416 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.02 tvheadend                                                                                                         
  417 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.09 tvh:mtick                                                                                                         
  418 hts       39  19  389684  21092   6216 S  0,0  2,2   0:04.38 tvh:tasklet                                                                                                       
  419 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:fsmonitor                                                                                                     
  453 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:imagecache                                                                                                    
  454 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:httpc                                                                                                         
  460 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:service                                                                                                       
  469 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:iptv                                                                                                          
  478 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.03 tvheadend                                                                                                         
  479 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.06 tvh:hdhm-disc                                                                                                     
  480 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:tshift-reap                                                                                                   
  481 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.01 tvh:tcp-loop                                                                                                      
  482 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.01 tvh:upnp                                                                                                          
  483 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:svcmap                                                                                                        
  650 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:epggrabso                                                                                                     
  651 hts       20   0  389684  21092   6216 S  0,0  2,2   0:01.69 tvh:epggrabi                                                                                                      
  652 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:dvr-inotify                                                                                                   
  653 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:avahi                                                                                                         
  654 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.46 tvh:mtimer                                                                                                        
  656 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.12 tvh:mi-table                                                                                                      
  657 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.02 tvh:mi-main                                                                                                       
  658 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:mi-table                                                                                                      
  659 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:mi-main                                                                                                       
  660 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.35 tvh:mi-table                                                                                                      
  661 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.04 tvh:mi-main                                                                                                       
  662 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.10 tvh:mi-table                                                                                                      
  663 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.03 tvh:mi-main                                                                                                       
  664 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.23 tvh:mi-table                                                                                                      
  665 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.04 tvh:mi-main                                                                                                       
  666 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.04 tvh:tcp-start                                                                                                     
  667 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.59 tvh:tcp-start                                                                                                     
  735 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.02 tvh:tcp-start                                                                                                     
  736 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.14 tvh:tcp-start                                                                                                     
  737 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.00 tvh:tcp-start                                                                                                     
  738 hts       20   0  389684  21092   6216 S  0,0  2,2   0:00.88 tvh:tcp-start

Now I'm still using 4.1-1672~g3927788 and recording 3 channels from 3 different muxes and memory is NOT increasing!
I didn't anything apart from a reboot.

top - 10:33:02 up 9 min,  1 user,  load average: 1,47, 0,59, 0,24
Threads:  45 total,   0 running,  45 sleeping,   0 stopped,   0 zombie
%Cpu(s):  1,1 us,  7,1 sy,  0,0 ni, 81,5 id,  6,5 wa,  0,0 hi,  3,9 si,  0,0 st
KiB Mem:    948060 total,   300132 used,   647928 free,    16464 buffers
KiB Swap:   102396 total,        0 used,   102396 free.   221568 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND                                                                                                           
  757 hts       20   0  399120  22488   6600 S  5,0  2,4   0:04.01 tvh:dvr                                                                                                           
  755 hts       20   0  399120  22488   6600 S  4,0  2,4   0:07.67 tvh:lnxdvb-fron                                                                                                   
  758 hts       20   0  399120  22488   6600 S  3,6  2,4   0:02.90 tvh:lnxdvb-fron                                                                                                   
  753 hts       20   0  399120  22488   6600 S  3,3  2,4   0:06.41 tvh:lnxdvb-fron                                                                                                   
  752 hts       20   0  399120  22488   6600 S  2,3  2,4   0:04.30 tvh:dvr                                                                                                           
  754 hts       20   0  399120  22488   6600 S  2,0  2,4   0:03.03 tvh:dvr                                                                                                           
  738 hts       20   0  399120  22488   6600 S  1,0  2,4   0:01.84 tvh:tcp-start                                                                                                     
  656 hts       20   0  399120  22488   6600 S  0,7  2,4   0:00.47 tvh:mi-table                                                                                                      
  657 hts       20   0  399120  22488   6600 S  0,7  2,4   0:01.11 tvh:mi-main                                                                                                       
  660 hts       20   0  399120  22488   6600 S  0,7  2,4   0:00.72 tvh:mi-table                                                                                                      
  661 hts       20   0  399120  22488   6600 S  0,7  2,4   0:00.58 tvh:mi-main                                                                                                       
  654 hts       20   0  399120  22488   6600 S  0,3  2,4   0:00.83 tvh:mtimer                                                                                                        
  663 hts       20   0  399120  22488   6600 S  0,3  2,4   0:01.06 tvh:mi-main                                                                                                       
  403 hts       20   0  399120  22488   6600 S  0,0  2,4   0:03.09 tvheadend                                                                                                         
  404 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.04 tvh:log                                                                                                           
  410 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.05 tvh:notify                                                                                                        
  411 hts       35  15  399120  22488   6600 S  0,0  2,4   0:00.10 tvh:save                                                                                                          
  416 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.06 tvheadend                                                                                                         
  417 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.22 tvh:mtick                                                                                                         
  418 hts       39  19  399120  22488   6600 S  0,0  2,4   0:04.40 tvh:tasklet                                                                                                       
  419 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:fsmonitor                                                                                                     
  453 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:imagecache                                                                                                    
  454 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:httpc                                                                                                         
  460 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:service                                                                                                       
  469 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:iptv                                                                                                          
  478 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.08 tvheadend                                                                                                         
  479 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.12 tvh:hdhm-disc                                                                                                     
  480 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:tshift-reap                                                                                                   
  481 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.01 tvh:tcp-loop                                                                                                      
  482 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.02 tvh:upnp                                                                                                          
  483 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:svcmap                                                                                                        
  650 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:epggrabso                                                                                                     
  651 hts       20   0  399120  22488   6600 S  0,0  2,4   0:01.69 tvh:epggrabi                                                                                                      
  652 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:dvr-inotify                                                                                                   
  653 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:avahi                                                                                                         
  658 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:mi-table                                                                                                      
  659 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.00 tvh:mi-main                                                                                                       
  662 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.36 tvh:mi-table                                                                                                      
  664 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.23 tvh:mi-table                                                                                                      
  665 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.04 tvh:mi-main                                                                                                       
  666 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.20 tvh:tcp-start                                                                                                     
  735 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.19 tvh:tcp-start                                                                                                     
  737 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.11 tvh:tcp-start                                                                                                     
  749 hts       20   0  399120  22488   6600 S  0,0  2,4   0:00.66 tvh:tcp-start

Stopped the recordings, made other 3 and still no issue.
Even with 4 recordings...

top - 10:38:44 up 14 min,  1 user,  load average: 1,32, 0,85, 0,45
Threads:  44 total,   0 running,  44 sleeping,   0 stopped,   0 zombie
%Cpu(s):  2,6 us,  9,5 sy,  0,0 ni, 69,2 id, 12,3 wa,  0,0 hi,  6,3 si,  0,0 st
KiB Mem:    948060 total,   683820 used,   264240 free,    16584 buffers
KiB Swap:   102396 total,        0 used,   102396 free.   602696 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND                                                                                                           
  800 hts       20   0  399212  22716   6600 S  5,3  2,4   0:07.48 tvh:dvr                                                                                                           
  886 hts       20   0  399212  22716   6600 S  4,6  2,4   0:00.89 tvh:dvr                                                                                                           
  807 hts       20   0  399212  22716   6600 S  4,3  2,4   0:05.58 tvh:lnxdvb-fron                                                                                                   
  801 hts       20   0  399212  22716   6600 S  4,0  2,4   0:05.57 tvh:lnxdvb-fron                                                                                                   
  804 hts       20   0  399212  22716   6600 S  4,0  2,4   0:05.42 tvh:lnxdvb-fron                                                                                                   
  887 hts       20   0  399212  22716   6600 S  4,0  2,4   0:00.69 tvh:lnxdvb-fron                                                                                                   
  806 hts       20   0  399212  22716   6600 S  2,3  2,4   0:03.29 tvh:dvr                                                                                                           
  661 hts       20   0  399212  22716   6600 S  1,3  2,4   0:03.17 tvh:mi-main                                                                                                       
  803 hts       20   0  399212  22716   6600 S  1,0  2,4   0:01.95 tvh:dvr                                                                                                           
  657 hts       20   0  399212  22716   6600 S  0,7  2,4   0:03.39 tvh:mi-main                                                                                                       
  662 hts       20   0  399212  22716   6600 S  0,7  2,4   0:00.82 tvh:mi-table                                                                                                      
  665 hts       20   0  399212  22716   6600 S  0,7  2,4   0:00.16 tvh:mi-main                                                                                                       
  738 hts       20   0  399212  22716   6600 S  0,7  2,4   0:04.05 tvh:tcp-start                                                                                                     
  416 hts       20   0  399212  22716   6600 S  0,3  2,4   0:00.11 tvheadend                                                                                                         
  417 hts       20   0  399212  22716   6600 S  0,3  2,4   0:00.38 tvh:mtick                                                                                                         
  654 hts       20   0  399212  22716   6600 S  0,3  2,4   0:01.49 tvh:mtimer                                                                                                        
  656 hts       20   0  399212  22716   6600 S  0,3  2,4   0:01.41 tvh:mi-table                                                                                                      
  660 hts       20   0  399212  22716   6600 S  0,3  2,4   0:01.57 tvh:mi-table                                                                                                      
  663 hts       20   0  399212  22716   6600 S  0,3  2,4   0:02.55 tvh:mi-main                                                                                                       
  403 hts       20   0  399212  22716   6600 S  0,0  2,4   0:03.11 tvheadend                                                                                                         
  404 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.05 tvh:log                                                                                                           
  410 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.08 tvh:notify                                                                                                        
  411 hts       35  15  399212  22716   6600 S  0,0  2,4   0:00.13 tvh:save                                                                                                          
  418 hts       39  19  399212  22716   6600 S  0,0  2,4   0:04.43 tvh:tasklet                                                                                                       
  419 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:fsmonitor                                                                                                     
  453 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:imagecache                                                                                                    
  454 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:httpc                                                                                                         
  460 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:service                                                                                                       
  469 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:iptv                                                                                                          
  478 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.13 tvheadend                                                                                                         
  479 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.20 tvh:hdhm-disc                                                                                                     
  480 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:tshift-reap                                                                                                   
  481 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.01 tvh:tcp-loop                                                                                                      
  482 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.03 tvh:upnp                                                                                                          
  483 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:svcmap                                                                                                        
  650 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:epggrabso                                                                                                     
  651 hts       20   0  399212  22716   6600 S  0,0  2,4   0:01.69 tvh:epggrabi                                                                                                      
  652 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:dvr-inotify                                                                                                   
  653 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:avahi                                                                                                         
  658 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:mi-table                                                                                                      
  659 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.00 tvh:mi-main                                                                                                       
  664 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.36 tvh:mi-table                                                                                                      
  737 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.11 tvh:tcp-start                                                                                                     
  756 hts       20   0  399212  22716   6600 S  0,0  2,4   0:00.86 tvh:tcp-start

What can I say, the issue has disappeared.
I'm quite sure I rebooted also with the previous installs and tests, so I can't say why it is now working.
Any idea? Is some setting reset after a reboot?
Is there anything I could keep monitoring to understand if the issue may be coming up again?

#24

Updated by g siviero over 4 years ago

Recording 3 channels.
I have these values in the configuration/Debugging/Memory info entries.

#25

Updated by g siviero over 4 years ago

The memory issue reappeared after a while. :(
Please wait...

#26

Updated by g siviero over 4 years ago

I tried again and again but I cannot reproduce the memory issue.
A (new?) problem now presents intermittently (cannot reproduce always): adding a recording while others are running results in a 0 size file or corrupted file.

In the following log the subscription: 0051 creates an invalid file.
If I leave the recording running or stop it manually I will find it in the "finished recording", not in failed, even if the file created is corrupted.

016-03-14 11:29:10.051 dvr: entry 36aeb8523ce8730ebf938d300f93ee97 "NEWS DEL MATTINO" on "TGCOM24" starting at 2016-03-14 08:19:30, scheduled for recording by "admin" 
2016-03-14 11:29:10.051 dvr: "NEWS DEL MATTINO" on "TGCOM24" recorder starting
2016-03-14 11:29:10.054 mpegts: 698MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 11:29:10.054 subscription: 004F: "DVR: NEWS DEL MATTINO" subscribing on channel "TGCOM24", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "698MHz", provider: "Mediaset", service: "TGCOM24", profile="pass" 
2016-03-14 11:29:10.507 TS: DVB-T Italy/658MHz/cielo: MPEG2VIDEO @ #166 Continuity counter error (total 1)
2016-03-14 11:29:10.636 dvr: /mnt/tv/Record/TGCOM24/NEWS-DEL-MATTINO.2016-03-14.08-45-1.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "698MHz", provider: "Mediaset", service: "TGCOM24" 
2016-03-14 11:29:10.636 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 11:29:10.636 dvr:  1  TELETEXT                                                                 
2016-03-14 11:29:10.636 dvr:  2  MPEG2VIDEO              ?           ?                                    
2016-03-14 11:29:10.636 dvr:  3  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 11:30:15.053 epggrab: EIT: DVB Grabber - data completion timeout for 698MHz in DVB-T Italy
2016-03-14 11:30:30.747 subscription: 004E: "DVR: Oggy e i maledetti scarafaggi" unsubscribing from "frisbee" 
2016-03-14 11:30:30.749 dvr: "Oggy e i maledetti scarafaggi" on "frisbee": End of program: Completed OK
2016-03-14 11:30:36.252 subscription: 004D: "DVR: L'aria che tira" unsubscribing from "LA7" 
2016-03-14 11:30:36.256 dvr: "L'aria che tira" on "LA7": End of program: Completed OK
2016-03-14 11:30:48.333 dvr: entry f21b1b37577d588aa31201c2cebfa928 "H2O [St.1] Ep. 9" on "Super!" starting at 2016-03-14 10:44:30, scheduled for recording by "admin" 
2016-03-14 11:30:48.334 dvr: "H2O [St.1] Ep. 9" on "Super!" recorder starting
2016-03-14 11:30:48.335 mpegts: 682MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 11:30:48.336 subscription: 0050: "DVR: H2O [St.1] Ep. 9" subscribing on channel "Super!", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "682MHz", provider: "Persidera", service: "Super!", profile="pass" 
2016-03-14 11:30:49.108 dvr: /mnt/tv/Record/Super!/H2O-[St.1]-Ep.-9.2016-03-14.11-10.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "682MHz", provider: "Persidera", service: "Super!" 
2016-03-14 11:30:49.108 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 11:30:49.108 dvr:  1  MPEG2VIDEO              ?           ?                                    
2016-03-14 11:30:49.108 dvr:  2  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 11:30:59.006 dvr: entry 9b573760ae2447de1c1700b1aeeae4dd "Oggy e i maledetti scarafaggi" on "frisbee" starting at 2016-03-14 10:54:30, scheduled for recording by "admin" 
2016-03-14 11:30:59.006 dvr: "Oggy e i maledetti scarafaggi" on "frisbee" recorder starting
2016-03-14 11:30:59.007 mpegts: 690MHz in DVB-T Italy - tuning on DiBcom 7000PC : DVB-T #0
2016-03-14 11:30:59.007 subscription: 0051: "DVR: Oggy e i maledetti scarafaggi" subscribing on channel "frisbee", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee", profile="pass" 
2016-03-14 11:30:59.794 TS: DVB-T Italy/682MHz/Super!: MPEG2VIDEO @ #556 Continuity counter error (total 1)
2016-03-14 11:31:00.097 dvr: /mnt/tv/Record/frisbee/Oggy-e-i-maledetti-scarafaggi.2016-03-14.11-20-1.ts from adapter: "DiBcom 7000PC : DVB-T #0", network: "DVB-T Italy", mux: "690MHz", provider: "Persidera", service: "frisbee" 
2016-03-14 11:31:00.097 dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-03-14 11:31:00.097 dvr:  1  MPEG2VIDEO              ?           ?                                    
2016-03-14 11:31:00.097 dvr:  2  MPEG2AUDIO        ita                             ?            ?         
2016-03-14 11:31:00.097 dvr:  3  TELETEXT                                                                 
2016-03-14 11:31:00.097 dvr:  4  TEXTSUB           ita                                                    
2016-03-14 11:31:01.100 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 1)
2016-03-14 11:31:01.182 pass-eit: -: invalid checksum (len 3, errors 1)
2016-03-14 11:31:02.773 pat: 690MHz in DVB-T Italy: invalid checksum (len 103, errors 1)
2016-03-14 11:31:03.089 pass-pat: -: invalid checksum (len 103, errors 1)
2016-03-14 11:31:11.098 eit: 690MHz in DVB-T Italy: invalid checksum (len 32, errors 10)
2016-03-14 11:31:11.173 TS: DVB-T Italy/690MHz/frisbee Transport error indicator (total 1)
2016-03-14 11:31:11.866 cat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 1)
2016-03-14 11:31:12.118 pass-eit: -: invalid checksum (len 3, errors 11)
2016-03-14 11:31:14.314 pat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 9)
2016-03-14 11:31:14.464 pass-pat: -: invalid checksum (len 3, errors 10)
2016-03-14 11:31:20.130 pass-sdt: -: invalid checksum (len 3, errors 1)
2016-03-14 11:31:20.130 bat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 1)
2016-03-14 11:31:20.130 sdt: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 1)
2016-03-14 11:31:21.181 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 22)
2016-03-14 11:31:22.811 pass-eit: -: invalid checksum (len 32, errors 24)
2016-03-14 11:31:24.121 pmt: 690MHz in DVB-T Italy: invalid checksum (len 5, errors 1)
2016-03-14 11:31:24.863 pat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 17)
2016-03-14 11:31:25.187 pass-pat: -: invalid checksum (len 3, errors 16)
2016-03-14 11:31:26.248 cat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 2)
2016-03-14 11:31:31.545 eit: 690MHz in DVB-T Italy: invalid checksum (len 32, errors 39)
2016-03-14 11:31:32.713 TS: DVB-T Italy/690MHz/frisbee Transport error indicator (total 3)
2016-03-14 11:31:33.101 pass-eit: -: invalid checksum (len 32, errors 42)
2016-03-14 11:31:34.845 pat: 690MHz in DVB-T Italy: invalid checksum (len 24, errors 23)
2016-03-14 11:31:36.246 cat: 690MHz in DVB-T Italy: invalid checksum (len 4, errors 3)
2016-03-14 11:31:37.194 bat: 690MHz in DVB-T Italy: invalid checksum (len 43, errors 2)
2016-03-14 11:31:37.194 sdt: 690MHz in DVB-T Italy: invalid checksum (len 43, errors 2)
2016-03-14 11:31:37.406 pass-sdt: -: invalid checksum (len 43, errors 2)
2016-03-14 11:31:38.040 pass-pat: -: invalid checksum (len 3, errors 20)
2016-03-14 11:31:42.643 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 62)
2016-03-14 11:31:43.722 pass-eit: -: invalid checksum (len 3, errors 64)
2016-03-14 11:31:48.284 cat: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 7)
2016-03-14 11:31:49.891 pat: 690MHz in DVB-T Italy: invalid checksum (len 2171, errors 29)
2016-03-14 11:31:50.424 pass-pat: -: invalid checksum (len 1014, errors 24)
2016-03-14 11:31:53.128 eit: 690MHz in DVB-T Italy: invalid checksum (len 3, errors 76)
2016-03-14 11:31:54.429 pass-eit: -: invalid checksum (len 3, errors 77)
2016-03-14 11:32:00.679 pat: 690MHz in DVB-T Italy: invalid checksum (len 1
#27

Updated by Jaroslav Kysela over 4 years ago

  • Status changed from New to Fixed

This is fixed in latest master.

#28

Updated by M W over 4 years ago

Hi,

today I got a quite similar Crash as posted above. It was the "out of ram" error. No EPG running state or timeshift is set. The cache scheme is on "do not keep".

I am using the HTS Tvheadend 4.1-1710, which is later than this fix was dated.

The program runs on a raspberry pi with 512mb ram and there where two recordings running on the same mux during the crash.

Thanks for the nice work.

Moe

Also available in: Atom PDF