Stream not starting with HDHomeRun Connect

Live TV & DVR
,
1

Server Version#: 1.25.9.5721
Player Version#: Plex Web 4.76.1
Tuner Make/Model: HDHomeRun Connect (HDHR4-2DT)
Guide/Lineup name: ?
Using XMLTV?: No
Channel number/Name: All

So I have a HDHomeRun Connect hooked up on my network. From my PC, streams play with no problems from HDHomeRun windows app or from VLC.

When I launch a stream from Plex, I can see the the right channel is launched on the tuner. But the stream never starts.

And my logs are full of transcoder errors :

Apr 07, 2022 20:58:01.438 [0x7f4ba6cf4b38] Erreur ā€” [Grabber/5fc76da1336b43002e23637b-5fc705e8a2236e002d69f44b-jubsfy411rbhgj7mocnsb7f1] Unknown metadata type:
Apr 07, 2022 20:58:05.134 [0x7f4ba703cb38] Erreur ā€” [Transcoder] [NULL @ 0x7f89011c15c0] non-existing PPS 0 referenced
Apr 07, 2022 20:58:05.135 [0x7f4ba8406b38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] non-existing PPS 0 referenced
Apr 07, 2022 20:58:05.135 [0x7f4ba703cb38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] decode_slice_header error
Apr 07, 2022 20:58:05.136 [0x7f4ba8406b38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] no frame!
Apr 07, 2022 20:58:05.136 [0x7f4ba703cb38] Erreur ā€” [Transcoder] [NULL @ 0x7f89011c15c0] non-existing PPS 0 referenced
Apr 07, 2022 20:58:05.137 [0x7f4ba8406b38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] non-existing PPS 0 referenced
Apr 07, 2022 20:58:05.137 [0x7f4ba703cb38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] decode_slice_header error
Apr 07, 2022 20:58:05.137 [0x7f4ba8406b38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] no frame!
Apr 07, 2022 20:58:05.138 [0x7f4ba703cb38] Erreur ā€” [Transcoder] [NULL @ 0x7f89011c15c0] non-existing PPS 0 referenced
Apr 07, 2022 20:58:05.138 [0x7f4ba8406b38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] non-existing PPS 0 referenced
Apr 07, 2022 20:58:05.138 [0x7f4ba703cb38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] decode_slice_header error
Apr 07, 2022 20:58:05.138 [0x7f4ba8406b38] Erreur ā€” [Transcoder] [h264 @ 0x7f890119e4c0] no frame!

The server has no hardware encoding option (Proxmox VM). Iā€™ve tried with and without video transcoding option.

Any idea of what I could try to make this work ?

Thanks for your help.

2

Would you mind providing a bit more information that may be useful?

  • Which web browser are you using, and what OS (Win or Linux?)

  • Are you able to test using any other Plex client (e.g. the Plex for Win client, since it does appear that you have a win computer where you tried the HDHR client.)

  • Are you getting failures when trying to record, or only with attempts to stream Live TV from your HDHR?

Also, would you be able to post full debug level server logs covering a time period where you have a failed tune?

4

I have this same problem. Here are my logs.

In my EPG I only have Unknown Airing for all channels. I click to play on a channel it just spins but I do see it start recording.

Plex Media Server Logs_2022-04-07_23-42-46.zip (4.8 MB)

5

Did you try to refresh the guide?

6

Hello,

Thanks for your help.

So Iā€™ve tried from my phone, and also from the same PC with the Plex Windows App, and both worked well. So it is only when using plex web via browser that streams donā€™t start.

Iā€™ve tried with every browsers installed on my PC (Brave, which is my main, Chrome, Firefox and Edge) and get the same results. The stream seems to launch (I get to the play screen, with the play/pause commands, a picture of the show in the background. But there is the spinning gold circle over it and it never stops).

I also tried to delete and recreate the HDHR entry with no luck.

My logs are full of these :

Apr 08, 2022 12:11:15.598 [0x7f4ba6ea7b38] ERROR - [Grabber/5fc76da1336b43002e23637b-5fc705e8a2236e002d69f44b-jubsfy411rbhgj7mocnsb7f1] Unable to find title for item of type 5
Apr 08, 2022 12:11:15.598 [0x7f4ba6ea7b38] ERROR - [Grabber/5fc76da1336b43002e23637b-5fc705e8a2236e002d69f44b-jubsfy411rbhgj7mocnsb7f1] Unable to find title for item of type 5
Apr 08, 2022 12:11:16.167 [0x7f4ba5f52b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.168 [0x7f4ba6d58b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.169 [0x7f4ba6b7bb38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] decode_slice_header error
Apr 08, 2022 12:11:16.169 [0x7f4ba6d7bb38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] no frame!
Apr 08, 2022 12:11:16.185 [0x7f4ba45d5b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.186 [0x7f4ba5f52b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.186 [0x7f4ba6d58b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] decode_slice_header error
Apr 08, 2022 12:11:16.187 [0x7f4ba6b7bb38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] no frame!
Apr 08, 2022 12:11:16.187 [0x7f4ba6d7bb38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.188 [0x7f4ba45d5b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.188 [0x7f4ba5f52b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] decode_slice_header error
Apr 08, 2022 12:11:16.188 [0x7f4ba6d58b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] no frame!
Apr 08, 2022 12:11:16.214 [0x7f4ba6b7bb38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.215 [0x7f4ba6d7bb38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] non-existing PPS 0 referenced
Apr 08, 2022 12:11:16.215 [0x7f4ba45d5b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] decode_slice_header error
Apr 08, 2022 12:11:16.216 [0x7f4ba5f52b38] ERROR - [Transcoder] [h264 @ 0x7f83b3bfe600] no frame!

In Plex Media Server.log, which seems to be the only log file updated during the process.

When starting the session, I also had a few lines added to the com.plexapp.system.log file but they seems to have nothing to do about it.:

2022-04-08 12:10:52,311 (7f7991a4fb38) : DEBUG (runtime:717) - Handling request GET /system/:/prefs
2022-04-08 12:10:52,313 (7f7991a4fb38) : DEBUG (runtime:814) - Found route matching /system/:/prefs
2022-04-08 12:10:52,316 (7f7991a4fb38) : DEBUG (runtime:88) - Sending packed state data (99 bytes)
2022-04-08 12:10:52,316 (7f7991a4fb38) : DEBUG (runtime:924) - Response: [200] MediaContainer, 418 bytes

I have not tried to record yet. Iā€™ll launch a session now and let you know.

Thanks again for your help.

7

So I have launched a 5 minutes record session, and it worked perfectly. So this is only streaming from plex web client that never starts.

8

@zejedi If you are able to record, and are also able to play OTA Live TV from other Plex clients other than Plex Web (e.g. the Plex for Win client), then look at what the CPU is doing on you Plex Server (Proxmox VM) during an attempted OTA Live TV stream. The web client is the least capable in terms of codecs and what it can direct play/stream. It may be that Plex Web is forcing a transcode that your VM isnā€™t able to handle, but other clients that can direct play/stream work because they donā€™t produce the transcode overhead.

9

@brad799 I see a number of errors in your logs that could lead to the problem.

In your Plex Server log, when trying to optimize your database Plex is getting an error. It doesnā€™t specifically say that there is a database corruption, but it looks wrong:

Apr 07, 2022 23:39:57.971 [5104] DEBUG - [Database optimization/com.plexapp.plugins.library.db] Activity: updated activity bd1f5bd1-1960-403f-90c4-16ef51f08259 - completed 40.0% - Optimizing database
Apr 07, 2022 23:39:58.936 [5104] ERROR - [Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x78fb4438, 14, os_win.c:47340: (87) winOpen(C:\Users\brad\AppData\Local\Temp\1\etilqs_hKQXjT2amem3Hqh) - The parameter is incorrect.
Apr 07, 2022 23:39:58.936 [5104] ERROR - [Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x78fb4438, 14, cannot open file at line 47341 of [1b256d97b5]
Apr 07, 2022 23:39:58.936 [5104] ERROR - [Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x78fb4438, 14, statement aborts at 6: [DELETE FROM 'main'.'fts4_metadata_titles_icu_docsize' WHERE docid = ?] unable to open database file
Apr 07, 2022 23:39:58.936 [5104] ERROR - [Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x78fb4438, 14, statement aborts at 10: [delete from fts4_metadata_titles_icu] unable to open database file
Apr 07, 2022 23:39:58.938 [5104] ERROR - [Database optimization/com.plexapp.plugins.library.db] Exception inside transaction (inside=1) (E:\jenkins\server\3589017872\Library\FullTextSearch.cpp:58): sqlite3_statement_backend::loadOne: unable to open database file
Apr 07, 2022 23:39:58.946 [5104] ERROR - Thread: Uncaught exception running async task which was spawned by thread 6428: sqlite3_statement_backend::loadOne: unable to open database file
Apr 07, 2022 23:39:58.947 [5104] DEBUG - Activity: Ended activity bd1f5bd1-1960-403f-90c4-16ef51f08259.

Also in your Plex Media server log, when trying to contact your Plex Tuner Service it is getting a 404 (not found) message back:

Apr 07, 2022 11:32:58.360 [6428] DEBUG - HTTP requesting GET https://epg.provider.plex.tv/library/metadata/5fc69815d7538d002d3adc8f?X-Plex-Api-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=en
Apr 07, 2022 11:32:58.361 [5128] DEBUG - [HttpClient] HTTP/1.1 (0.0s) 404 response from GET http://127.0.0.1:32600/devices/dvb%23bda%230%23%40device%3apnp%3a%5c%5c%3f%5cpci%23ven_1131%26dev_7160%26subsys_1e551461%26rev_01%236%26692f63c%260%260020020b%23%7b71985f48-1ca1-11d3-9cc8-00c04f7971e0%7d%5c%7beaddee01-bc81-4ca2-b48b-5b4158d60000%7d (reused)
Apr 07, 2022 11:32:58.361 [3364] ERROR - Error parsing content.
Apr 07, 2022 11:32:58.361 [3364] ERROR - Error parsing XML: Error parsing file.
Apr 07, 2022 11:32:58.361 [3364] ERROR - Grabber: Error obtaining device status.
Apr 07, 2022 11:32:58.361 [3364] ERROR - DVR:Device: Error refreshing existing device device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_1131%26dev_7160%26subsys_1e551461%26rev_01%236%26692f63c%260%260020020b%23, marking as dead.

Looking at your Plex Tuner Service logs, it looks like it canā€™t find your tuner. (What is your tuner, and are you able to access it from outside of Plex?)

Apr 07, 2022 11:32:58.341 [7860] INFO - [I] DeviceGetListEx_impl: pDL->Count:0
Apr 07, 2022 11:32:58.341 [5720] DEBUG - [I] file_tuner::get_stream_file. File C:\Program Files (x86)\Plex\Plex Media Server\Resources\Tuner\Private\devices\dvb\file\drv_file.ini does not exist
Apr 07, 2022 11:32:58.341 [5720] INFO - [I] web_service_t::process_device_status
Apr 07, 2022 11:32:58.341 [1256] WARN - [E] enum_create(): Class not found, CreateClassEnumerator returned S_FALSE
Apr 07, 2022 11:32:58.341 [1256] INFO - [I] DeviceGetListEx_impl: pDL->Count:0
Apr 07, 2022 11:32:58.341 [5720] WARN - [E] web_service_t::get_device_status. get_device_info returned error for device dvb#bda#0#@device:pnp:\\?\pci#ven_1131&dev_7160&subsys_1e551461&rev_01#4&1ab2be6e&0&00e3#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{eaddee01-bc81-4ca2-b48b-5b4158d60000}
Apr 07, 2022 11:32:58.341 [7732] INFO - [I] web_service_t::process_device_status
Apr 07, 2022 11:32:58.341 [6484] WARN - [E] enum_create(): Class not found, CreateClassEnumerator returned S_FALSE
Apr 07, 2022 11:32:58.341 [6484] INFO - [I] DeviceGetListEx_impl: pDL->Count:0
Apr 07, 2022 11:32:58.341 [7732] WARN - [E] web_service_t::get_device_status. get_device_info returned error for device dvb#bda#0#@device:pnp:\\?\pci#ven_1131&dev_7160&subsys_1e551461&rev_01#6&36e336d5&0&0000020b#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{eaddee01-bc81-4ca2-b48b-5b4158d60000}
Apr 07, 2022 11:32:58.357 [7732] INFO - [I] web_service_t::process_device_status
Apr 07, 2022 11:32:58.357 [0880] WARN - [E] enum_create(): Class not found, CreateClassEnumerator returned S_FALSE
Apr 07, 2022 11:32:58.357 [0880] INFO - [I] DeviceGetListEx_impl: pDL->Count:0
Apr 07, 2022 11:32:58.357 [7732] WARN - [E] web_service_t::get_device_status. get_device_info returned error for device dvb#bda#0#@device:pnp:\\?\pci#ven_1131&dev_7160&subsys_1e551461&rev_01#6&692f63c&0&0020020b#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{eaddee01-bc81-4ca2-b48b-5b4158d60000}

Iā€™d suggest tryng deleting your DVR and then re-add it.

10

@hokierulz It appears when using another device that I can stream channels. Iā€™ve been able to open streams on WebOS and Android app. Things appear to be somewhat working with those. On Roku it says ā€œthere was an error loading the guide, please try againā€. On the web and WebOS the guide shows ā€œUnknown Airingā€ for all channels" and on the web version it will never open the stream.

Iā€™m using two HDHomeRun Connects. It must find them because I wouldnā€™t be able to stream. Iā€™ve rebuilt my DVR twice and I guess from what Iā€™ve been told doing so actually made it worse on Roku. Apparently it would stream before but the guide was just blank white bars whereas it doesnā€™t work at all now after the rebuilds.

Attached are new logs that would include some streaming to WebOS and Android.
Plex Media Server Logs_2022-04-08_17-02-59.zip (4.8 MB)

11

And now more information. If I go to the ā€œWhatā€™s On Nowā€ section - it shows what is actually on and I can start the stream from the web client and WebOS. So it appears that the grid Guide is broken.

12

Iā€™m going to call this a really weird anonmoly, because after getting things to work by using the ā€œWhatā€™s on nowā€ the grid guide populated itself. It is now working exactly as it was before.

1 Like
13

I see 2 processes starting when I launch a stream from Plex Web on the VM :

plex 20740 3.3 0.0 45312 16896 ? S 19:46 0:01 /usr/lib/plexmediaserver/Plex Transcoder -noaccurate_seek -ignore_unknown -scan_all_pmts -1 -rw_timeout 30000000 -reconnect 1 -reconnect_s
plex 20746 6.3 0.0 50816 20984 ? Sl 19:46 0:03 /usr/lib/plexmediaserver/Plex Transcoder -codec:0 h264 -codec:1 eac3_eae -eae_prefix:1 kgtl5oy07zkoydlwi6u1hhsa_ -noaccurate_seek -live_st

The 1st one never exceeds 5% CPU and the 2nd one 10%.

The full command line for these 2 processes are here :

/usr/lib/plexmediaserver/Plex Transcoder -noaccurate_seek -ignore_unknown -scan_all_pmts -1 -rw_timeout 30000000 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 30 -fflags +discardcorruptts+fillwallclockdts -probesize 20000000 -i http://10.0.0.22:5004/auto/v1 -map 0:V? -codec:V copy -map 0:a? -codec:a copy -map 0:s? -codec:s copy -break_non_keyframes 1 -segment_format mpegts -f ssegment -individual_header_trailer 0 -segment_time 1 -segment_start_number 0 -segment_time_delta 0.25 -segment_list http://127.0.0.1:32400/video/:/transcode/session/4a9bb850-dd49-41ba-93e1-5a0cbe8320c3/07e3ffb7-8242-45a7-bdbb
-cfa7511d68d8/manifest?X-Plex-Http-Pipeline=infinite -segment_list_type csv -segment_list_size 5 -segment_list_separate_stream_times 1 -segment_list_unfinished 1 -max_delay 5000000 -map_metadata -1 -map_chapters -1 media-%05d.ts -y -nostats -loglevel quiet -loglevel_plex error -xioerror -progressurl http://127.0.0.1:32400/video/:/transcode/session/4a9bb850-dd49-41ba-93e1-5a0cbe8320c3/07e3ffb7-8242-45a7-bdbb-cfa7511d68d8/progress

/usr/lib/plexmediaserver/Plex Transcoder -codec:0 h264 -codec:1 eac3_eae -eae_prefix:1 xmuhbutxf14eougywi3ji7ma_ -noaccurate_seek -live_start_index 0 -probesize 20000000 -i http://127.0.0.1:32400/livetv/sessions/4a9bb850-dd49-41ba-93e1-5a0cbe8320c3/a1828gaxoot768sz7gcfk7bc/index.m3u8?offset=0.000000&X-Plex-Incomplete-Segments=1&X-Plex-Token=SyqSy4iYvakynHWuHdUZ -map 0:0 -codec:0 copy -filter_complex [0:1] aresample=async=1:ocl=ā€˜stereoā€™:rematrix_maxval=0.000000dB:osr=48000[0] -map [0] -metadata:s:1 language=fra -codec:1 aac -b:1 256k -segment_format mpegts -f ssegment -individual_header_trailer 0 -segment_time 1 -segment_start_number 0 -segment_time_delta 0.25 -segment_list http://127.0.0.1:32400/video/:/transcode/session/xmuhbutxf14eougywi3ji7ma/a028c78f-4dbe-470f-9a44-4b74d8a1a991/manifest?X-Plex-Http-Pipeline=infinite -segment_list_type csv -segment_list_size 5 -segment_list_separate_stream_times 1 -segment_list_unfinished 1 -max_delay 5000000 -map_metadata -1 -map_chapters -1 media-%05d.ts -y -nostats -loglevel quiet -loglevel_plex error -xioerror -progressurl http://127.0.0.1:32400/video/:/transcode/session/xmuhbutxf14eougywi3ji7ma/a028c78f-4dbe-470f-9a44-4b74d8a1a991/progress

14

Itā€™s clearly a problem with the transcoder, which is consistent with other clients that can direct stream/play working and recording working. The errors from above (e.g. ā€œnon-existing PPS 0 referencedā€, ā€œdecode_slice_header errorā€) are from ffmpeg, which Plex uses to transcode. Searching for these errors I find developers getting them who use ffmpeg directly, Emby, Plex, etc. but no specific resolution. I did see developers chatting about changing settings being passed to ffmpeg.

Under Plex Web ā†’ Settings ā†’ Transcoder have you tried changing ā€œTranscoder Qualityā€ from ā€œAutomaticā€ to one of the other options?

15

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.