Server Version#:1.32.2.7100
Since updating to the latest PMS version several recordings of various TV episodes have only recorded a few minutes of the scheduled recording, from 2-7 minutes instead of getting the whole episode. Not every episode or every show, but easily half of recordings have been incomplete. Never had this problem prior to this version.
I rolled back to version 1.32.0.6918 and it fixed the problem. I tried the latest version of PMS, 1.32.4.7164, and had the same problem again.
This is getting ridiculous now. I’ve tried multiple versions of PMS and still have the same problem, multiple shows only recording a very small amount, sometimes under a minute. I’m adding logs in the vain hope that someone can tell me what’s going on
Plex Media Server Logs_2023-06-06_19-21-31.zip (5.1 MB)
.
So after skipping a few versions of PMS I tried the latest version, still getting the issue with not recording full episodes of some shows. I tried stepping back to an earlier version, same issue. This is clearly something in my server that’s a problem, so do I need to just tear the whole thing down and start over? I really don’t want to do that because I share with a lot of family that use it a lot. I’m at my wit’s end here, really wish I could get someone to pay attention that might be able to figure out what my problem is. Attaching latest log.
Plex Media Server Logs_2023-07-26_15-40-27.zip (4.9 MB)
Once again, no response from Plex. I think if I had known the kind of support I would get I’d have thought twice about shelling out the money for a lifetime Plex pass.
Are you using IPTV or a tuner card?
Are you killing the server or is it crashing? How did you start PMS.
Instead of pointing Plex at S:\ for the tv library it should be S:\TV and S:\MOVIE.
I saw a Hauppauge tuner, some people just can’t get the drivers to be stable.
Do you have offsets defined in your scheduled recordings, it looks like channel conflicts?
A few months ago (5/26) you stated that your recordings were ending prematurely. On 6/6 you then tested two versions, 1.32.0.6918 which “fixed the problem,” and 1.32.4.7164 which exhibited the same problem.
Later on 6/6, you stated that you tested multiple versions again and that they had the same problem.
On 7/26 you stated that you tried “the latest version” and were still experiencing the issue. And that you tried stepping back to an earlier version (did you revert to 1.32.0.6918 which previously resolved this?) and that it didn’t resolve the issue. And, based on that, you thought it was “clearly something in my server that’s a problem.”
Today, you don’t mention any versions and lament Plex’s lack of response.
In addition to @pl_5309’ questions/suggestions, I’d suggest you disable real-time transcoding to H.264 if you have that enabled in Plex DVR’s “device settings.” It hurts much more than it helps.
Why would the path make a difference? I’ve had it the same way for a couple of years and this is a recent issue. I don’t have channel conflicts, most of the shows I record are only one at a time and I have a 4 tuner card. I don’t have a problem with the Hauppauge drivers.
That’s why I’ve attached logs each time, in the hopes that someone who can interpret the logs can tell me the problem. But this is why I have an issue with Plex in general. Still no Plex employees to assist with the support of a feature that’s only available to paying customers. If the business I worked for treated customers like this we’d be out of business.
Updated today to PMS version 1.32.6.7468, encountered the same issues again. Some episodes record OK, some cut off at 2 minutes. Attaching logs again in the hopes that someone can help me resolve this.
Plex Media Server Logs_2023-09-14_13-17-18.zip (5.4 MB)
If you really want help you will have to make an effort, try providing some additional info like:
Name of the show being recorded.
Time it failed.
You haven’t done anything about the S: drive.
Do not use the root of your drive as a folder location. It will cause problems. Make an appropriate folder and put your media in that, such as D:/Movies . Reference
Sep 14, 2023 13:02:04.014 [6192] ERROR - [Req#112b0/Transcode/8bb50c28-5dd1-484d-b674-6c5a8e2c057a/b7fdddad-02fc-4896-bafe-d5e797b2e6b7] av_interleaved_write_frame(): Invalid argument
Sep 14, 2023 13:02:04.014 [7276] ERROR - [Req#112b4/Transcode/8bb50c28-5dd1-484d-b674-6c5a8e2c057a/b7fdddad-02fc-4896-bafe-d5e797b2e6b7] Error writing trailer of S:\.grab\8fd20dedec915bf5c865d5772fb94911b9504302-88d24317a31be0ffff5f4096e0d9ab12274a1f3c\Laverne & Shirley (1976) - S04E10 - A Chorus Line.ts: Invalid argument
Sep 14, 2023 13:02:04.015 [7276] ERROR - [Req#112b7/Transcode/8bb50c28-5dd1-484d-b674-6c5a8e2c057a/b7fdddad-02fc-4896-bafe-d5e797b2e6b7] Error closing file S:\.grab\8fd20dedec915bf5c865d5772fb94911b9504302-88d24317a31be0ffff5f4096e0d9ab12274a1f3c\Laverne & Shirley (1976) - S04E10 - A Chorus Line.ts: Invalid argument
Sep 14, 2023 13:02:04.018 [1568] DEBUG - Jobs: 'C:\Program Files\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 12516 is 1 (failure)
Sep 14, 2023 13:02:04.018 [11528] DEBUG - Killing job.
Sep 14, 2023 13:02:04.018 [11528] DEBUG - Job was already killed, not killing again.
Sep 14, 2023 13:02:04.018 [11528] DEBUG - Stopping transcode session 8bb50c28-5dd1-484d-b674-6c5a8e2c057a
Sep 14, 2023 13:02:04.018 [1396] DEBUG - [Req#f20e/Grabber/8fd20dedec915bf5c865d5772fb94911b9504302] Recorder: No more consumers, stopping.
Sep 14, 2023 13:02:04.018 [1396] DEBUG - [Req#f20e/Grabber/8fd20dedec915bf5c865d5772fb94911b9504302] Recorder: Asked to stop recording 'Laverne & Shirley - E10 - A Chorus Line' on channel 5fc76c796b022a002d86984e-5fc705ed088e7d002d4c703d.
Sep 14, 2023 13:02:04.018 [1396] DEBUG - [Req#f20e/Grabber/8fd20dedec915bf5c865d5772fb94911b9504302] Recorder: Stopping transcode session.
Sep 14, 2023 13:02:04.018 [6236] DEBUG - [Grabber/8fd20dedec915bf5c865d5772fb94911b9504302] Recording for 'Laverne & Shirley - E10 - A Chorus Line' on channel 5fc76c796b022a002d86984e-5fc705ed088e7d002d4c703d stopped with status complete.
Sep 14, 2023 13:02:04.018 [6236] DEBUG - [Grabber/8fd20dedec915bf5c865d5772fb94911b9504302] Using recording status.
Sep 14, 2023 13:02:04.019 [8392] DEBUG - Streaming Resource: Terminated session 000001CAEB332880:8bb50c28-5dd1-484d-b674-6c5a8e2c057a with reason Recording failed. Please check your tuner or antenna.
Weak signal or misbehaving driver.
Sep 14, 2023 13:02:00.661 [12000] DEBUG - [I] enum_get_name(): IPropertyBag->Read method failed -2147024894
Sep 14, 2023 13:02:00.661 [12000] DEBUG - [I] BDA: DeviceGetListEx_impl. Skipped nameless BDA driver
Sep 14, 2023 13:02:00.661 [12000] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV-dualHD ATSC Tuner 2]
Sep 14, 2023 13:02:00.661 [12000] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_2040&pid_846d#6&33c275d6&0&3#
Sep 14, 2023 13:02:00.661 [12000] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV-dualHD ATSC Tuner]
Sep 14, 2023 13:02:00.661 [12000] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_2040&pid_846d#6&33c275d6&0&3#
Sep 14, 2023 13:02:00.661 [12000] DEBUG - [I] enum_get_name(): IPropertyBag->Read method failed -2147024894
Sep 14, 2023 13:02:00.661 [12000] DEBUG - [I] BDA: DeviceGetListEx_impl. Skipped nameless BDA driver
Sep 14, 2023 13:02:00.661 [12000] INFO - [I] DeviceGetListEx_impl: pDL->Count:8
Sep 14, 2023 13:02:00.661 [2160] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#bda#0#@device:pnp:\\?\pci#ven_14f1&dev_8880&subsys_6a180070&rev_04#6&12867a92&0&000800e8#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9f0e8b90-c677-11db-beb4-00a0c9f21fc7}, Name Hauppauge WinTV 885 ATSC Tuner/Demod, Standards 24, Serial
Sep 14, 2023 13:02:00.661 [2160] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#bda#0#@device:pnp:\\?\pci#ven_14f1&dev_8880&subsys_6a180070&rev_04#6&12867a92&0&000800e8#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9f0e8b98-c677-11db-beb4-00a0c9f21fc7}, Name Hauppauge WinTV 885 ATSC Tuner/Demod 2, Standards 24, Serial
Sep 14, 2023 13:02:00.677 [2160] INFO - [I] device_manager_t::get_device_list. Device Hauppauge WinTV 885 ATSC Tuner/Demod, id dvb#bda#0#@device:pnp:\\?\pci#ven_14f1&dev_8880&subsys_6a180070&rev_04#6&12867a92&0&000800e8#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9f0e8b90-c677-11db-beb4-00a0c9f21fc7}, tuners 1, state 2, standards 24, serial
Sep 14, 2023 13:02:00.677 [2160] INFO - [I] device_manager_t::get_device_list. Device Hauppauge WinTV 885 ATSC Tuner/Demod 2, id dvb#bda#0#@device:pnp:\\?\pci#ven_14f1&dev_8880&subsys_6a180070&rev_04#6&12867a92&0&000800e8#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9f0e8b98-c677-11db-beb4-00a0c9f21fc7}, tuners 1, state 0, standards 24, serial
Sep 14, 2023 13:02:04.004 [10400] WARN - [E] http_streamer_t::handle_read: read_error: An existing connection was forcibly closed by the remote host (10054)
Sep 14, 2023 13:02:04.004 [10400] WARN - [E] http_streamer_t: http write error
Sep 14, 2023 13:02:04.004 [12308] INFO - [I] http_streamer_t::send_thread_func: removing http client
Sep 14, 2023 13:02:04.004 [12308] INFO - [I] http_streamer_t::send_thread_func: all clients are deleted. Setting exit flag
Sep 14, 2023 13:02:04.020 [8756] INFO - [I] streamer_container_t::control_thread. Removed disconnected streamer 90ea3e0c-b819-4d44-8957-2c2a8ec5bab3
Sep 14, 2023 13:02:04.020 [8756] INFO - [I] streamer_container_t::delete_streamer. Last streamer was deleted. Stop program streamer
Sep 14, 2023 13:02:04.020 [8756] INFO - [I] dvb_program_streamer_t::stop. Stoping program streamer for channel triplet://0:2753:7
Sep 14, 2023 13:02:04.020 [8756] INFO - [I] transponder_streamer::stop_channel. Stop request for channel triplet://0:2753:7
Sep 14, 2023 13:02:04.036 [9956] INFO - [I] CTVSStreamSource::RemoveAllPids. Removing PIDs for client triplet://0:2753:7
Sep 14, 2023 13:02:04.036 [8756] INFO - [I] transponder_streamer::start_idle_timer
Sep 14, 2023 13:02:04.395 [7996] INFO - [I] Stopping graph
Sep 14, 2023 13:02:04.426 [6256] INFO - [I] StopDevice()
Sep 14, 2023 13:02:04.442 [6256] INFO - [I] stop_graph: hr=0, state=0
Sep 14, 2023 13:02:04.473 [7064] INFO - [I] web_service_t::process_device_status
Sep 14, 2023 13:02:04.473 [7064] INFO - [I] device_reactor_t::get_device_list (dvb#bda#pci#ven_14f1&dev_8880&subsys_6a180070&rev_04#6&12867a92&0&000800e8#)
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV-dualHD ATSC Tuner]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_2040&pid_846d#0014073123#
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] enum_get_name(): IPropertyBag->Read method failed -2147024894
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] BDA: DeviceGetListEx_impl. Skipped nameless BDA driver
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] enum_get_name(): IPropertyBag->Read method failed -2147024894
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] BDA: DeviceGetListEx_impl. Skipped nameless BDA driver
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV-dualHD ATSC Tuner 2]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_2040&pid_846d#0014073123#
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV 885 ATSC Tuner/Demod]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path pci#ven_14f1&dev_8880&subsys_6b180070&rev_04#6&2a91e53a&0&001000e8#
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV 885 ATSC Tuner/Demod 2]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path pci#ven_14f1&dev_8880&subsys_6b180070&rev_04#6&2a91e53a&0&001000e8#
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV 885 ATSC Tuner/Demod]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path pci#ven_14f1&dev_8880&subsys_6a180070&rev_04#6&12867a92&0&000800e8#
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV 885 ATSC Tuner/Demod 2]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path pci#ven_14f1&dev_8880&subsys_6a180070&rev_04#6&12867a92&0&000800e8#
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] enum_get_name(): IPropertyBag->Read method failed -2147024894
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] BDA: DeviceGetListEx_impl. Skipped nameless BDA driver
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV-dualHD ATSC Tuner 2]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_2040&pid_846d#6&33c275d6&0&3#
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: Filter [Hauppauge WinTV-dualHD ATSC Tuner]
Sep 14, 2023 13:02:04.473 [4696] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_2040&pid_846d#6&33c275d6&0&3#
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] enum_get_name(): IPropertyBag->Read method failed -2147024894
Sep 14, 2023 13:02:04.473 [4696] DEBUG - [I] BDA: DeviceGetListEx_impl. Skipped nameless BDA driver
Why do these issues only crop up with certain versions of PMS? I have a very good outside roof mounted antenna, all channels always show good signal strength. I’ve never had a problem with tuner or drivers prior to this. I can’t be the only person running this config, but there’s clearly something wrong. As far as what shows, it’s random. When testing, I just pick 30 minute shows from the guide. Some of them record OK, and then another one (sometimes the next show on the same channel) will record 2 minutes and stop.
OK, I changed the library from using the root and updated to the latest version available for download, same problem again. A couple of shows record ok, then the next recording last 2 minutes and quit. If this is a tuner/driver problem why does it work fine with some versions of PMS and not others? That just doesn’t make sense. The only variable that’s changing is the PMS version.
Hi Tiebierius, do you know if there is further diagnosis for the misbehaving driver issue? (please lmk if i should start a new thread or I can piggyback on this one.)
I’m having a similar issue to OP, with the DVR not recording full football games, stopping randomly from only a few minutes in to 90+. I’ve noticed that one channel is more problematic to others. I’ve tried three antennas so far, so I don’t think the weak signal is a cause, but it does seem to be isolated to the one broadcast channel which is in 720p instead of 1080i. Looking at the tuner log, everything was normal for the first 1:38 from 10am to 11:38am, at which point a handle_read: read_error occurred.
Oct 01, 2023 11:38:08.266 [11856] INFO - [I] web_service_t::process_device_status
Oct 01, 2023 11:38:08.266 [1700] INFO - [I] DeviceGetListEx_impl: Filter [AVerMedia USB Polaris Digital Tuner]
Oct 01, 2023 11:38:08.266 [1700] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_07ca&pid_0837&mi_01#6&1de50247&0&0001#
Oct 01, 2023 11:38:08.266 [1700] INFO - [I] DeviceGetListEx_impl: pDL->Count:1
Oct 01, 2023 11:38:08.985 [11856] WARN - [E] http_streamer_t::handle_read: read_error: An existing connection was forcibly closed by the remote host (10054)
Oct 01, 2023 11:38:08.985 [11856] WARN - [E] http_streamer_t: http write error
Oct 01, 2023 11:38:08.985 [5260] INFO - [I] http_streamer_t::send_thread_func: removing http client
Oct 01, 2023 11:38:08.985 [5260] INFO - [I] http_streamer_t::send_thread_func: all clients are deleted. Setting exit flag
Oct 01, 2023 11:38:09.000 [5648] INFO - [I] streamer_container_t::control_thread. Removed disconnected streamer 36470944-7136-41b4-ba59-5445aba6f2df
Oct 01, 2023 11:38:09.000 [5648] INFO - [I] streamer_container_t::delete_streamer. Last streamer was deleted. Stop program streamer
Oct 01, 2023 11:38:09.000 [5648] INFO - [I] dvb_program_streamer_t::stop. Stoping program streamer for channel triplet://0:1325:3
Oct 01, 2023 11:38:09.000 [5648] INFO - [I] transponder_streamer::stop_channel. Stop request for channel triplet://0:1325:3
Oct 01, 2023 11:38:09.016 [7684] INFO - [I] CTVSStreamSource::RemoveAllPids. Removing PIDs for client triplet://0:1325:3
Oct 01, 2023 11:38:09.016 [5648] INFO - [I] transponder_streamer::start_idle_timer
Oct 01, 2023 11:38:11.344 [11844] INFO - [I] web_service_t::process_device_status
Oct 01, 2023 11:38:11.344 [7332] INFO - [I] DeviceGetListEx_impl: Filter [AVerMedia USB Polaris Digital Tuner]
Oct 01, 2023 11:38:11.344 [7332] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_07ca&pid_0837&mi_01#6&1de50247&0&0001#
Oct 01, 2023 11:38:11.344 [7332] INFO - [I] DeviceGetListEx_impl: pDL->Count:1
Oct 01, 2023 11:38:11.406 [11820] INFO - [I] device_manager_t::idle_timer_func. Stream container for channel triplet://0:1325:3 on device dvb#bda#0#@device:pnp:\\?\usb#vid_07ca&pid_0837&mi_01#6&1de50247&0&0001#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{e01e1072-3df3-4c23-b99f-6a27a7cc7cd2} is idle. Deleting it.
Oct 01, 2023 11:38:13.359 [6860] INFO - [I] Stopping graph
Oct 01, 2023 11:38:13.391 [7684] INFO - [I] StopDevice()
Oct 01, 2023 11:38:13.391 [7684] INFO - [I] stop_graph: hr=0, state=0
Oct 01, 2023 11:38:13.620 [11844] INFO - [I] web_service_t::process_device_status
Oct 01, 2023 11:38:13.636 [11440] INFO - [I] DeviceGetListEx_impl: Filter [AVerMedia USB Polaris Digital Tuner]
Oct 01, 2023 11:38:13.636 [11440] INFO - [I] DeviceGetListEx_impl: device instance path usb#vid_07ca&pid_0837&mi_01#6&1de50247&0&0001#
Oct 01, 2023 11:38:13.636 [11440] INFO - [I] DeviceGetListEx_impl: pDL->Count:1
Oct 01, 2023 11:38:14.448 [11820] INFO - [I] device_manager_t::idle_timer_func. Device dvb#bda#0#@device:pnp:\\?\usb#vid_07ca&pid_0837&mi_01#6&1de50247&0&0001#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{e01e1072-3df3-4c23-b99f-6a27a7cc7cd2} is idle. Unloading it.
Oct 01, 2023 11:38:14.448 [11820] INFO - [I] transponder_streamer::stop_idle_timer
Oct 01, 2023 11:38:14.448 [11820] INFO - [I] CTVSStreamSource::Term. Waiting for streaming thread to stop
Oct 01, 2023 11:38:14.510 [11820] INFO - [I] CTVSStreamSource::Term. Streaming is stopped
Oct 01, 2023 11:38:14.510 [11820] INFO - [I] CTVSStreamSource::Term. Stream source is stopped
Oct 01, 2023 11:38:15.417 [6860] INFO - [I] transponder_streamer::idle_thread_func finished
Oct 01, 2023 14:58:42.338 [6216] INFO - [I] Signal Control-Break was received
Would really appreciate some help with this. I tried turning the thread priority for the tuner service to “High” in Task Manager based on this Reddit thread, but it didn’t help:
https://www.reddit.com/r/PleX/comments/fgqiy5/dvr_recordings_always_fail/
I should mention that the prior except was from the turner service log. In the Media Server log, a cascade of other errors. occurs…
Oct 01, 2023 11:38:13.733 [5660] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.733 [5660] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3dc9fcd5f4d19d36035
Oct 01, 2023 11:38:13.733 [5660] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.735 [6716] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.736 [6716] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3db8913539be994ee1e
Oct 01, 2023 11:38:13.736 [6716] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.745 [808] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.745 [808] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3dc9fcd5f4d19d36035
Oct 01, 2023 11:38:13.745 [808] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.748 [7740] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.748 [7740] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3dc9fcd5f4d19d3603c
Oct 01, 2023 11:38:13.748 [7740] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.750 [6716] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.751 [6716] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F64602d4637f621a0284a7c58
Oct 01, 2023 11:38:13.751 [6716] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.825 [808] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.825 [6716] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.825 [6716] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3db8913539be994ee1e
Oct 01, 2023 11:38:13.825 [808] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3dc9fcd5f4d19d36035
Oct 01, 2023 11:38:13.825 [6716] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.825 [808] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.836 [5660] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.836 [5660] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3dc9fcd5f4d19d3603c
Oct 01, 2023 11:38:13.836 [5660] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.839 [7740] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.839 [7740] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F62b8c3dc9fcd5f4d19d36035
Oct 01, 2023 11:38:13.839 [7740] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.842 [808] ERROR - PlexClient::downloadContainer: expected MediaContainer element, found html
Oct 01, 2023 11:38:13.842 [808] WARN - Subscription: No container available for /tv.plex.providers.epg.cloud:4/metadata/plex%3A%2F%2Fepisode%2F64602d4637f621a0284a7c58
Oct 01, 2023 11:38:13.842 [808] ERROR - Subscription: Error refreshing sub: No container found
Oct 01, 2023 11:38:13.946 [6480] WARN - [Grabber/5a00bc73e51422e90526bfc969433e11bb7955e2] MDE: unable to find a working transcode profile for video stream
Oct 01, 2023 11:38:13.946 [6480] WARN - [Grabber/5a00bc73e51422e90526bfc969433e11bb7955e2] MDE: unable to find a working transcode profile for video stream
Oct 01, 2023 11:38:22.339 [4932] ERROR - [Req#26d42/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] expacc 125 is out-of-range
Oct 01, 2023 11:38:22.340 [4932] ERROR - [Req#26d43/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] error decoding the audio block
Oct 01, 2023 11:38:22.351 [4932] ERROR - [Req#26d44/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] exponent 26 is out-of-range
Oct 01, 2023 11:38:22.351 [4932] ERROR - [Req#26d45/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] error decoding the audio block
Oct 01, 2023 11:38:22.352 [4932] ERROR - [Req#26d46/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] Error while decoding stream #0:1: Error number -16976906 occurred
Oct 01, 2023 11:38:22.353 [4932] ERROR - [Req#26d47/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] invalid coupling range (8 >= 5)
Oct 01, 2023 11:38:22.354 [4932] ERROR - [Req#26d48/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] error decoding the audio block
Oct 01, 2023 11:38:22.357 [4932] ERROR - [Req#26d49/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] exponent -1 is out-of-range
Oct 01, 2023 11:38:22.358 [4932] ERROR - [Req#26d4a/Transcode/694b5661-f806-46a4-9149-7b4040381910/29d90121-0e84-4038-b9bd-79e047668ee8] [ac3 @ 02edc4c0] error decoding the audio block
Reverse order:
Subscription error is usually Plex changing the database schema and the server/client aren’t updated yet.
Some possibilities:
Windows Update causing issues with the device driver. Check for an updated device driver or do a fix install if it has one or uninstall/reinstall. http_streamer_t::handle_read: read_error: An existing connection was forcibly closed by the remote host (10054) is troubling but your tuner isn’t officially supported. It might be a case of it works until it doesn’t, but the fact it is with one channel points to the broadcaster.
The broadcaster sometimes sends an inconsistent stream or altering formats between commercials. Usually this is reproducible in that it fails when they switch from say a local nightly news program to the national broadcast. Save any broken recordings, they can be looked at.
Filesystem issues such as RAM drive running out of space or connection problems with your storage. Plex Server must run on local storage and if temp/transcoder is set to a RAM drive Live TV/DVR can run out of space. If you TV recordings drive is a network drive, try setting to local storage for one show.
So I’ve been having this problem since May 26, Today is Oct 6. So that’s 5 months of having a problem with a function that’s only available with a paid Plex pass. The only “support” available for Plex is here in this forum. So far, after 5 months of problems, no Plex employee has even asked me a question, much less assisted me. I’ve spent the last 30 years working in and around technical support organizations. I can’t imagine the thinking at any company that says this is acceptable support.
Thanks Tiebierius! So it sounds like the subscription error isn’t a big deal.
Windows update – doesn’t show any driver issues, will try an uninstall/reinstall.
I’m surprised that you say the tuner isn’t officially supported – I purchased this tuner (AverMedia Volar Hybrid Q) because it was listed as supporting Windows and NVIDIA Shield (my other Plex box) here: https://support.plex.tv/articles/225877427-supported-dvr-tuners-and-antennas/ Is there a specific supported driver that I should be using?
The broadcaster sending an inconsistent stream is interesting. I do have some of the “broken” recordings. Is there a way I could send them to you?
Filesystem – using local storage, SSD. Not using a RAM drive as far as I’m aware.
Thanks again for the help!
Hi Gary, I’m not with Plex but I took a look through your last Tuner Service log (“Plex Tuner Service5.log” in your zip file.) Can you confirm that this is the date & time that one of your failed recordings stopped?
Sep 13, 2023 20:17:54.258 [7764] INFO - [I] device_manager_t::get_device_list. Device Hauppauge WinTV 885 ATSC Tuner/Demod 2, id dvb#bda#0#@device:pnp:\\?\pci#ven_14f1&dev_8880&subsys_6b180070&rev_04#6&2a91e53a&0&001000e8#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9f0e8b98-c677-11db-beb4-00a0c9f21fc7}, tuners 1, state 0, standards 24
Sep 13, 2023 20:17:57.101 [8104] INFO - [I] web_service_t::process_device_status
Sep 13, 2023 20:17:57.101 [8104] INFO - [I] device_reactor_t::get_device_list (dvb#bda#pci#ven_14f1&dev_8880&subsys_6b180070&rev_04#6&2a91e53a&0&001000e8#)
Sep 13, 2023 20:17:57.101 [7764] WARN - [E] http_streamer_t::handle_read: read_error: An existing connection was forcibly closed by the remote host (10054)
Sep 13, 2023 20:17:57.101 [7764] WARN - [E] http_streamer_t: http write error
