search

mathieugouin / rpiDvr

Using a Raspberry Pi micro-computer as a DVR to record free over the air HD channels.
https://mathieugouin.github.io/rpiDvr
GNU General Public License v3.0
9 stars 0 forks source link

Latest LibreELEC cannot sustain 2 simultaneous recordings & 1 playback #31

Open mathieugouin opened 3 years ago

mathieugouin commented 3 years ago

On Raspberry Pi 3 Model B.

mathieugouin commented 3 years ago

Current system info:

Linux LibreELEC 4.19.127 #1 SMP Sun Oct 25 08:49:28 EDT 2020 armv7l GNU/Linux
NOTICE: Starting Kodi (18.9 (18.9.0) Git:newclock5_18.9-Leia). Platform: Linux ARM 32-bit
NOTICE: Using Release Kodi x32 build (version for Raspberry Pi)
NOTICE: Kodi compiled 2020-10-25 by GCC 8.3.0 for Linux ARM 32-bit version 4.19.127 (267135)
NOTICE: Running on LibreELEC (official): 9.2.6, kernel: Linux ARM 32-bit version 4.19.127
Tvheadend 4.2.8-36
mathieugouin commented 3 years ago

Current:

Initial setup (first install)

Setup still working (from memory)

According to: https://libreelec.wiki/support/update, downgrading from the same major version should work...

mathieugouin commented 3 years ago

Steps to try (need to be performed atomically in downtime):

mathieugouin commented 3 years ago

Typical log failure is:

2021-03-01 20:47:12.279 T:1339024256   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
2021-03-01 20:47:12.283 T:1631232896   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - new connection requested
2021-03-01 20:47:12.283 T:1631232896   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connecting to localhost:9982
2021-03-01 20:47:12.284 T:1339024256   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:47:12.284 T:1339024256   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs read failed after 1 attempts
2021-03-01 20:47:12.345 T:1631232896   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected
2021-03-01 20:47:12.345 T:1419211648   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending hello
2021-03-01 20:47:12.346 T:1419211648   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected to Tvheadend / 4.2.8-36 ~ LibreELEC Tvh-addon v9.2.0.123 (H
TSPv27)
2021-03-01 20:47:12.346 T:1419211648   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending auth
2021-03-01 20:47:12.351 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   Received permissions:
2021-03-01 20:47:12.351 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   administrator              : 1
2021-03-01 20:47:12.351 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   HTSP streaming             : 1
2021-03-01 20:47:12.351 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   HTSP DVR                   : 1
2021-03-01 20:47:12.351 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   Failed/aborted DVR         : 1
2021-03-01 20:47:12.352 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   anonymous HTSP only        : 0
2021-03-01 20:47:12.352 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   global connection limit    : 0
2021-03-01 20:47:12.352 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   DVR connection limit       : 0
2021-03-01 20:47:12.352 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   streaming connection limit : 0
2021-03-01 20:47:12.352 T:1419211648   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - rebuilding state
2021-03-01 20:47:12.354 T:1419211648   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs re-open file
2021-03-01 20:47:12.354 T:1419211648   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs open file=dvr/348021984
2021-03-01 20:47:17.355 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileOpen failed: No response received
2021-03-01 20:47:17.355 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to re-open file
2021-03-01 20:47:17.359 T:1419211648   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs close id=1
2021-03-01 20:47:27.361 T:1419211648    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - request async EPG (14)
2021-03-01 20:47:27.390 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to write (Invalid argument)
2021-03-01 20:47:27.391 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to transmit
2021-03-01 20:47:27.391 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - message without a method
2021-03-01 20:47:27.391 T:1339024256   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:47:27.391 T:1339024256   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs read failed after 1 attempts
2021-03-01 20:47:27.418 T:1339024256   DEBUG: ffmpeg[4FCFE380]: [mpegts] PES packet size mismatch
2021-03-01 20:47:27.452 T:1339024256   DEBUG: CVideoPlayer::OMXDoProcessing Pause -11.67,-11.22 (A:10 V:10) EOF:0 FULL:0 T:0.40
2021-03-01 20:47:27.452 T:1339024256   DEBUG: OMXClock::OMXSetSpeed(0.000) pause_resume:1
2021-03-01 20:47:27.454 T:1339024256   DEBUG: ffmpeg[4FCFE380]: [mpegts] PES packet size mismatch
2021-03-01 20:47:27.579 T:1429205888   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_EOF
2021-03-01 20:47:27.579 T:1429205888    INFO: COMXVideo::SubmitEOS
2021-03-01 20:47:27.584 T:1410818944   ERROR: ffmpeg[54176380]: [ac3] incomplete frame
mathieugouin commented 3 years ago

grep relevant:

grep ERROR: kodi.log | grep pvr.hts

Typical output:

2021-02-16 09:59:45.482 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - unable to connect to localhost:9982
2021-02-16 09:59:45.985 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - unable to connect to localhost:9982
2021-02-16 09:59:46.492 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - unable to connect to localhost:9982
2021-02-16 09:59:46.998 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - unable to connect to localhost:9982
2021-02-16 09:59:47.504 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - unable to connect to localhost:9982
2021-02-16 09:59:48.010 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - unable to connect to localhost:9982
2021-02-22 20:57:35.605 T:1435497344   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
2021-02-22 20:57:36.712 T:1435497344   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-02-22 20:57:41.737 T:1427104640   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileOpen failed: No response received
2021-02-22 20:57:41.737 T:1427104640   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to re-open file
2021-02-22 20:57:51.742 T:1427104640   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to write (Invalid argument)
2021-02-22 20:57:51.742 T:1427104640   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to transmit
2021-02-22 20:57:51.742 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - message without a method
2021-02-22 20:57:51.742 T:1435497344   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:33:05.940 T:1357894528   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
2021-03-01 20:33:05.959 T:1357894528   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:33:11.068 T:1328542592   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileOpen failed: No response received
2021-03-01 20:33:11.068 T:1328542592   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to re-open file
2021-03-01 20:33:21.103 T:1328542592   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to write (Invalid argument)
2021-03-01 20:33:21.103 T:1328542592   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to transmit
2021-03-01 20:33:21.103 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - message without a method
2021-03-01 20:33:21.103 T:1357894528   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:36:54.449 T:1366287232   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
2021-03-01 20:36:54.450 T:1366287232   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:36:59.459 T:1357894528   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileOpen failed: No response received
2021-03-01 20:36:59.460 T:1357894528   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to re-open file
2021-03-01 20:37:09.499 T:1357894528   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to write (Invalid argument)
2021-03-01 20:37:09.499 T:1357894528   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to transmit
2021-03-01 20:37:09.499 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - message without a method
2021-03-01 20:37:09.499 T:1366287232   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:47:12.279 T:1339024256   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
2021-03-01 20:47:12.284 T:1339024256   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2021-03-01 20:47:17.355 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileOpen failed: No response received
2021-03-01 20:47:17.355 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to re-open file
2021-03-01 20:47:27.390 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to write (Invalid argument)
2021-03-01 20:47:27.391 T:1419211648   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to transmit
2021-03-01 20:47:27.391 T:1631232896   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - message without a method
2021-03-01 20:47:27.391 T:1339024256   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
mathieugouin commented 3 years ago

Old downloads: http://archive.libreelec.tv/

mathieugouin commented 3 years ago

Upload log file: cat myfile.txt | curl -F 'sprunge=<-' http://sprunge.us

Full log with play stopped

http://sprunge.us/jqKa5p

Play "_-fond-de-train_2016-05-29" start at:

2021-03-02 21:39:33.994 T:1937283520  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/_-fond-de-train_2016-05-29, TV%20(Radio-Canada), 20160530_020000, 1026801670.pvr

Play stopped (automatically) at:

2021-03-02 21:52:42.965 T:1494913920   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
mathieugouin commented 3 years ago

Fairly easy to reproduce...

mathieugouin commented 3 years ago

LibreELEC thread: https://forum.libreelec.tv/thread/23623-libreelec-9-2-6-cannot-sustain-2-simultaneous-hd-recordings-1-playback/

mathieugouin commented 3 years ago

TVH debug log: /storage/.kodi/userdata/addon_data/service.tvheadend42/service.log

mathieugouin commented 3 years ago

TVH service log example:

http://sprunge.us/CqEdeV

mathieugouin commented 3 years ago

Available LibreELEC versions to try (9.2.X).

From: http://archive.libreelec.tv/

LibreELEC-RPi2.arm-9.2.0.img.gz 2019-11-23 03:42    125M
LibreELEC-RPi2.arm-9.2.1.img.gz 2020-03-04 00:22    126M
LibreELEC-RPi2.arm-9.2.3.img.gz 2020-06-01 19:15    126M
LibreELEC-RPi2.arm-9.2.4.img.gz 2020-07-29 23:58    126M
LibreELEC-RPi2.arm-9.2.6.img.gz 2020-10-25 14:57    126M
mathieugouin commented 3 years ago

Started fresh install of 9.2.1 on new sdcard.

mathieugouin commented 3 years ago

Same tvheadend version on LE 9.2.1: 4.2.8-36

mathieugouin commented 1 year ago

Happens sometime with only 1 recording while playing back 1.

mathieugouin commented 1 year ago

Both kodi + tvh service log

kodi

.kodi/temp/kodi.log

2023-02-14 10:02:16.192 T:1507701632   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
2023-02-14 10:02:16.192 T:1507701632   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2023-02-14 10:02:16.192 T:1507701632   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs read failed after 1 attempts
2023-02-14 10:02:16.193 T:1680782208   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - new connection requested
2023-02-14 10:02:16.193 T:1680782208   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connecting to 127.0.0.1:9982
2023-02-14 10:02:16.193 T:1680782208   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected
2023-02-14 10:02:16.195 T:1516094336   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending hello
2023-02-14 10:02:16.196 T:1516094336   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected to Tvheadend / 4.2.8-36 ~ LibreELEC Tvh-addon v9.2.0.123 (H
TSPv27)
2023-02-14 10:02:16.197 T:1516094336   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending auth
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   Received permissions:
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   administrator              : 1
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   HTSP streaming             : 1
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   HTSP DVR                   : 1
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   Failed/aborted DVR         : 1
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   anonymous HTSP only        : 0
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   global connection limit    : 0
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   DVR connection limit       : 0
2023-02-14 10:02:16.201 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts -   streaming connection limit : 0
2023-02-14 10:02:16.202 T:1516094336   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - rebuilding state
2023-02-14 10:02:16.203 T:1516094336   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs re-open file
2023-02-14 10:02:16.203 T:1516094336   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs open file=dvr/1139757566
2023-02-14 10:02:21.203 T:1516094336   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileOpen failed: No response received
2023-02-14 10:02:21.204 T:1516094336   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to re-open file
2023-02-14 10:02:21.205 T:1516094336   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs close id=2
2023-02-14 10:02:31.207 T:1516094336    INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - request async EPG (14)
2023-02-14 10:02:31.226 T:1516094336   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to write (Invalid argument)
2023-02-14 10:02:31.226 T:1516094336   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to transmit
2023-02-14 10:02:31.226 T:1680782208   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - message without a method
2023-02-14 10:02:31.226 T:1507701632   ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2023-02-14 10:02:31.226 T:1507701632   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs read failed after 1 attempts

tvh service

.kodi/userdata/addon_data/service.tvheadend42/service.log

2023-02-14 10:02:16.195 [   INFO] htsp: Got connection from 127.0.0.1
2023-02-14 10:02:16.196 [   INFO] htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv34)
2023-02-14 10:02:16.201 [   INFO] htsp: 127.0.0.1 [ Kodi Media Center ]: Identified as user ''
2023-02-14 10:02:21.204 [   INFO] htsp: 127.0.0.1 [  | Kodi Media Center ]: Disconnected
2023-02-14 10:02:21.205 [   INFO] subscription: 0023: "HTSP" unsubscribing, hostname="127.0.0.1", username="127.0.0.1", client="Kodi Media Center"
2023-02-14 10:02:33.436 [   INFO] htsp: 127.0.0.1 [  | Kodi Media Center ]: Write error -- Broken pipe
2023-02-14 10:02:33.541 [   INFO] htsp: 127.0.0.1 [  | Kodi Media Center ]: Disconnected
2023-02-14 10:02:33.541 [   INFO] subscription: 0021: "HTSP" unsubscribing, hostname="127.0.0.1", username="127.0.0.1", client="Kodi Media Center"
2023-02-14 10:02:36.227 [   INFO] htsp: Got connection from 127.0.0.1
2023-02-14 10:02:36.228 [   INFO] htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv34)
2023-02-14 10:02:36.229 [   INFO] htsp: 127.0.0.1 [ Kodi Media Center ]: Identified as user ''