Apparent Plex Tuner crash on Linux with Hauppauge WinTV-dualHD

General Discussions Plex Features
1

I am running Open Media Vault - which is nifty appliance distribution based on Debian Jessie. Through the magic of using different kernels (https://forum.openmediavault.org/index.php/Thread/19321-ZFS-Plugin-ZoL-upgrade-possible/?postID=151964#post151964), I was able to upgrade Jessie to Linux 4.11.0-14.

At this point, the Hauppauge WinTV-dualHD was detectable and usable by Plex. I’m running Version 1.8.2.4209 of the plex media server.

I experienced a few days of bliss as Plex would add episodes of Night Court seemingly out of the air … okay it really was out of the air, but it sounds better how I said it.

My bliss did not last. When attempting to conjure up an episode of MST3K this evening, the tuner appeared to stall. I killed the “Plex Tuner Service” processes and bounced the plexmediaserver daemon.

It appeared to create a .grab/6003908b56a054f652ab19c73239bc0f0da5f164 directory and streamed the episode into it “Mystery Science Theater 3000 (1988) - S06E06 - Bloodlust.ts”

However, the file stopped around 12:10 this morning. I’ve snagged what appears to be the relevant log files below. Looks like there was an error at 00:10:40.370 which started the problems.

Sep 04, 2017 00:00:02.194 [0x7f1a23fff700] INFO - * tvs_program_streamer::check_pmt_version. New PMT version is detected: 9, 8
Sep 04, 2017 00:00:02.194 [0x7f1a23fff700] INFO - * CTVSStreamSource::AddPID. Requesting PID 81, client triplet://0:2537:5
Sep 04, 2017 00:00:02.194 [0x7f1a23fff700] INFO - * CTVSStreamSource::AddPID. Requesting PID 84, client triplet://0:2537:5
Sep 04, 2017 00:00:02.194 [0x7f1a23fff700] INFO - * CTVSStreamSource::AddPID. Requesting PID 8191, client triplet://0:2537:5
Sep 04, 2017 00:10:40.370 [0x7f1a2a7fc700] WARN - [E] http_streamer_t: http write error
Sep 04, 2017 00:10:40.371 [0x7f1a297fa700] INFO - * http_streamer_t::send_thread_func: removing http client
Sep 04, 2017 00:10:40.371 [0x7f1a297fa700] INFO - * http_streamer_t::send_thread_func: all clients are deleted. Setting exit flag
Sep 04, 2017 00:10:40.378 [0x7f1a237fe700] INFO - * streamer_container_t::control_thread. Removed disconnected streamer abc6338d-0a6a-430d-aad6-30ebe4071336
Sep 04, 2017 00:10:40.379 [0x7f1a237fe700] INFO - * streamer_container_t::delete_streamer. Last streamer was deleted. Stop program streamer
Sep 04, 2017 00:10:40.379 [0x7f1a237fe700] INFO - * dvb_program_streamer_t::stop. Stoping program streamer for channel triplet://0:2537:5
Sep 04, 2017 00:10:40.379 [0x7f1a237fe700] INFO - * transponder_streamer::stop_channel. Stop request for channel triplet://0:2537:5
Sep 04, 2017 00:10:40.410 [0x7f1a23fff700] INFO - * CTVSStreamSource::RemoveAllPids. Removing PIDs for client triplet://0:2537:5
Sep 04, 2017 00:10:40.410 [0x7f1a237fe700] INFO - * transponder_streamer::start_idle_timer
Sep 04, 2017 00:10:40.418 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 0
Sep 04, 2017 00:10:40.426 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 1
Sep 04, 2017 00:10:40.434 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 16
Sep 04, 2017 00:10:40.442 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 17
Sep 04, 2017 00:10:40.450 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 18
Sep 04, 2017 00:10:40.458 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 80
Sep 04, 2017 00:10:40.466 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 81
Sep 04, 2017 00:10:40.474 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 84
Sep 04, 2017 00:10:40.484 [0x7f1a22ffd700] INFO - * tuner 0, demux 0. Deleting PID 8191
Sep 04, 2017 00:10:43.671 [0x7f1a32e6b700] INFO - * device_manager_t::idle_timer_func. Stream container for channel triplet://0:2537:5 on device dvb#v4l#0#/sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0 is idle. Deleting it.
Sep 04, 2017 00:10:50.387 [0x7f1a28ff9700] INFO - * Stopping graph
Sep 04, 2017 00:10:50.436 [0x7f1a23fff700] INFO - * Stop device request for tuner 0, frontend 0
Sep 04, 2017 00:10:50.499 [0x7f1a22ffd700] INFO - * Exiting streaming thread for tuner 0, frontend 0
Sep 04, 2017 00:10:53.691 [0x7f1a32e6b700] INFO - * device_manager_t::idle_timer_func. Device dvb#v4l#0#/sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0 is idle. Unloading it.
Sep 04, 2017 00:10:53.691 [0x7f1a32e6b700] INFO - * transponder_streamer::stop_idle_timer
Sep 04, 2017 00:10:53.691 [0x7f1a32e6b700] INFO - * CTVSStreamSource::Term. Waiting for streaming thread to stop
Sep 04, 2017 00:10:53.707 [0x7f1a23fff700] INFO - * Stop device request for tuner 65535, frontend 0
Sep 04, 2017 00:10:53.707 [0x7f1a32e6b700] INFO - * CTVSStreamSource::Term. Streaming is stopped
Sep 04, 2017 00:10:53.707 [0x7f1a32e6b700] INFO - * CTVSStreamSource::Term. Stream source is stopped
Sep 04, 2017 00:10:54.500 [0x7f1a28ff9700] INFO - * transponder_streamer::idle_thread_func finished

What data can I provide to help troubleshoot this issue?

Thanks!

2

Additional information. Plex was suppose to download Night Court at 00:30 as well. The Plex Recording Schedule has a nice big red dot on that episode of Night Court - not the fancy “play” button. I see a new directory was created in the .grab folder. But there is no data in it.

At 01:22, when getting a list of running services, I see the following:

plex 1283 0.0 0.0 4336 708 ? Ss Sep03 0:00 /bin/sh -c LD_LIBRARY_PATH=/usr/lib/plexmediaserver “/usr/lib/plexmediaserver/Plex Media Server”
plex 1284 7.0 0.1 1039564 123724 ? Sl Sep03 5:47 /usr/lib/plexmediaserver/Plex Media Server
plex 1297 0.2 0.1 1726756 66928 ? SNl Sep03 0:12 Plex Plug-in [com.plexapp.system] /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.8.2.4209-b1d4aa012 /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/System.bundle
plex 1352 0.2 0.0 1201180 19904 ? Sl Sep03 0:11 /usr/lib/plexmediaserver/Plex Tuner Service /usr/lib/plexmediaserver/Resources/Tuner/Private /usr/lib/plexmediaserver/Resources/Tuner/Shared 1.8.2.4209-b1d4aa012 32600 /waitmutex
plex 1419 0.0 0.0 95908 21864 ? S Sep03 0:01 /usr/lib/plexmediaserver/Plex Transcoder -codec:0 mpeg2video -noaccurate_seek -i http://127.0.0.1:32400/livetv/sessions/75d4ba11-5d7a-4c18-9fc2-c2bedde392cb/09feaeba-d7dd-443c-bf07-2fbfff5dd68f/index.m3u8?X-Plex-Token=YFZQtLLoLLH4wp2LSvnT -map 0:0 -codec:0 copy -map 0:1 -metadata:s:1 language=eng -codec:1 copy -copypriorss:1 0 -f mpegts -map_metadata -1 -map_chapters -1 -t 1826 /media/208f31bd-6fc1-4b76-bb1e-a868c56fe332/TV_Shows/.grab/6003908b56a054f652ab19c73239bc0f0da5f164/Mystery Science Theater 3000 (1988) - S06E06 - Bloodlust.ts -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/09feaeba-d7dd-443c-bf07-2fbfff5dd68f/ba086143-29f1-41c6-8425-9497bf09dee8/progress
plex 2656 0.1 0.0 955052 39476 ? Sl 00:43 0:02 Plex Plug-in [com.plexapp.agents.opensubtitles] /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.8.2.4209-b1d4aa012 /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/OpenSubtitles.bundle
plex 2868 0.3 0.0 1174960 49140 ? Sl 00:46 0:06 Plex Plug-in [com.plexapp.agents.thetvdb] /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.8.2.4209-b1d4aa012 /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/TheTVDB.bundle
plex 2908 0.0 0.0 952528 36376 ? Sl 00:47 0:02 Plex Plug-in [com.plexapp.agents.plexthememusic] /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.8.2.4209-b1d4aa012 /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/PlexThemeMusic.bundle
plex 3402 0.1 0.0 950760 41376 ? Sl 00:55 0:02 Plex Plug-in [com.plexapp.agents.localmedia] /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.8.2.4209-b1d4aa012 /usr/lib/plexmediaserver/Resources/Plug-ins-b1d4aa012/LocalMedia.bundle

Looks like whatever tanked my MST3K episode, hung the process and could not continue. That hang has stopped any future attempts at recording OTA signals.

3

Same here:
___==> Plex Tuner Service.log <==
Sep 30, 2017 15:09:02.082 [0x7fb36d2ff700] WARN - [E] http_streamer_t: http write error
Sep 30, 2017 15:09:02.083 [0x7fb364ff9700] INFO - * http_streamer_t::send_thread_func: removing http client
Sep 30, 2017 15:09:02.083 [0x7fb364ff9700] INFO - * http_streamer_t::send_thread_func: all clients are deleted. Setting exit flag
Sep 30, 2017 15:09:02.091 [0x7fb33e7fc700] INFO - * streamer_container_t::control_thread. Removed disconnected streamer da84de95-0a2a-4aca-a483-40321314c0a5
Sep 30, 2017 15:09:02.091 [0x7fb33e7fc700] INFO - * streamer_container_t::delete_streamer. Last streamer was deleted. Stop program streamer
Sep 30, 2017 15:09:02.091 [0x7fb33e7fc700] INFO - * dvb_program_streamer_t::stop. Stoping program streamer for channel triplet://42249:1:17
Sep 30, 2017 15:09:02.091 [0x7fb33e7fc700] INFO - * transponder_streamer::stop_channel. Stop request for channel triplet://42249:1:17
Sep 30, 2017 15:09:02.121 [0x7fb33d7fa700] INFO - * tvs_program_streamer::tuning_thread. Finished tuning thread for program triplet://42249:1:17
Sep 30, 2017 15:09:02.171 [0x7fb33f7fe700] INFO - * CTVSStreamSource::RemoveAllPids. Removing PIDs for client triplet://42249:1:17
Sep 30, 2017 15:09:02.171 [0x7fb33e7fc700] INFO - * transponder_streamer::start_idle_timer
Sep 30, 2017 15:09:02.174 [0x7fb33dffb700] INFO - * tuner 0, demux 0. Deleting PID 0
Sep 30, 2017 15:09:02.184 [0x7fb33dffb700] INFO - * tuner 0, demux 0. Deleting PID 1