Recording Failed - Need help troubleshooting

Live TV & DVR
1

Server Version#: Version 1.41.3.9314
Tuner Make/Model: SiliconDust Home run Prime
Guide/Lineup name: Fios
Using XMLTV?: no
Channel number/Name: 580 MSGSN

My recording is inconsistent at best. Sometimes it’s difficult for Plex to even accept a recording (ie: I go through the scheduling, but after saving, it doesn’t even get scheduled), sometimes it gets scheduled, but doesn’t actually record.

The tuner works when I select a channel to watch in real time.

image
image487×399 33.3 KB

And after a few attempts, I was able to schedule the recording:

image
image1179×306 60.3 KB

But it did not record. I’m not fluent enough in Plex troubleshooting to figure out what the issues are.

I see one oddity in the logs that says a device isn’t compatible, but everything has worked for years.

Jan 19, 2025 10:30:18.796 [131215839472440] DEBUG - SSDP departed after not being seen for 23.321527 seconds: 192.168.0.202 (DP-UB420)
Jan 19, 2025 10:30:18.796 [131215839472440] DEBUG - SSDP departed after not being seen for 24.604952 seconds: 192.168.0.227 (Home (Home Assistant))
Jan 19, 2025 10:30:18.796 [131215831083832] DEBUG - DVR:Device: Discovering and refreshing devices with identifier tv.plex.grabbers.hdhomerun
Jan 19, 2025 10:30:18.797 [131215831083832] DEBUG - Grabber: HDHomerun discovered 0 compatible devices.
Jan 19, 2025 10:30:18.797 [131215831083832] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 19, 2025 10:30:18.797 [131215831083832] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/1311D52B was already known, refreshing database info
Jan 19, 2025 10:30:18.798 [131215831083832] DEBUG - [HCl#cb417] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 19, 2025 10:30:18.798 [131218519632696] DEBUG - [NSB/SSDP] Parsing SSDP schema for http://192.168.0.142:50201/dial.xml
Jan 19, 2025 10:30:18.809 [131218628684600] DEBUG - [HttpClient/HCl#cb417] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 19, 2025 10:30:18.809 [131215831083832] DEBUG - [HCl#cb419] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 19, 2025 10:30:18.810 [131218628684600] DEBUG - [HttpClient/HCl#cb418] HTTP/1.1 (0.0s) 200 response from GET 

Jan 19, 2025 10:30:18.858 [131215831083832] DEBUG - DVR:Device: Device was alive, refreshed device://tv.plex.grabbers.hdhomerun/1311D52B.
Jan 19, 2025 10:30:18.872 [131218628684600] DEBUG - [HttpClient/HCl#cb41c] HTTP/1.1 (0.0s) 200 response from GET 

Jan 19, 2025 10:30:18.951 [131218519632696] DEBUG - [NSB/SSDP] Parsing SSDP schema for http://192.168.0.44:80/dri/device.xml
Jan 19, 2025 10:30:18.994 [131218628684600] DEBUG - [HttpClient/HCl#cb421] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/dri/device.xml
Jan 19, 2025 10:30:18.999 [131218519632696] DEBUG - [NSB/SSDP] found 4 SSDP devices via http://192.168.0.44:80/dri/device.xml
Jan 19, 2025 10:30:18.999 [131218519632696] DEBUG - [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun DRI Tuner 1311D52B)
Jan 19, 2025 10:30:18.999 [131218519632696] DEBUG - [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-0)
Jan 19, 2025 10:30:18.999 [131218519632696] DEBUG - [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-1)
Jan 19, 2025 10:30:18.999 [131218519632696] DEBUG - [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-2)
Jan 19, 2025 10:30:18.999 [131218519632696] DEBUG - [NSB/SSDP] Parsing SSDP schema for http://192.168.0.170:8091/XD/25a6a590-1dd2-11b2-9c91-60d2486e8992

Jan 19, 2025 10:31:03.816 [131215797529400] DEBUG - SSDP departed after not being seen for 22.433312 seconds: 192.168.0.44 (HDHomeRun DRI Tuner 1311D52B)
Jan 19, 2025 10:31:03.816 [131215797529400] DEBUG - SSDP departed after not being seen for 22.433324 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-0)
Jan 19, 2025 10:31:03.816 [131215797529400] DEBUG - SSDP departed after not being seen for 22.433334 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-1)
Jan 19, 2025 10:31:03.816 [131215797529400] DEBUG - SSDP departed after not being seen for 22.433346 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-2)

plexDVRfail.log (6.9 MB)

But I’m not sure what to do with any of this info. Any advice would be greatly appreciated. Recording was scheduled for 10:30a. Log attached.

2

What is your network topology, and has anything changed (if it was working, but is now a problem?) Without a full set of logs I’m not seeing what the IP address is of your Plex server, and so can’t tell if your HDHR tuner is on the same subnet as your Plex server. Every ~20-25 seconds Plex is removing your tuner because it isn’t seen on the network:

Jan 19, 2025 07:09:13.651 [131215824792376] DEBUG - SSDP departed after not being seen for 22.410932 seconds: 192.168.0.44 (HDHomeRun DRI Tuner 1311D52B)
Jan 19, 2025 07:09:13.651 [131215824792376] DEBUG - SSDP departed after not being seen for 22.410970 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-0)
Jan 19, 2025 07:09:13.651 [131215824792376] DEBUG - SSDP departed after not being seen for 22.410988 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-1)
Jan 19, 2025 07:09:13.651 [131215824792376] DEBUG - SSDP departed after not being seen for 22.411003 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-2)

but then it quickly retests for your HDHR tuner and makes it available again.

Jan 19, 2025 07:09:13.661 [131215789140792] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 19, 2025 07:09:13.661 [131215789140792] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/1311D52B was already known, refreshing database info

And it does this several hundred times in the ~4 hours that your log snippet covers. This looks like a network problem, not specifically a Plex problem.

3

Nothing changed that I intentionally changed, but I could imagine I did something while messing with the homelab that I forgot I did or how it would impact my server. Maybe a firmware update to the turner that I never tested afterwards?

Fios ONT > FIrewalla Gold SE > a couple of TP-Link managed switches. Everything on the same subnet (192.168.0.xx). Plex Server is 192.168.0.134.

As a ‘network’ problem, any suggestions on what to look at? Other than the Plex DVR not recording, I’m not having any issues that are exposing themselves in real-world performance. No complaints from the kids on tablets, phones, Roku, or Plex.

Googling for similar errors, I found reference to a script that might have been helpful, but I don’t know what to make of the output other than seeing it says “error” at the end.


[+] Loading http://192.168.0.44:80/dms/device.xml...
        -> Server String: HDHomeRun/1.0
        ==== XML Attributes ===
        -> Device Type: urn:schemas-upnp-org:device:MediaServer:1
        -> Friendly Name: HDHomeRun DMS 1311D52B
        -> Manufacturer: Silicondust
        -> Manufacturer URL: http://www.silicondust.com/
        -> Model Description: HDHR3-CC HDHomeRun PRIME
        -> Model Name: HDHomeRun PRIME
        -> Model Number: HDHR3-CC
        -> Services:
                => Service Type: urn:schemas-upnp-org:service:ConnectionManager:1
                => Control: http://192.168.0.44:80/dms/ConnectionManager
                => Events: http://192.168.0.44:80/dms/ConnectionManager
                => API: http://192.168.0.44:80/dms/ConnectionManager.xml
                        - GetProtocolInfo
                        - GetCurrentConnectionIDs
                        - GetCurrentConnectionInfo
                => Service Type: urn:schemas-upnp-org:service:ContentDirectory:1
                => Control: http://192.168.0.44:80/dms/ContentDirectory
                => Events: http://192.168.0.44:80/dms/ContentDirectory
                => API: http://192.168.0.44:80/dms/ContentDirectory.xml
                        - Browse
                        - GetSearchCapabilities
                        - GetSortCapabilities
                        - GetSystemUpdateID
        [+] Content browsing available. Looking up base directories...
Traceback (most recent call last):
  File "/usr/lib/python3/dist-packages/urllib3/util/url.py", line 425, in parse_url
    host, port = _HOST_PORT_RE.match(host_port).groups()  # type: ignore[union-attr]
                 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
AttributeError: 'NoneType' object has no attribute 'groups'

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/lib/python3/dist-packages/requests/models.py", line 434, in prepare_url
    scheme, auth, host, port, path, query, fragment = parse_url(url)
                                                      ^^^^^^^^^^^^^^
  File "/usr/lib/python3/dist-packages/urllib3/util/url.py", line 451, in parse_url
    raise LocationParseError(source_url) from e
urllib3.exceptions.LocationParseError: Failed to parse: http://192.168.0.44:80http://192.168.0.44:80/dms/ContentDirectory

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/mnt/dockssd/upnp_info.py", line 320, in <module>
    main(sys.argv)
  File "/mnt/dockssd/upnp_info.py", line 315, in main
    parse_locations(locations)
  File "/mnt/dockssd/upnp_info.py", line 144, in parse_locations
    find_directories(cd_ctr, cd_service)
    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/mnt/dockssd/upnp_info.py", line 226, in find_directories
    resp = requests.post(p_url, data=payload, headers=soapActionHeader)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3/dist-packages/requests/api.py", line 115, in post
    return request("post", url, data=data, json=json, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3/dist-packages/requests/api.py", line 59, in request
    return session.request(method=method, url=url, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3/dist-packages/requests/sessions.py", line 575, in request
    prep = self.prepare_request(req)
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3/dist-packages/requests/sessions.py", line 486, in prepare_request
    p.prepare(
  File "/usr/lib/python3/dist-packages/requests/models.py", line 368, in prepare
    self.prepare_url(url, params)
  File "/usr/lib/python3/dist-packages/requests/models.py", line 436, in prepare_url
    raise InvalidURL(*e.args)
requests.exceptions.InvalidURL: Failed to parse: http://192.168.0.44:80http://192.168.0.44:80/dms/ContentDirectory

Nothing exciting in the log of the tuner itself:

20250118-23:38:12 Tuner: tuner0 tuning 507 WABC-DT (auto:519MHz-1029)
20250118-23:38:12 Tuner: tuner0 streaming http to 192.168.0.134:52306
20250118-23:38:12 CableCARD: tuner0 507 WABC-DT (auto:519MHz-1029) access = subscribed
20250119-00:48:27 Tuner: tuner0 http stream ended (remote closed)
20250119-01:07:39 Tuner: tuner0 tuning 505 WNYW-DT (auto:519MHz-1028)
20250119-01:07:39 Tuner: tuner0 streaming http to 192.168.0.134:39214
20250119-01:07:39 CableCARD: tuner0 505 WNYW-DT (auto:519MHz-1028) access = subscribed
20250119-02:50:49 Tuner: tuner0 http stream ended (remote closed)
20250119-03:07:27 Tuner: tuner0 tuning 505 WNYW-DT (auto:519MHz-1028)
20250119-03:07:27 Tuner: tuner0 streaming http to 192.168.0.134:42932
20250119-03:07:27 CableCARD: tuner0 505 WNYW-DT (auto:519MHz-1028) access = subscribed
20250119-03:59:59 Tuner: tuner1 tuning 580 MSG Sportsnet H (auto:339MHz-759)
20250119-03:59:59 Tuner: tuner1 streaming http to 192.168.0.134:50502
20250119-03:59:59 CableCARD: tuner1 580 MSG Sportsnet H (auto:339MHz-759) access = subscribed
20250119-05:06:52 Tuner: tuner0 http stream ended (remote closed)
20250119-06:00:00 Tuner: tuner1 http stream ended (remote closed)
20250119-19:53:20 Tuner: tuner0 tuning 504 WNBC-DT (auto:519MHz-1027)
20250119-19:53:20 Tuner: tuner0 streaming http to 192.168.0.134:49672
20250119-19:53:20 CableCARD: tuner0 504 WNBC-DT (auto:519MHz-1027) access = subscribed
20250119-23:32:59 Tuner: tuner0 http stream ended (remote closed)
20250119-23:33:00 Tuner: tuner0 tuning 502 WCBS-DT (auto:549MHz-1025)
20250119-23:33:00 Tuner: tuner0 streaming http to 192.168.0.134:60978
20250119-23:33:00 CableCARD: tuner0 502 WCBS-DT (auto:549MHz-1025) access = subscribed
20250120-01:05:11 Tuner: tuner0 http stream ended (remote closed)
20250120-01:15:31 Tuner: tuner0 tuning 502 WCBS-DT (auto:549MHz-1025)
20250120-01:15:32 Tuner: tuner0 streaming http to 192.168.0.134:50014
20250120-01:15:32 CableCARD: tuner0 502 WCBS-DT (auto:549MHz-1025) access = subscribed
4

I was going to ask about the HDHR logs, thanks for those. As you say, looks normal. I was hoping that you had moved your HDHR tuner to a different subnet recently. Of course until the problem is fixed we can’t be sure what it is, but an intermittent connection and/or Plex reconnecting to your tuner could explain the problems you are having.

It is unusual that Plex is testing for your HDHR nearly 100 times an hour (This is the “Testing grabber HDHomerun device” message.) I’m also on Linux and have a HDHR Extend, and I only get this tuner testing/verifying message once each time the Plex Server process starts.

Some ideas to try:

  1. Turn off DLNA in Plex (In Plex Web go to Settings by clicking the wrench at the upper right, then under your server find Settings → DLNA. Uncheck “Enable the DLNA server”. Save, and then restart your Plex server service.) Do you intentionally use DLNA? It is supposed to be turned off by default if you have created a Plex Home because it is seen as a security risk.
  2. Is your Plex server’s network connection wifi or wired? If wifi, try wired.
  3. Do you run a firewall on your Plex server (e.g. UFW?) If so, any changes recently?

It looks like that script tests your HDHR tuner, but I can’t take anything from that output other than it does connect. Is the script old?

5

I think the script was from 2019, if I recall, but didn’t look too closely. Was just looking for anything to see if I could find any hint of whats up.

DLNA server is not enabled. Hmmmm…

Server is wired. Bare metal. Only thing running on the box. I dont think I’ve got UFW running, but I’ll dive in and see.

6

Hmm, do you have SSDP/UPnP enabled in your router? If so, try disabling those. I’m not familiar with the Firewalla devices to know where this is set. I can’t say for sure that eliminating all of those SSDP checks will help, but the HDHR tuner re-discovery in your logs happens every time immediately after SSDP notes that your HDHR tuner (and a bunch of other devices) have departed.

7

Agreed, it’s really strange to see the HDHR tuner “depart” so frequently. Also agreed that this likely is a symptom of some underlying network issue, though I’m not sure what it would be at this point.

If you’re comfortable on the command-line, I’d recommend running tcpdump to capture some the “heartbeat” traffic between the server and the tuner and see if/when it falls down. Something like the following:

sudo tcpdump -i any -n -A -vv 'udp port 1900 and (host 10.10.0.243 or host 239.255.255.250)' # Replace 10.10.0.243 with the IP of your tuner. You can add the ‘-w’ command-line argument if you want to write the capture to a file.

This will show all SSDP traffic between your host and the tuner (the discovery and any replies). These should occur regularly, maybe every 10 seconds or so.

The multicast messages to 239.255.255.250 are the SSDP discovery requests. There should be a more-or-less immediate reply from the tuner.

It would be interesting to leave this running and see if you could correlate the tuner departure messages with a lack of replies from the tuner in the tcpdump. This might provide some clues if it shows that the server is indeed sending the SSDP discovery requests and the replies are missing, implying that the tuner either isn’t replying or that the network is dropping the replies.

8

I’m not getting any output int the terminal when running that. Even when playing content from the tuner on Plex.

That said, the console output looks normal.

Jan 21, 2025 13:10:42.095 [131215396973368] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb8fe] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:42.102 [131218628684600] Debug — [HttpClient/HCl#eb8fe] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:42.103 [131215396973368] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb8ff] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:42.117 [131218628684600] Debug — [HttpClient/HCl#eb8ff] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.041 [131215409556280] Debug — SSDP departed after not being seen for 22.421867 seconds: 192.168.0.44 (HDHomeRun DRI Tuner 1311D52B)
Jan 21, 2025 13:10:45.041 [131215409556280] Debug — SSDP departed after not being seen for 22.421883 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-0)
Jan 21, 2025 13:10:45.041 [131215409556280] Debug — SSDP departed after not being seen for 22.421945 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-1)
Jan 21, 2025 13:10:45.042 [131215409556280] Debug — SSDP departed after not being seen for 22.421966 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-2)
Jan 21, 2025 13:10:45.055 [131215413750584] Debug — DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 21, 2025 13:10:45.055 [131215413750584] Debug — [HCl#eb901] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.062 [131218628684600] Debug — [HttpClient/HCl#eb901] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.062 [131215413750584] Debug — [HCl#eb903] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.071 [131218628684600] Debug — [HttpClient/HCl#eb903] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.148 [131215357127480] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb908] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.156 [131218628684600] Debug — [HttpClient/HCl#eb908] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.156 [131215357127480] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb909] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.166 [131218628684600] Debug — [HttpClient/HCl#eb909] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.174 [131218519632696] Debug — [NSB/SSDP] Parsing SSDP schema for http://192.168.0.44:80/dri/device.xml
Jan 21, 2025 13:10:45.194 [131218628684600] Debug — [HttpClient/HCl#eb90b] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/dri/device.xml
Jan 21, 2025 13:10:45.199 [131218519632696] Debug — [NSB/SSDP] found 4 SSDP devices via http://192.168.0.44:80/dri/device.xml
Jan 21, 2025 13:10:45.199 [131218519632696] Debug — [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun DRI Tuner 1311D52B)
Jan 21, 2025 13:10:45.199 [131218519632696] Debug — [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-0)
Jan 21, 2025 13:10:45.199 [131218519632696] Debug — [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-1)
Jan 21, 2025 13:10:45.199 [131218519632696] Debug — [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-2)
Jan 21, 2025 13:10:45.209 [131215373904696] Debug — [NSB/SSDP/HCl#eb90d] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.218 [131218628684600] Debug — [HttpClient/HCl#eb90d] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.218 [131215373904696] Debug — [NSB/SSDP/HCl#eb90f] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.228 [131218628684600] Debug — [HttpClient/HCl#eb90f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.260 [131215373904696] Debug — [NSB/SSDP] DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 21, 2025 13:10:45.260 [131215373904696] Debug — [NSB/SSDP/HCl#eb911] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.267 [131218628684600] Debug — [HttpClient/HCl#eb911] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:45.268 [131215373904696] Debug — [NSB/SSDP/HCl#eb912] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:45.279 [131218628684600] Debug — [HttpClient/HCl#eb912] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:48.198 [131215426333496] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb91d] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:48.205 [131218628684600] Debug — [HttpClient/HCl#eb91d] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:48.205 [131215426333496] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb91e] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:48.218 [131218628684600] Debug — [HttpClient/HCl#eb91e] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:51.249 [131215382293304] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb91f] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:51.259 [131218628684600] Debug — [HttpClient/HCl#eb91f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:51.259 [131215382293304] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb920] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:51.268 [131218628684600] Debug — [HttpClient/HCl#eb920] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:54.300 [131215476665144] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb921] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:54.308 [131218628684600] Debug — [HttpClient/HCl#eb921] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:54.308 [131215476665144] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb922] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:54.318 [131218628684600] Debug — [HttpClient/HCl#eb922] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:57.350 [131215373904696] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb92f] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:57.357 [131218628684600] Debug — [HttpClient/HCl#eb92f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:10:57.358 [131215373904696] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb930] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:10:57.369 [131218628684600] Debug — [HttpClient/HCl#eb930] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:00.403 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb93d] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:00.411 [131218628684600] Debug — [HttpClient/HCl#eb93d] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:00.411 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb93e] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:00.423 [131218628684600] Debug — [HttpClient/HCl#eb93e] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:03.457 [131215365516088] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb93f] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:03.463 [131218628684600] Debug — [HttpClient/HCl#eb93f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:03.464 [131215365516088] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb940] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:03.473 [131218628684600] Debug — [HttpClient/HCl#eb940] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:06.506 [131215361321784] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb945] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:06.512 [131218628684600] Debug — [HttpClient/HCl#eb945] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:06.513 [131215361321784] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb946] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:06.523 [131218628684600] Debug — [HttpClient/HCl#eb946] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:09.554 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb959] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:09.562 [131218628684600] Debug — [HttpClient/HCl#eb959] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:09.562 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb95a] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:09.571 [131218628684600] Debug — [HttpClient/HCl#eb95a] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:12.604 [131215365516088] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb95b] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:12.612 [131218628684600] Debug — [HttpClient/HCl#eb95b] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:12.613 [131215365516088] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb95c] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:12.621 [131218628684600] Debug — [HttpClient/HCl#eb95c] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:15.655 [131215373904696] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb95e] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:15.663 [131218628684600] Debug — [HttpClient/HCl#eb95e] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:15.663 [131215373904696] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb95f] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:15.671 [131218628684600] Debug — [HttpClient/HCl#eb95f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:18.705 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb960] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:18.716 [131218628684600] Debug — [HttpClient/HCl#eb960] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:18.716 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb961] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:18.730 [131218628684600] Debug — [HttpClient/HCl#eb961] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:21.763 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb962] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:21.772 [131218628684600] Debug — [HttpClient/HCl#eb962] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:21.772 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb963] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:21.783 [131218628684600] Debug — [HttpClient/HCl#eb963] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:24.817 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb964] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:24.824 [131218628684600] Debug — [HttpClient/HCl#eb964] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:24.824 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb965] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:24.836 [131218628684600] Debug — [HttpClient/HCl#eb965] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:27.867 [131215413750584] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb966] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:27.875 [131218628684600] Debug — [HttpClient/HCl#eb966] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:27.875 [131215413750584] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb967] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:27.886 [131218628684600] Debug — [HttpClient/HCl#eb967] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.048 [131215361321784] Debug — SSDP departed after not being seen for 22.342160 seconds: 192.168.0.44 (HDHomeRun DRI Tuner 1311D52B)
Jan 21, 2025 13:11:30.048 [131215361321784] Debug — SSDP departed after not being seen for 22.342175 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-0)
Jan 21, 2025 13:11:30.048 [131215361321784] Debug — SSDP departed after not being seen for 22.342198 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-1)
Jan 21, 2025 13:11:30.048 [131215361321784] Debug — SSDP departed after not being seen for 22.342210 seconds: 192.168.0.44 (HDHomeRun Prime Tuner 1311D52B-2)
Jan 21, 2025 13:11:30.050 [131215413750584] Debug — DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 21, 2025 13:11:30.050 [131215413750584] Debug — [HCl#eb968] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.060 [131218628684600] Debug — [HttpClient/HCl#eb968] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.060 [131215413750584] Debug — [HCl#eb96a] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.071 [131218628684600] Debug — [HttpClient/HCl#eb96a] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.099 [131218519632696] Debug — [NSB/SSDP] Parsing SSDP schema for http://192.168.0.44:80/dms/device.xml
Jan 21, 2025 13:11:30.113 [131218628684600] Debug — [HttpClient/HCl#eb96e] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/dms/device.xml
Jan 21, 2025 13:11:30.113 [131218519632696] Debug — [NSB/SSDP] found 1 SSDP devices via http://192.168.0.44:80/dms/device.xml
Jan 21, 2025 13:11:30.113 [131218519632696] Debug — [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun DMS 1311D52B)
Jan 21, 2025 13:11:30.130 [131215434722104] Debug — [NSB/SSDP/HCl#eb970] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.139 [131218628684600] Debug — [HttpClient/HCl#eb970] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.139 [131215434722104] Debug — [NSB/SSDP/HCl#eb972] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.150 [131218628684600] Debug — [HttpClient/HCl#eb972] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.186 [131215434722104] Debug — [NSB/SSDP] DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 21, 2025 13:11:30.186 [131215434722104] Debug — [NSB/SSDP/HCl#eb974] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.197 [131218628684600] Debug — [HttpClient/HCl#eb974] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.198 [131215434722104] Debug — [NSB/SSDP/HCl#eb975] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.214 [131218628684600] Debug — [HttpClient/HCl#eb975] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.921 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb981] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.928 [131218628684600] Debug — [HttpClient/HCl#eb981] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:30.929 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb982] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:30.941 [131218628684600] Debug — [HttpClient/HCl#eb982] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:33.974 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb983] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:33.981 [131218628684600] Debug — [HttpClient/HCl#eb983] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:33.981 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb984] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:33.989 [131218628684600] Debug — [HttpClient/HCl#eb984] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:37.020 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb985] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:37.027 [131218628684600] Debug — [HttpClient/HCl#eb985] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:37.027 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb986] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:37.039 [131218628684600] Debug — [HttpClient/HCl#eb986] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:40.072 [131215476665144] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb987] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:40.079 [131218628684600] Debug — [HttpClient/HCl#eb987] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:40.079 [131215476665144] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb988] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:40.093 [131218628684600] Debug — [HttpClient/HCl#eb988] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:43.126 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb98a] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:43.133 [131218628684600] Debug — [HttpClient/HCl#eb98a] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:43.133 [131215472470840] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb98b] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:43.144 [131218628684600] Debug — [HttpClient/HCl#eb98b] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:46.175 [131215365516088] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb98c] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:46.183 [131218628684600] Debug — [HttpClient/HCl#eb98c] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:46.183 [131215365516088] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb98d] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:46.191 [131218628684600] Debug — [HttpClient/HCl#eb98d] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:49.223 [131215409556280] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb98e] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:49.230 [131218628684600] Debug — [HttpClient/HCl#eb98e] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:49.230 [131215409556280] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb98f] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:49.241 [131218628684600] Debug — [HttpClient/HCl#eb98f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:52.274 [131215373904696] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb990] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:52.282 [131218628684600] Debug — [HttpClient/HCl#eb990] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:52.282 [131215373904696] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb991] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:52.292 [131218628684600] Debug — [HttpClient/HCl#eb991] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:55.325 [131215413750584] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb992] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:55.336 [131218628684600] Debug — [HttpClient/HCl#eb992] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:55.337 [131215413750584] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb993] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:55.353 [131218628684600] Debug — [HttpClient/HCl#eb993] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:58.383 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb994] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:58.391 [131218628684600] Debug — [HttpClient/HCl#eb994] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:11:58.392 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb995] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:11:58.405 [131218628684600] Debug — [HttpClient/HCl#eb995] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:01.438 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb997] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:01.447 [131218628684600] Debug — [HttpClient/HCl#eb997] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:01.447 [131215369710392] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb998] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:01.457 [131218628684600] Debug — [HttpClient/HCl#eb998] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:04.493 [131215382293304] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb999] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:04.501 [131218628684600] Debug — [HttpClient/HCl#eb999] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:04.502 [131215382293304] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb99a] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:04.517 [131218628684600] Debug — [HttpClient/HCl#eb99a] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:07.552 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb99b] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:07.559 [131218628684600] Debug — [HttpClient/HCl#eb99b] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:07.560 [131215378099000] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb99c] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:07.572 [131218628684600] Debug — [HttpClient/HCl#eb99c] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:10.605 [131215455693624] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb99d] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:10.613 [131218628684600] Debug — [HttpClient/HCl#eb99d] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:10.613 [131215455693624] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb99e] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:10.625 [131218628684600] Debug — [HttpClient/HCl#eb99e] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:13.659 [131215434722104] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb99f] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:13.670 [131218628684600] Debug — [HttpClient/HCl#eb99f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:13.670 [131215434722104] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb9a0] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:13.678 [131218628684600] Debug — [HttpClient/HCl#eb9a0] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:15.053 [131215413750584] Debug — SSDP departed after not being seen for 22.338157 seconds: 192.168.0.44 (HDHomeRun DMS 1311D52B)
Jan 21, 2025 13:12:15.055 [131215348738872] Debug — DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 21, 2025 13:12:15.055 [131215348738872] Debug — [HCl#eb9a1] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:15.072 [131218628684600] Debug — [HttpClient/HCl#eb9a1] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:15.072 [131215348738872] Debug — [HCl#eb9a4] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:15.087 [131218628684600] Debug — [HttpClient/HCl#eb9a4] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:15.152 [131218519632696] Debug — [NSB/SSDP] Parsing SSDP schema for http://192.168.0.44:80/dms/device.xml
Jan 21, 2025 13:12:15.167 [131218628684600] Debug — [HttpClient/HCl#eb9a8] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/dms/device.xml
Jan 21, 2025 13:12:15.168 [131218519632696] Debug — [NSB/SSDP] found 1 SSDP devices via http://192.168.0.44:80/dms/device.xml
Jan 21, 2025 13:12:15.168 [131218519632696] Debug — [NSB/SSDP] SSDP arrived: 192.168.0.44 (HDHomeRun DMS 1311D52B)
Jan 21, 2025 13:12:15.195 [131215361321784] Debug — [NSB/SSDP/HCl#eb9aa] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:15.203 [131218628684600] Debug — [HttpClient/HCl#eb9aa] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:15.204 [131215361321784] Debug — [NSB/SSDP/HCl#eb9ad] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:15.217 [131218628684600] Debug — [HttpClient/HCl#eb9ad] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:15.248 [131215361321784] Debug — [NSB/SSDP] DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/1311D52B at http://192.168.0.44:80
Jan 21, 2025 13:12:15.248 [131215361321784] Debug — [NSB/SSDP/HCl#eb9af] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:15.255 [131218628684600] Debug — [HttpClient/HCl#eb9af] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:15.255 [131215361321784] Debug — [NSB/SSDP/HCl#eb9b0] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:15.265 [131218628684600] Debug — [HttpClient/HCl#eb9b0] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:16.708 [131215401167672] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb9ba] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:16.718 [131218628684600] Debug — [HttpClient/HCl#eb9ba] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:16.719 [131215401167672] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb9bb] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:16.728 [131218628684600] Debug — [HttpClient/HCl#eb9bb] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:19.761 [131215476665144] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb9bc] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:19.768 [131218628684600] Debug — [HttpClient/HCl#eb9bc] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:19.768 [131215476665144] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb9bd] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:19.777 [131218628684600] Debug — [HttpClient/HCl#eb9bd] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:12:22.808 [131215401167672] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb9be] HTTP requesting GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:12:22.816 [131218628684600] Debug — [HttpClient/HCl#eb9be] HTTP/1.1 http://192.168.0.44:80/discover.json
Jan 21, 2025 13:13:08.623 [131218628684600] Debug — [HttpClient/HCl#eb9f6] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/discover.json
Jan 21, 2025 13:13:08.623 [131215382293304] Debug — [Req#a4f747/Grabber/5fc76e5a992cb9002eaf8256-5fc705ff2f220e002d5e6bf5-ghhw0lshmi6qrs9c1rhua3h0/HCl#eb9f7] HTTP requesting GET http://192.168.0.44:80/lineup_status.json
Jan 21, 2025 13:13:08.632 [131218628684600] Debug — [HttpClient/HCl#eb9f7] HTTP/1.1 (0.0s) 200 response from GET http://192.168.0.44:80/lineup_status.json
9

You’re running it on your server, correct? And you changed ‘10.10.0.243’ to be the IP address of your tuner?

10

Yup. SSH into my Plex server.

11

Huh. That’s weird in-and-of itself. Maybe simplify it to just:
sudo tcpdump -i any -n -A -vv port 1900

The other filters are largely unnecessary, I just didn’t want the output to be flooded with unrelated messages from other hosts.

‘-i any’ = Capture all interfaces
‘-n’ = Don’t try to substitute hostnames for IP addresses
‘-A’ = Print each packet in ASCII
‘-vv’ = Be verbose

12

That’s working. Although as you feared, flooded with other stuff.
Output attached.

plextuner.txt (1.1 MB)

13

Oh yeah, that’s busy. We’re going to need to quiet that down quite a bit if it’s going to be useful. Let’s try this next:

sudo tcpdump -i any -n -A -vv "port 1900 and (host 192.168.0.44 or host 192.168.0.134)"

Maybe it had a problem with the multi-cast address for some reason before. Make sure to include the quotes when you run the command, please.

If this works we can tighten it up a little more to really get just the traffic we want.

In the end you should be able to leave it running until the problem occurs (monitor the logs for the SSDP depart messages) and then compare the time leading leading up to those in the capture.

Edit:
This is what we ultimately want to get to. This or something a lot like it:

sudo tcpdump -i any -n -A -vv "port 1900 and ((src host 192.168.0.44 and dst host 192.168.0.134) or (src host 192.168.0.134 and dst host 239.255.255.250))"

This would just capture the discovery requests sent by your server and the responses from your tuner. Or should, at least.

14

If I may? @pshanew is providing great analysis, and I don’t want to derail the effort. However, I do still think it would be valuable from a troubleshooting perspective to turn off UPnP/SSDP on your router as a short term test to see if it resolves the issue. I’d recommend rebooting your router and restarting your Plex Server service after turning of UPnP. In less than an hour you could verify if you can schedule a 30 minute recording without multiple tries, see if the recording completes, and gather Plex logs to see if the repeat tuner discovery goes away. If it does we will have narrowed down the needed scope of analysis significantly.

Plex Server is CAN BE configured with the IP address of your tuner - depending on how you initially set it up (I have my tuner IP static) and then doesn’t need UPnP or SSDP to work, and you have also confirmed that you have DLNA turned off on your Plex Server instance. I personally don’t enable UPnP/SSDP on my network, and Plex runs like a champ with my HDHR tuner - but that is purely personal preference if you have other capabilities on your network that need it.

Edit: Based on this article Plex Server captures the IP address of your tuner even if it was auto-detected, which is why they are recommending static IPs for network tuners.

1 Like
15

I’m digging into the router settings on my Firewalla, and UPnP was buried deep. However, it has been off.

There is a SSDP toggle that is on/off that is on. There’s some support articles that suggest only using it if you’ve got devices that aren’t being discovered with it off, so I guess that’s my next thing to test on this “fork” of support.

BTW, I appreciate both you and @pshanew weighing in with different things to look at. I’m trying to take it one step at a time so I don’t confuse the issue by trying to many things at once.

16

Here’s the output of the first command you suggested…
secondtunertest.txt (158.9 KB)

17

Were you able to test this one to see if it works for you?
sudo tcpdump -i any -n -A -vv "port 1900 and ((src host 192.168.0.44 and dst host 192.168.0.134) or (src host 192.168.0.134 and dst host 239.255.255.250))"

If so, please let it run until you see the “SSDP Departed” message for 192.168.0.44 in your Plex Media Server.log file. Then please provide that log file and the tcpdump output. I’ll have a look to see if I can correlate the events.

Thanks!

18

This output is stopping short (I think), but here’s what I just ran…

cktunertest.txt (8.7 KB)

19

Cool, that looks much better. I’d just let that run until the problem occurs in the logs. Then provide both the server logs and the output. It stopping short may be indicative of the underlying problem. We’ll just have to see if they correlate.

20

Left it running and a set a recording for 2:30p, which predictably failed.
newtunertest.txt (243.6 KB)
PlexLogTunerTest.log (7.5 MB)