hauppauge2 external recorder for mythtv--solved by upgrading to mythtv 30.0

[amyo777]

I have a HDPVR2 I am using jpoet's github hosted hauppauge2 external recorder. I can record at the linux prompt just fine even with the config file so that seems to be working well.

I am running mythtv 29.1. My system also contains HDHRs to record OTA and I have read it is hard to re-add them to version 30 so a little reluctant to upgrade if I do not have to.

My problem is with mythtv. I have the hdpvr on a slave backend (it's an old atom based aspire revo). I have the slave all setup with a video source and channel and such. Eventually this setup will replace my rpi3 + hdpvr classic slave setup, but not till I've got it working and tested. So right now it is just recording the output of an android tv box.

My problem is in the recording with mythtv. I get the ASK_RECORDING and it really looks like everything is working but then I do not get a recording and I do see a segfault but only after the recording has completed (it was a short test recording).
Relevant mythtv logs.

Mar 9 13:51:30 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: I TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) TVRec[38]: ASK_RECORDING 38 29 0 0
Mar 9 13:51:30 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: I TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) TVRec[36]: ASK_RECORDING 36 29 0 0
Mar 9 13:51:30 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: I TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) TVRec[37]: ASK_RECORDING 37 29 0 0
Mar 9 13:52:00 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: I TVRecEvent tv_rec.cpp:1090 (HandleStateChange) TVRec[36]: Changing from None to RecordingOnly
Mar 9 13:52:00 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Mar 9 13:52:00 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: I TVRecEvent tv_rec.cpp:3665 (TuningFrequency) TVRec[36]: TuningFrequency
Mar 9 13:52:07 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: E TVRecEvent tv_rec.cpp:3949 (TuningSignalCheck) TVRec[36]: TuningSignalCheck: SignalMonitor failed
Mar 9 13:56:23 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: I Metadata_43310 jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "25 (CW26)":"2020-03-09 13:51:00" recorded from channel 6261 at 2020-03-09T18:51:00Z
Mar 9 13:57:00 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: I TVRecEvent tv_rec.cpp:1090 (HandleStateChange) TVRec[36]: Changing from RecordingOnly to None
Mar 9 13:57:00 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: C CoreContext signalhandling.cpp:305 (handleSignal) Received Segmentation fault: Code 1, PID -1453252588, UID 32653, Value 0x00000000

Mar 9 13:57:00 amyo-Aspire-R1600 mythbackend: mythbackend[19724]: E TVRecEvent recorders/streamhandler.cpp:43 (~StreamHandler) SH(/opt/Hauppauge/bin/hauppauge2 -c /opt/Hauppauge/etc/amy.conf): dtor & _stream_data_list not empty
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: C thread_unknown mythcommandlineparser.cpp:2619 (ConfigureLogging) mythbackend version: [v29.1] www.mythtv.___
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: C thread_unknown mythcommandlineparser.cpp:2621 (ConfigureLogging) Qt version: compile: 5.9.5, runtime: 5.9.5
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: N thread_unknown mythcommandlineparser.cpp:2623 (ConfigureLogging) Enabled verbose msgs: general
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: N thread_unknown logging.cpp:927 (logStart) Setting Log Level to LOG_INFO
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: I Logger logging.cpp:313 (run) Added logging to the console
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: I thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup Interrupt handler
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: I thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup Terminated handler
Mar 9 13:57:04 amyo-Aspire-R1600 mythbackend: mythbackend[19990]: I thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup Segmentation fault handler


Relevant hdpvr2 log:

2020-03-09 13:52:00.543697 C [main] hauppauge2.cpp:361 (main) - Initializing [Bus: 1, Port: 4] E505-00-00D76509
2020-03-09 13:52:00.558434 N [Commands] MythTV.cpp:252 (send_status) - APIVersion?: ':OK:2'
2020-03-09 13:52:00.558726 N [Commands] MythTV.cpp:252 (send_status) - 1:APIVersion:2: '1:OK:API Version 2'
2020-03-09 13:52:00.558918 N [Commands] MythTV.cpp:252 (send_status) - 2:Version?: '2:OK:0.6'
2020-03-09 13:52:00.559112 N [Commands] MythTV.cpp:252 (send_status) - 3:HasTuner?: '3:OK:No'
2020-03-09 13:52:00.559295 N [Commands] MythTV.cpp:252 (send_status) - 4:HasPictureAttributes?: '4:OK:No'
2020-03-09 13:52:00.559468 N [Commands] MythTV.cpp:252 (send_status) - 5:FlowControl?: '5:OK:XON/XOFF'
2020-03-09 13:52:00.559643 N [Commands] MythTV.cpp:252 (send_status) - 6:BlockSize:6160384: '6:OK'
2020-03-09 13:52:00.590236 N [Commands] MythTV.cpp:252 (send_status) - 7:Version?: '7:OK:0.6'
2020-03-09 13:52:00.591526 N [Commands] MythTV.cpp:252 (send_status) - 8:LockTimeout?: '8:OK:12000'
2020-03-09 13:52:00.590236 N [Commands] MythTV.cpp:252 (send_status) - 7:Version?: '7:OK:0.6'
2020-03-09 13:52:00.591526 N [Commands] MythTV.cpp:252 (send_status) - 8:LockTimeout?: '8:OK:12000'
2020-03-09 13:52:00.899089 N [Commands] HauppaugeDev.cpp:538 (Open) - Opening Hauppauge USB device.
2020-03-09 13:52:00.909136 N [Commands] HauppaugeDev.cpp:560 (Open) - FX2 ready after 0 tries.
2020-03-09 13:52:05.495798 N [Commands] HauppaugeDev.cpp:580 (Open) - encDev ready
2020-03-09 13:52:05.495989 N [Commands] HauppaugeDev.cpp:608 (Open) - Using 1080p6050 stereo RGB EDID.
2020-03-09 13:52:05.500085 N [Commands] HauppaugeDev.cpp:612 (Open) - rxDev ready
2020-03-09 13:52:05.500253 N [Commands] HauppaugeDev.cpp:625 (Open) - Hauppauge USB device ready.
2020-03-09 13:52:05.500461 N [Commands] MythTV.cpp:252 (send_status) - 9:SignalStrengthPercent?: '9:OK:100'
2020-03-09 13:52:07.502237 N [Commands] MythTV.cpp:252 (send_status) - 10:SignalStrengthPercent?: '10:OK:100'
2020-03-09 13:52:07.502462 N [Commands] MythTV.cpp:252 (send_status) - 11:SignalStrengthPercent?: '11:OK:100'
2020-03-09 13:52:07.502629 N [Commands] MythTV.cpp:252 (send_status) - 12:HasLock?: '12:OK:Yes'
2020-03-09 13:52:07.503567 N [Commands] MythTV.cpp:252 (send_status) - 13:IsOpen?: '13:OK:Open'
2020-03-09 13:52:07.504283 N [Commands] MythTV.cpp:252 (send_status) - 14:Version?: '14:OK:0.6'
2020-03-09 13:52:08.959306 N [Commands] HauppaugeDev.cpp:457 (init_hdmi) - Invalid Output Params, retrying.
2020-03-09 13:52:09.015498 N [Commands] HauppaugeDev.cpp:457 (init_hdmi) - Invalid Output Params, retrying.
2020-03-09 13:52:09.071141 N [Commands] HauppaugeDev.cpp:457 (init_hdmi) - Invalid Output Params, retrying.
2020-03-09 13:52:09.140768 N [Commands] HauppaugeDev.cpp:457 (init_hdmi) - Invalid Output Params, retrying.
2020-03-09 13:52:09.207149 N [Commands] HauppaugeDev.cpp:457 (init_hdmi) - Invalid Output Params, retrying.
2020-03-09 13:52:09.279973 N [Commands] HauppaugeDev.cpp:152 (set_input_format) - Input width: 1920 height: 1080 interlaced: 0 vFreq: 59.9369 audio SR: 48000
2020-03-09 13:52:09.287025 N [Commands] HauppaugeDev.cpp:135 (set_digital_audio) - CS8416 initialized.

2020-03-09 13:52:09.287025 N [Commands] HauppaugeDev.cpp:135 (set_digital_audio) - CS8416 initialized.
2020-03-09 13:52:09.288649 N [Commands] HauppaugeDev.cpp:198 (set_input_format) - I2S audio from ADV7842
2020-03-09 13:52:09.288759 N [Commands] HauppaugeDev.cpp:200 (set_input_format) - Audio Input: HDMI
2020-03-09 13:52:09.288824 N [Commands] HauppaugeDev.cpp:225 (set_input_format) - Audio codec: AUTO
2020-03-09 13:52:09.790933 N [Commands] HauppaugeDev.cpp:497 (init_hdmi) - HDMI video input initialized.
2020-03-09 13:52:10.903283 N [Commands] MythTV.cpp:252 (send_status) - 15:StartStreaming: '15:OK:Started'
2020-03-09 13:52:10.903549 W [Commands] MythTV.cpp:161 (StartEncoding) - Already streaming!
2020-03-09 13:52:10.903702 N [Commands] MythTV.cpp:252 (send_status) - 16:StartStreaming: '16:OK:Started'
2020-03-09 13:52:10.904820 N [Commands] MythTV.cpp:252 (send_status) - 17:XON: '17:OK'
2020-03-09 13:57:00.132964 N [Commands] MythTV.cpp:252 (send_status) - 18:CloseRecorder: '18:OK:Terminating'
2020-03-09 13:57:00.133167 C [Commands] MythTV.cpp:64 (Terminate) - Terminating.
2020-03-09 13:57:00.133285 N [Commands] MythTV.cpp:214 (StopEncoding) - Stopping encoder.
2020-03-09 13:57:00.571946 C [main] MythTV.cpp:64 (Terminate) - Terminating.
2020-03-09 13:57:01.688117 N [Commands] MythTV.cpp:222 (StopEncoding) - Encoder stopped
2020-03-09 13:57:02.006133 C [main] hauppauge2.cpp:478 (main) - Done.
2020-03-09 13:57:06.331589 C [main] hauppauge2.cpp:361 (main) - Initializing [Bus: 1, Port: 4] E505-00-00D76509
2020-03-09 13:57:06.346431 N [Commands] MythTV.cpp:252 (send_status) - APIVersion?: ':OK:2'
2020-03-09 13:57:06.346852 N [Commands] MythTV.cpp:252 (send_status) - 1:APIVersion:2: '1:OK:API Version 2'
2020-03-09 13:57:06.347221 N [Commands] MythTV.cpp:252 (send_status) - 2:Version?: '2:OK:0.6'
2020-03-09 13:57:06.347438 N [Commands] MythTV.cpp:252 (send_status) - 3:HasTuner?: '3:OK:No'
2020-03-09 13:57:06.347623 N [Commands] MythTV.cpp:252 (send_status) - 4:HasPictureAttributes?: '4:OK:No'
2020-03-09 13:57:06.347799 N [Commands] MythTV.cpp:252 (send_status) - 5:FlowControl?: '5:OK:XON/XOFF'
2020-03-09 13:57:06.348040 N [Commands] MythTV.cpp:252 (send_status) - 6:BlockSize:6160384: '6:OK'
2020-03-09 13:57:06.390819 N [Commands] MythTV.cpp:252 (send_status) - 7:CloseRecorder: '7:OK:Terminating'
2020-03-09 13:57:06.390998 C [Commands] MythTV.cpp:64 (Terminate) - Terminating.
2020-03-09 13:57:06.391377 C [main] MythTV.cpp:64 (Terminate) - Terminating.
2020-03-09 13:57:06.416914 C [main] hauppauge2.cpp:478 (main) - Done.
2020-03-09 13:57:06.753636 C [main] hauppauge2.cpp:361 (main) - Initializing [Bus: 1, Port: 4] E505-00-00D76509
2020-03-09 13:57:06.768414 N [Commands] MythTV.cpp:252 (send_status) - APIVersion?: ':OK:2'
2020-03-09 13:57:06.768722 N [Commands] MythTV.cpp:252 (send_status) - 1:APIVersion:2: '1:OK:API Version 2'
2020-03-09 13:57:06.768923 N [Commands] MythTV.cpp:252 (send_status) - 2:Version?: '2:OK:0.6'
2020-03-09 13:57:06.769094 N [Commands] MythTV.cpp:252 (send_status) - 3:HasTuner?: '3:OK:No'
2020-03-09 13:57:06.769270 N [Commands] MythTV.cpp:252 (send_status) - 4:HasPictureAttributes?: '4:OK:No'
2020-03-09 13:57:06.769439 N [Commands] MythTV.cpp:252 (send_status) - 5:FlowControl?: '5:OK:XON/XOFF'
2020-03-09 13:57:06.769610 N [Commands] MythTV.cpp:252 (send_status) - 6:BlockSize:6160384: '6:OK'
2020-03-09 13:57:06.811130 N [Commands] MythTV.cpp:252 (send_status) - 7:CloseRecorder: '7:OK:Terminating'
2020-03-09 13:57:06.811279 C [Commands] MythTV.cpp:64 (Terminate) - Terminating.
2020-03-09 13:57:06.811593 C [main] MythTV.cpp:64 (Terminate) - Terminating.
2020-03-09 13:57:06.836965 C [main] hauppauge2.cpp:478 (main) - Done.

[amyo777]

Since I am unsure whether this is a slave backend problem, an external recorder problem or something with 29.1 vs. 30, I am going to try setting up an isolated master backend just running 30 and I'll set the HDPVR2 up on it and see how that goes. If I get that working I can then step back to 29.1 on that isolated backend and if I see it no longer functions, then I'll know I just need to bite the bullet and upgrade my master and other slave to 30.

Fortunately my existing setup is functioning well, so this testing phase can last as long as necessary.

[amyo777]

mythtv 30.0 with HDPVR2 on master backend is working for me. So at least now I have a baseline of what looks "normal" and I have a known functioning in mythtv context hauppauge config file.

[amyo777]

So having observed that it would work on v 30 of mythtv, I went ahead and upgraded my "production" system to 0.30 (the Dell backend, the RPI3 slave and the Aspire Revo slave). After that, and setting EXTRA_ARGS to --quiet in the init script, it just worked. Now it can be swapped in for the RPI3 and I'll see how it handles the HDMI out of the receiver. It has handled the HDMI out of an Android Tv box just great. Now that I'm to 30 I guess I can repurpose the Android box for a front end!