(Shield) DVR didn't record a full day, where to begin?

[dzeff]

Server Version#: 1.23.3.4707
Player Version#:
Tuner Make/Model: WinTV-dualHD
Guide/Lineup name:
Using XMLTV?: Not sure
Channel number/Name: all

Where in the server logs do I begin to look for why I didn’t record ANYTHING for an entire day? Looking at the attached screen capture you will see the yellow arrow pointing to yesterday:

• I had internet
• the shield was connected to the tuner
  ** it recorded the previous day and has started recording today.

In a tuner service log that includes yesterday I did see that “DTV_STAT_CNR has failed for tuner 0”

un 21, 2021 17:00:54.405 [8502] INFO - [I] web_service_t::process_device_status
Jun 21, 2021 17:00:54.405 [8502] INFO - [I] device_reactor_t::get_device_list (dvb#v4l#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0013984767)
Jun 21, 2021 17:00:54.406 [8502] INFO - [I] v4l::DeviceGetList_impl. getting v4l device list
Jun 21, 2021 17:00:54.406 [8502] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb0.frontend0
Jun 21, 2021 17:00:54.407 [8502] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb1.frontend0
Jun 21, 2021 17:00:54.415 [8502] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, Name 955D, Standards 24
Jun 21, 2021 17:00:54.415 [8502] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, Name 955D, Standards 24
Jun 21, 2021 17:00:54.419 [8502] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, tuners 1, state 2, standards 24
Jun 21, 2021 17:00:54.419 [8502] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, tuners 1, state 0, standards 24
Jun 21, 2021 17:00:54.567 [8953] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -59000, 18446744073709492616, 1
Jun 21, 2021 17:00:54.567 [8953] DEBUG - [I] DTV_STAT_CNR has failed for tuner 0, frontend 0
Jun 21, 2021 17:00:54.569 [8953] DEBUG - [I] quality: 251
Jun 21, 2021 17:00:57.573 [8507] INFO - [I] web_service_t::process_device_s

[dzeff]

I’ve started getting this after a recent update, and it has happened twice now. The screenshot shows you that there were 3 days July 3-5 that didn’t record, and it turns out that the Shield was unable to tune to the channel. Because it was the holiday weekend and most of the shows are in rerun that I only record new for, I didn’t realize it sooner.

The solution - I unplug the shield and unplug the cable with the WinTV-dualHD.

From Plex Tuner Service.log:
Jul 06, 2021 22:31:24.526 [8272] INFO - Plex Tuner Service v1.0.0.0 - NVIDIA SHIELD Android TV foster - build:
Jul 06, 2021 22:31:24.527 [8272] INFO - Android version: 9, language: en-US
Jul 06, 2021 22:31:24.527 [8272] INFO - Processor Unknown architecture
Jul 06, 2021 22:31:24.527 [8272] INFO - /data/user/0/com.plexapp.mediaserver.smb/Resources/Plex Tuner Service /data/user/0/com.plexapp.mediaserver.smb/Resources/Tuner/Private /data/user/0/com.plexapp.mediaserver.smb/Resources/Tuner/Shared 1.23.3.4707-ebb5fe9f3 32600
Jul 06, 2021 22:31:24.501 [8282] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb0.frontend0
Jul 06, 2021 22:31:24.503 [8282] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb1.frontend0
Jul 06, 2021 22:31:24.510 [8282] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, Name 955D, Standards 24
Jul 06, 2021 22:31:24.510 [8282] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, Name 955D, Standards 24
Jul 06, 2021 22:31:24.516 [8282] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:24.521 [8282] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:24.704 [9026] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:24.704 [9026] DEBUG - [I] DTV_STAT_CNR has failed for tuner 0, frontend 0
Jul 06, 2021 22:31:24.706 [9026] DEBUG - [I] quality: 225
Jul 06, 2021 22:31:24.858 [17785] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:24.859 [17785] DEBUG - [I] DTV_STAT_CNR has failed for tuner 1, frontend 0
Jul 06, 2021 22:31:24.861 [17785] DEBUG - [I] quality: 223
Jul 06, 2021 22:31:27.866 [8280] INFO - [I] web_service_t::process_device_status
Jul 06, 2021 22:31:27.866 [8280] INFO - [I] device_reactor_t::get_device_list (dvb#v4l#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0013984767)
Jul 06, 2021 22:31:27.866 [8280] INFO - [I] v4l::DeviceGetList_impl. getting v4l device list
Jul 06, 2021 22:31:27.866 [8280] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb0.frontend0
Jul 06, 2021 22:31:27.868 [8280] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb1.frontend0
Jul 06, 2021 22:31:27.873 [8280] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, Name 955D, Standards 24
Jul 06, 2021 22:31:27.873 [8280] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, Name 955D, Standards 24
Jul 06, 2021 22:31:27.883 [8280] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:27.893 [8280] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:28.030 [9026] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:28.030 [9026] DEBUG - [I] DTV_STAT_CNR has failed for tuner 0, frontend 0
Jul 06, 2021 22:31:28.032 [9026] DEBUG - [I] quality: 227
Jul 06, 2021 22:31:28.207 [17785] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -63000, 18446744073709488616, 1
Jul 06, 2021 22:31:28.208 [17785] DEBUG - [I] DTV_STAT_CNR has failed for tuner 1, frontend 0
Jul 06, 2021 22:31:28.210 [17785] DEBUG - [I] quality: 230
Jul 06, 2021 22:31:31.216 [8281] INFO - [I] web_service_t::process_device_status
Jul 06, 2021 22:31:31.216 [8281] INFO - [I] device_reactor_t::get_device_list (dvb#v4l#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0013984767)
Jul 06, 2021 22:31:31.216 [8281] INFO - [I] v4l::DeviceGetList_impl. getting v4l device list
Jul 06, 2021 22:31:31.216 [8281] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb0.frontend0
Jul 06, 2021 22:31:31.221 [8281] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb1.frontend0
Jul 06, 2021 22:31:31.230 [8281] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, Name 955D, Standards 24
Jul 06, 2021 22:31:31.231 [8281] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, Name 955D, Standards 24
Jul 06, 2021 22:31:31.233 [8281] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:31.241 [8281] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:31.379 [9026] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:31.379 [9026] DEBUG - [I] DTV_STAT_CNR has failed for tuner 0, frontend 0
Jul 06, 2021 22:31:31.381 [9026] DEBUG - [I] quality: 227
Jul 06, 2021 22:31:31.549 [17785] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:31.550 [17785] DEBUG - [I] DTV_STAT_CNR has failed for tuner 1, frontend 0
Jul 06, 2021 22:31:31.555 [17785] DEBUG - [I] quality: 230
Jul 06, 2021 22:31:34.560 [8282] INFO - [I] web_service_t::process_device_status
Jul 06, 2021 22:31:34.560 [8282] INFO - [I] device_reactor_t::get_device_list (dvb#v4l#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0013984767)
Jul 06, 2021 22:31:34.560 [8282] INFO - [I] v4l::DeviceGetList_impl. getting v4l device list
Jul 06, 2021 22:31:34.561 [8282] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb0.frontend0
Jul 06, 2021 22:31:34.562 [8282] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb1.frontend0
Jul 06, 2021 22:31:34.569 [8282] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, Name 955D, Standards 24
Jul 06, 2021 22:31:34.569 [8282] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, Name 955D, Standards 24
Jul 06, 2021 22:31:34.580 [8282] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:34.580 [8282] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:34.723 [9026] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:34.723 [9026] DEBUG - [I] DTV_STAT_CNR has failed for tuner 0, frontend 0
Jul 06, 2021 22:31:34.726 [9026] DEBUG - [I] quality: 227
Jul 06, 2021 22:31:34.911 [17785] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:34.911 [17785] DEBUG - [I] DTV_STAT_CNR has failed for tuner 1, frontend 0
Jul 06, 2021 22:31:34.913 [17785] DEBUG - [I] quality: 227
Jul 06, 2021 22:31:37.919 [8278] INFO - [I] web_service_t::process_device_status
Jul 06, 2021 22:31:37.919 [8278] INFO - [I] device_reactor_t::get_device_list (dvb#v4l#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0013984767)
Jul 06, 2021 22:31:37.919 [8278] INFO - [I] v4l::DeviceGetList_impl. getting v4l device list
Jul 06, 2021 22:31:37.919 [8278] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb0.frontend0
Jul 06, 2021 22:31:37.921 [8278] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb1.frontend0
Jul 06, 2021 22:31:37.932 [8278] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, Name 955D, Standards 24
Jul 06, 2021 22:31:37.933 [8278] INFO - [I] dvb_device_factory_t::get_device_list. Id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, Name 955D, Standards 24
Jul 06, 2021 22:31:37.937 [8278] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:0, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:37.941 [8278] INFO - [I] device_manager_t::get_device_list. Device 955D, id dvb#v4l#0#/sys/devices/70090000.xusb/usb1/1-2/1-2.2/1-2.2:1.0:1, tuners 1, state 2, standards 24
Jul 06, 2021 22:31:38.085 [9026] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:38.085 [9026] DEBUG - [I] DTV_STAT_CNR has failed for tuner 0, frontend 0
Jul 06, 2021 22:31:38.087 [9026] DEBUG - [I] quality: 227
Jul 06, 2021 22:31:38.228 [17785] DEBUG - [I] DTV_STAT_SIGNAL_STRENGTH: 1, -64000, 18446744073709487616, 1
Jul 06, 2021 22:31:38.228 [17785] DEBUG - [I] DTV_STAT_CNR has failed for tuner 1, frontend 0
Jul 06, 2021 22:31:38.230 [17785] DEBUG - [I] quality: 227

[dzeff]

I did find this in Plex Tuner Service.5.log from July 2nd 3 PM when it appears to have had the issue becaues the next time the Tuner log is logged starts on the 6th:

Jul 02, 2021 15:00:20.871 [7171] INFO - Plex Tuner Service v1.0.0.0 - NVIDIA SHIELD Android TV foster - build:
Jul 02, 2021 15:00:20.871 [7171] INFO - Android version: 9, language: en-US
Jul 02, 2021 15:00:20.872 [7171] INFO - Processor Unknown architecture
Jul 02, 2021 15:00:20.872 [7171] INFO - /data/user/0/com.plexapp.mediaserver.smb/Resources/Plex Tuner Service /data/user/0/com.plexapp.mediaserver.smb/Resources/Tuner/Private /data/user/0/com.plexapp.mediaserver.smb/Resources/Tuner/Shared 1.23.3.4707-ebb5fe9f3 32600
Jul 02, 2021 15:00:20.838 [7170] INFO - [I] broker_t::start…
Jul 02, 2021 15:00:20.935 [7170] INFO - [I] device_manager_t::init.
Jul 02, 2021 15:00:20.935 [7170] INFO - [I] device_manager_t::init. Initialize provider manager
Jul 02, 2021 15:00:20.935 [7170] INFO - [I] device_manager_t::init. Initialize all device auxes
Jul 02, 2021 15:00:20.935 [7170] INFO - [I] dvb_device_factory_t::get_aux_list
Jul 02, 2021 15:00:20.936 [7170] INFO - [I] tvbutler_aux_t::init
Jul 02, 2021 15:00:20.940 [7170] INFO - [I] web_server_t::start. Starting web server on port 32600
Jul 02, 2021 15:00:20.941 [7170] WARN - [E] web_server_t: bind: Address already in use
Jul 02, 2021 15:00:20.941 [7170] WARN - [E] web_server_t: port 32600 is not available