[mythtv-users] Problems with ExternalRecorder and streamlink

Fri Feb 12 20:44:22 UTC 2021

On Fri, Feb 12, 2021 at 10:20:04AM -0700, John P Poet wrote:
> >
> > Below you can see part of the log files. I can provide the full log
> > files but was not sure I could send attachments to the mailing list.
> >
> > Will these excerpts give any clues?
> >
> > Thanks again for all your help!
> >
> > Best regards,
> > Peter Carlsson
> >
> > cat /tmp/mythexternrecorder.20210212104722.32675.log
> >   2021-02-12 11:47:22.145760 C [32675/32675] thread_unknown
> > mythcommandlineparser.cpp:2611 (ConfigureLogging) - mythexternrecorder
> > version:  [v31.0] www.mythtv.org
> >   2021-02-12 11:47:22.145793 C [32675/32675] thread_unknown
> > mythcommandlineparser.cpp:2613 (ConfigureLogging) - Qt version: compile:
> > 5.11.3, runtime: 5.11.3
> >   2021-02-12 11:47:22.145847 I [32675/32675] thread_unknown
> > mythcommandlineparser.cpp:2615 (ConfigureLogging) - Debian GNU/Linux 10
> > (buster) (i386)
> >   2021-02-12 11:47:22.145851 N [32675/32675] thread_unknown
> > mythcommandlineparser.cpp:2617 (ConfigureLogging) - Enabled verbose msgs:
> > general channel record
> >   2021-02-12 11:47:22.145947 N [32675/32675] thread_unknown
> > logging.cpp:725 (logStart) - Setting Log Level to LOG_INFO
> >   2021-02-12 11:47:22.156735 I [32675/32678] Logger logging.cpp:280 (run)
> > - Added logging to the console
> >   2021-02-12 11:47:22.157080 I [32675/32679] thread_unknown
> > MythExternControl.cpp:565 (Run) - : Buffer: Ready for data.
> >   2021-02-12 11:47:22.157268 I [32675/32680] thread_unknown
> > MythExternControl.cpp:450 (Run) - : Command parser ready.
> >   2021-02-12 11:47:22.157356 I [32675/32677] LogForward
> > loggingserver.cpp:129 (FileLogger) - Added logging to
> > /var/log/mythtv/mythexternrecorder.20210212104722.32675.log
> >   2021-02-12 11:47:22.157410 I [32675/32680] thread_unknown
> > MythExternControl.cpp:215 (SendStatus) - : Processing 'APIVersion?' -->
> > 'OK:2'
> >   2021-02-12 11:47:22.157636 I [32675/32680] thread_unknown
> > MythExternControl.cpp:240 (SendStatus) - : Processing '1:APIVersion:2' -->
> > '1:OK:2'
> >   2021-02-12 11:47:22.157763 I [32675/32680] thread_unknown
> > MythExternControl.cpp:240 (SendStatus) - : Processing '2:Version?' -->
> > '2:OK:0.6'
> >   2021-02-12 11:47:22.157880 I [32675/32680] thread_unknown
> > MythExternControl.cpp:240 (SendStatus) - : Processing '3:Description?' -->
> > '3:WARN:Not set'
> >   2021-02-12 11:47:22.163536 I [32675/32675] thread_unknown
> > MythExternRecApp.cpp:50 (MythExternRecApp) -  Channels in
> > '/home/peter/delat/script/mythtv-svtplay-external-recorder-channels.conf',
> > Tuner: '', Scanner: ''
> >   2021-02-12 11:47:26.167121 I [32675/32675] thread_unknown
> > MythExternControl.cpp:240 (SendStatus) - : Processing 'HasTuner' -->
> > '5:OK:Yes'
> >   2021-02-12 11:47:26.177351 I [32675/32675] thread_unknown
> > MythExternControl.cpp:240 (SendStatus) - : Processing
> > 'HasPictureAttributes' --> '6:OK:No'
> >   2021-02-12 11:47:26.177552 I [32675/32680] thread_unknown
> > MythExternControl.cpp:240 (SendStatus) - : Processing '7:FlowControl?' -->
> > '7:OK:XON/XOFF'
> >   2021-02-12 11:47:26.187588 I [32675/32675] thread_unknown
> > MythExternControl.cpp:240 (SendStatus) - : Processing 'BlockSize' --> '8:OK'
> >   2021-02-12 11:52:27.189668 N [32675/32679] thread_unknown
> > MythExternControl.cpp:591 (Run) - : Not streaming.
> >
> > cat /tmp/mythbackend.20210212104435.32334.log
> >   2021-02-12 11:47:21.885916 I [32334/32405] TVRecEvent tv_rec.cpp:1057
> > (HandleStateChange) - TVRec[21]: Changing from None to WatchingLiveTV
> >   2021-02-12 11:47:21.885970 I [32334/32405] TVRecEvent tv_rec.cpp:4434
> > (ClearFlags) - TVRec[21]: ClearFlags(FrontendReady,CancelNextRecording,) ->
> > RunMainLoop, @ tv_rec.cpp:1291
> >   2021-02-12 11:47:21.885996 I [32334/32405] TVRecEvent tv_rec.cpp:3502
> > (HandleTuning) - TVRec[21]: HandleTuning Request: Program(NULL) channel()
> > input() flags(LiveTV,)
> >   2021-02-12 11:47:21.886014 I [32334/32405] TVRecEvent cardutil.cpp:2055
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() input 21
> >   2021-02-12 11:47:21.887648 I [32334/32405] TVRecEvent cardutil.cpp:2087
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() got input 22
> >   2021-02-12 11:47:21.887683 I [32334/32405] TVRecEvent cardutil.cpp:2087
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() got input 23
> >   2021-02-12 11:47:21.887694 I [32334/32405] TVRecEvent cardutil.cpp:2087
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() got input 24
> >   2021-02-12 11:47:21.887741 I [32334/32405] TVRecEvent
> > recorders/channelbase.cpp:259 (IsInputAvailable) - ChannelBase[21]: Input
> > is free on 0/0
> >   2021-02-12 11:47:21.889052 I [32334/32405] TVRecEvent tv_rec.cpp:3571
> > (TuningShutdowns) - TVRec[21]: TuningShutdowns(Program(NULL) channel(1)
> > input() flags(LiveTV,))
> >   2021-02-12 11:47:21.889071 I [32334/32405] TVRecEvent tv_rec.cpp:4434
> > (ClearFlags) - TVRec[21]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop, @
> > tv_rec.cpp:3640
> >   2021-02-12 11:47:21.889083 I [32334/32405] TVRecEvent tv_rec.cpp:3524
> > (HandleTuning) - TVRec[21]: No recorder yet, calling TuningFrequency
> >   2021-02-12 11:47:21.889092 I [32334/32405] TVRecEvent tv_rec.cpp:3662
> > (TuningFrequency) - TVRec[21]: TuningFrequency
> >   2021-02-12 11:47:21.889728 I [32334/32405] TVRecEvent
> > recorders/ExternalChannel.cpp:21 (Open) -
> > ExternChan[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf): Open()
> >   2021-02-12 11:47:21.891806 I [32334/32405] TVRecEvent
> > recorders/channelbase.cpp:618 (InitializeInput) - ChannelBase[21]: Input
> > #21: 'MPEG2TS' schan(1) sourceid(4)
> >   2021-02-12 11:47:21.891880 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:550 (ExternalStreamHandler) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf): args
> > "--conf /home/peter/delat/script/mythtv-svtplay-external-recorder.conf
> > --verbose general,channel,record --logpath /var/log/mythtv --loglevel info
> > --quiet --inputid 21"
> >   2021-02-12 11:47:21.891942 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:913 (OpenApp) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf): Spawn
> > '/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf'
> >   2021-02-12 11:47:21.891950 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:231 (Run) - ExternIO::Run()
> >   2021-02-12 11:47:21.910956 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:321 (Fork) - ExternIO::Fork
> > 'mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf --verbose
> > general,channel,record --logpath /var/log/mythtv --loglevel info --quiet
> > --inputid 21'
> >   2021-02-12 11:47:21.914682 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:379 (Fork) - Spawned
> >   2021-02-12 11:47:22.157517 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1319 (ProcessVer1) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessCommand('APIVersion?') = 'OK:2' took 232ms
> >   2021-02-12 11:47:22.157678 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1460 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessV2('1:APIVersion:2') = 'OK:2' took 0ms
> >   2021-02-12 11:47:22.157785 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1460 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessV2('2:Version?') = 'OK:0.6' took 0ms
> >   2021-02-12 11:47:22.157917 W [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1460 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessV2('3:Description?') = 'WARN:Not set' took 0ms <-- NOTE
> >   2021-02-12 11:47:26.161611 E [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1423 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessVer2: Giving up waiting for response for command '4:HasTuner?'
> >   2021-02-12 11:47:26.167204 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1460 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessV2('5:HasTuner?') = 'OK:Yes' took 5ms
> >   2021-02-12 11:47:26.177405 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1460 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessV2('6:HasPictureAttributes?') = 'OK:No' took 10ms
> >   2021-02-12 11:47:26.177584 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1460 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessV2('7:FlowControl?') = 'OK:XON/XOFF' took 0ms
> >   2021-02-12 11:47:26.177599 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:970 (OpenApp) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf): App opened
> > successfully
> >   2021-02-12 11:47:26.177615 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:978 (OpenApp) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > Capabilities: tuner(yes) Picture attributes(no) Flow control(XON/XOFF)
> >   2021-02-12 11:47:26.187687 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:1460 (ProcessVer2) -
> > ExternSH[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > ProcessV2('8:BlockSize:1540096') = 'OK' took 10ms
> >   2021-02-12 11:47:26.187718 I [32334/32405] TVRecEvent
> > recorders/ExternalStreamHandler.cpp:470 (Get) - ExternSH[21]: Creating new
> > stream handler 21 for /usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf
> >   2021-02-12 11:47:26.187734 I [32334/32405] TVRecEvent
> > recorders/ExternalChannel.cpp:52 (Open) -
> > ExternChan[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf): Opened
> >   2021-02-12 11:47:26.187748 I [32334/32405] TVRecEvent
> > recorders/dtvchannel.cpp:159 (SetChannelByString) -
> > DTVChan[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > SetChannelByString(1):
> >   2021-02-12 11:47:26.187759 I [32334/32405] TVRecEvent cardutil.cpp:2055
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() input 21
> >   2021-02-12 11:47:26.188661 I [32334/32405] TVRecEvent cardutil.cpp:2087
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() got input 22
> >   2021-02-12 11:47:26.188682 I [32334/32405] TVRecEvent cardutil.cpp:2087
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() got input 23
> >   2021-02-12 11:47:26.188698 I [32334/32405] TVRecEvent cardutil.cpp:2087
> > (GetConflictingInputs) - CardUtil[21]: GetConflictingInputs() got input 24
> >   2021-02-12 11:47:26.188765 I [32334/32405] TVRecEvent
> > recorders/channelbase.cpp:259 (IsInputAvailable) - ChannelBase[21]: Input
> > is free on 0/0
> >   2021-02-12 11:47:26.190694 E [32334/32405] TVRecEvent
> > dtvmultiplex.cpp:412 (ParseTuningParams) - DTVMux: ParseTuningParams --
> > Unknown tuner type = 0xffffffff80000000
> >   2021-02-12 11:47:26.190750 E [32334/32405] TVRecEvent
> > recorders/dtvchannel.cpp:290 (SetChannelByString) -
> > DTVChan[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > SetChannelByString(1): Failed to initialize multiplex options
> >   2021-02-12 11:47:26.190761 I [32334/32405] TVRecEvent
> > recorders/dtvchannel.cpp:320 (SetChannelByString) -
> > DTVChan[21](/usr/bin/mythexternrecorder --conf
> > /home/peter/delat/script/mythtv-svtplay-external-recorder.conf):
> > SetChannelByString(1): failure
> >   2021-02-12 11:47:26.190774 E [32334/32405] TVRecEvent tv_rec.cpp:3737
> > (TuningFrequency) - TVRec[21]: Failed to set channel to 1. Reverting to
> > kState_None
> >   2021-02-12 11:47:26.190803 I [32334/32405] TVRecEvent tv_rec.cpp:1057
> > (HandleStateChange) - TVRec[21]: Changing from WatchingLiveTV to None
> >
> 
> Are you running v31 or v31-latest (v31-fixes)? If you are not running the
> latest version of v31 you may be hitting a bug preventing it from handling
> the situation where there are channels but no tuner. I had forgotten about
> that because it was fixed so long ago.

dpkg -l mythtv*
  ii  mythtv           31.0+fixes20201214.gite9b795a1e4-dmo0~bpo10+1 all          Personal video recorder application (client and server)
  ii  mythtv-backend   31.0+fixes20201214.gite9b795a1e4-dmo0~bpo10+1 i386         Personal video recorder application (server)
  ii  mythtv-common    31.0+fixes20210206.git7763a3363d-dmo0~bpo10+1 all          Personal video recorder application (common data)
  ii  mythtv-database  31.0+fixes20210206.git7763a3363d-dmo0~bpo10+1 all          Personal video recorder application (database)
  ii  mythtv-doc       30.0+fixes20201003.git622c94965e-dmo1+deb10u2 all          Personal video recorder application (documentation)
  ii  mythtv-frontend  31.0+fixes20201214.gite9b795a1e4-dmo0~bpo10+1 i386         Personal video recorder application (client)
  ii  mythtv-transcode 31.0+fixes20201214.gite9b795a1e4-dmo0~bpo10+1 i386         Utilities used for transcoding MythTV tasks

This is from deb-multimedia.org and I don't see any more newer.

/Peter