Project

General

Profile

Bug #4772

[WARNING] dvr: Unable to reconfigure - clarification on event please ?

Added by Steve P almost 4 years ago. Updated over 3 years ago.

Status:
New
Priority:
Normal
Assignee:
Category:
PVR / DVR
Target version:
-
Start date:
2017-12-06
Due date:
% Done:

0%

Estimated time:
Found in version:
4.2.4
Affected Versions:

Description

Hi,

Not certain if this is a bug or not, but could someone clarify what occurred ?

Currently running 4.2.4, tuners shown in attachment.

2017-12-04 02:24:30.000 [   INFO] dvr: "The Hills Have Eyes" on "Horror Channel" recorder starting
2017-12-04 02:24:30.000 [   INFO] dvr: About to set stop timer for "The Hills Have Eyes" on "Horror Channel" at start 1512354600 and original stop 1512361200 and overall stop at 1512361800
2017-12-04 02:24:30.000 [   INFO] mpegts: 506MHz in Freeview - tuning on DiBcom 7000PC : DVB-T #0
2017-12-04 02:24:30.000 [   INFO] subscription: 00E3: "DVR: The Hills Have Eyes" subscribing on channel "Horror Channel", weight: 300, adapter: "DiBcom 7000PC : DVB-T #0", network: "Freeview", mux: "506MHz", service: "Horror Channel", profile="matroska" 
2017-12-04 02:25:00.034 [   INFO] dvr: /opt/TV/The Hills Have Eyes.mkv from adapter: "DiBcom 7000PC : DVB-T #0", network: "Freeview", mux: "506MHz", provider: "<N/A>", service: "Horror Channel" 
2017-12-04 02:25:00.034 [   INFO] dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2017-12-04 02:25:00.034 [   INFO] dvr:  1  MPEG2VIDEO              544x576     16:9
2017-12-04 02:25:00.034 [   INFO] dvr:  2  MPEG2AUDIO        eng                             48000        2
2017-12-04 02:25:00.034 [   INFO] dvr:  3  MPEG2AUDIO        eng                             ?            ?         <disabled, no valid input>
2017-12-04 02:25:00.034 [   INFO] dvr:  4  DVBSUB            eng

2017-12-04 03:59:09.395 [WARNING] dvr: Unable to reconfigure "/opt/TV/The Hills Have Eyes.mkv" 
2017-12-04 03:59:09.415 [   INFO] dvr: "The Hills Have Eyes" on "Horror Channel": End of program: Source reconfigured
2017-12-04 03:59:09.415 [   INFO] dvr: "The Hills Have Eyes" on "Horror Channel" recorder starting
2017-12-04 03:59:09.415 [   INFO] dvr: About to set stop timer for "The Hills Have Eyes" on "Horror Channel" at start 1512359948 and original stop 1512361200 and overall stop at 1512361800
2017-12-04 03:59:09.416 [   INFO] dvr: /opt/TV/The Hills Have Eyes-1.mkv from adapter: "DiBcom 7000PC : DVB-T #0", network: "Freeview", mux: "506MHz", provider: "<N/A>", service: "Horror Channel" 
2017-12-04 03:59:09.416 [   INFO] dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2017-12-04 03:59:09.416 [   INFO] dvr:  1  MPEG2VIDEO              544x576     16:9
2017-12-04 03:59:09.416 [   INFO] dvr:  2  MPEG2AUDIO        eng                             48000        2
2017-12-04 03:59:09.416 [   INFO] dvr:  3  MPEG2AUDIO        eng                             ?            ?         <disabled, no valid input>
2017-12-04 03:59:09.416 [   INFO] dvr:  4  DVBSUB            eng
2017-12-04 04:30:00.014 [   INFO] subscription: 00E3: "DVR: The Hills Have Eyes" unsubscribing from "Horror Channel" 
2017-12-04 04:30:00.017 [   INFO] dvr: "The Hills Have Eyes" on "Horror Channel": End of program: Completed OK

Resulted in having a split file recording:

-rw-rw-r-- 1 user 1155475415 Dec  4 03:59 /opt/TV/The Hills Have Eyes.mkv   <- Marked as failed recording in WebUI
-rw-rw-r-- 1 user  443503701 Dec  4 04:30 /opt/TV/The Hills Have Eyes-1.mkv

I can see the logic in the code:

./src/tvheadend.h

#define SM_CODE_FORCE_OK                  10

#define SM_CODE_SOURCE_RECONFIGURED       100
#define SM_CODE_BAD_SOURCE                101
#define SM_CODE_SOURCE_DELETED            102

./src/dvr/dvr_rec.c

  if (*started) {
    if (muxer_reconfigure(prch->prch_muxer, ss) >= 0)
      return 1;
    tvhwarn(LS_DVR, "Unable to reconfigure \"%s\"",
            dvr_get_filename(de) ?: lang_str_get(de->de_title, NULL));

    // Try to restart the recording if the muxer doesn't
    // support reconfiguration of the streams.
    if (!dvr_thread_global_lock(de, run)) {
      *dts_offset = PTS_UNSET;
      *started = 0;
      return 0;
    }

+++++

    case SMT_STOP:
       if (sm->sm_code == SM_CODE_SOURCE_RECONFIGURED) {
         // Subscription is restarting, wait for SMT_START
         muxing = 0; // reconfigure muxer

       } else if(sm->sm_code == 0) {
         // Recording is completed

// Try to restart the recording if the muxer doesn't
// support reconfiguration of the streams.

Why would the muxer attempt to reconfigure in the middle of a recording?

It would appear that 'Subscription is restarting' was caught.

It is a tuner side error, some other event conflicting the tuner (epg grab)?
Or some internal tvheadend event?

Many thanks for anyone who can enlighten me, trying to ensure the hardware is not the culprit here..


Files

tvh.PNG (16.2 KB) tvh.PNG Steve P, 2017-12-06 17:14

History

#1

Updated by Jaroslav Kysela almost 4 years ago

Something was probably changed in the PMT table. The change detection is not perfect in the current code.

#2

Updated by Steve P almost 4 years ago

Thank you Jaroslav for the update. Only seen it happen once in many years of use, so nearly perfect code :)

#3

Updated by Anders Gustafsson over 3 years ago

Happens on one of my channels. 4.2.4

When that happens I end up with three files where only one is the correct one.

I read what possibly happens in the discussion about Bug #4882, but is there a fix for 4.2.4 as well apart from changing from .mkv to .ts? I changed to .mkv as Kodi would not fast forward or seek in .ts files from this channel.

Also available in: Atom PDF