SageTV Community  

Go Back   SageTV Community > SageTV Products > SageTV Linux > SageTV for unRAID/Docker

Notices

SageTV for unRAID/Docker Discussion related to SageTV for unRAID/Docker. Questions, issues, problems, suggestions, etc. relating to SageTV for unRAID/Docker should be posted here.

Reply
 
Thread Tools Search this Thread Display Modes
  #1  
Old 04-14-2017, 07:26 AM
wayner wayner is offline
SageTVaholic
 
Join Date: Jan 2008
Location: Toronto, ON
Posts: 7,192
Strange OTA recording errors

I am continually getting errors on my system with OTA recordings. These errors ALWAYS happen within a few minutes of the top of the hour. These recordings are generally 3 hours or longer in length and are of golf, hockey, basketball, etc. For example, I was recording a hockey game last night starting at 19:00 and I had these errors at 21:01 and 22:01. I get a "Halt Detected in Recording" error. After a couple of minutes the recording will recover and restart but it will have a hole of a couple of minutes. There relevant portion of the SageTV log is posted below. There is nothing in the unRAID logs at the time of these errors.

These errors occur almost every long OTA recording that I do - sometimes one hour after the start of the recordings, sometimes two or more hours after the start of the recording.

FYI - the tuner is an HDHR - I have used this tuner for years in my previous Windows based system without any issues. These recordings are being made to a /media share that I have created on my server. The cache setting for this share is No. These same types of errors do not occur on recordings from my HD-PVR tuners.

Is there anything that unRAID or that SageTV could be doing to cause all of this to happen?

Code:
Thu 4/13 22:01:27.196 [FSManager@636713b6] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=2939479 A[2849037,2849036,"NHL Hockey",46245@0413.19:00,180,T] mask=TV host=Hoylake encodedBy=HDHomeRun 1040e3e1 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]{SeriesInfoID=2380853, SREv4_Monitored=true}] /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-0.mpg, Seg0[Thu 4/13 19:00:00.003-Thu 4/13 21:01:26.808], Seg1[Thu 4/13 21:02:56.784-Wed 12/31 19:00:00.000]] size=6777476070
Thu 4/13 22:01:27.198 [Seeker@62933632] SEEKER HAS DETECTED A HALT IN THE ENCODER...TRYING TO RESET IT file=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg size=6777476070
Thu 4/13 22:01:27.199 [Seeker@62933632] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg])
Thu 4/13 22:01:27.199 [Seeker@62933632] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg])
Thu 4/13 22:01:27.199 [Seeker@62933632] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg])
Thu 4/13 22:01:27.199 [VideoFrame-448a5b24fb26@6bd8eadd] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg] nPlayin=false
Thu 4/13 22:01:27.199 [VideoFrame-448a5b24fb26@6bd8eadd] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg]
Thu 4/13 22:01:27.199 [VideoFrame-448a5b24fb26@6bd8eadd] VF processing job null nPlayin=false
Thu 4/13 22:01:27.199 [VideoFrame-448a5b24fb26@6bd8eadd] VF thread is now waiting for 0:00:00.000
Thu 4/13 22:01:27.199 [VideoFrame-001d6a4bff9a@24860822] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg] nPlayin=true
Thu 4/13 22:01:27.200 [Seeker@62933632] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg])
Thu 4/13 22:01:27.200 [VideoFrame-001d6a4bff9a@24860822] VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg]
Thu 4/13 22:01:27.212 [VideoFrame-001d6a4bff9a@24860822] VF processing job null nPlayin=true
Thu 4/13 22:01:27.213 [VideoFrame-001d6a5ca9ad@1b15b219] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg] nPlayin=false
Thu 4/13 22:01:27.213 [Seeker@62933632] stopEncoding for HDHomeRun 1040e3e1 Tuner 0
Thu 4/13 22:01:27.213 [Seeker@62933632] Waiting for capture thread to terminate
Thu 4/13 22:01:27.213 [VideoFrame-001d6a5ca9ad@1b15b219] VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-1.mpg]
Thu 4/13 22:01:27.213 [VideoFrame-001d6a5ca9ad@1b15b219] VF notified of Inactive File
Thu 4/13 22:01:27.213 [VideoFrame-001d6a4bff9a@24860822] isRec=false rd=461470 base=257292 eos=false
Thu 4/13 22:01:27.213 [VideoFrame-001d6a4bff9a@24860822] VF thread is now waiting for 0:03:23.928
Thu 4/13 22:01:27.213 [VideoFrame-001d6a5ca9ad@1b15b219] VF processing job null nPlayin=false
Thu 4/13 22:01:27.218 [VideoFrame-001d6a5ca9ad@1b15b219] isRec=true rd=3510434 base=2032425 eos=false
Thu 4/13 22:01:27.218 [VideoFrame-001d6a5ca9ad@1b15b219] VF thread is now waiting for 0:00:00.000
Thu 4/13 22:01:27.337 [HDHomeRun 1040e3e1 Tuner 0-Encoder@61de3e0d] HDHR_closeEncoding0(0x2ae4d8339f40)
Thu 4/13 22:01:27.355 [HDHomeRun 1040e3e1 Tuner 0-Encoder@61de3e0d] HDHomeRun 1040e3e1 Tuner 0 capture thread terminating
Thu 4/13 22:01:27.356 [Seeker@62933632] MediaFile startSegment enc=null MediaFile[id=2939479 A[2849037,2849036,"NHL Hockey",46245@0413.19:00,180,T] mask=TV host=Hoylake encodedBy=HDHomeRun 1040e3e1 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]{SeriesInfoID=2380853, SREv4_Monitored=true}] /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-0.mpg, Seg0[Thu 4/13 19:00:00.003-Thu 4/13 21:01:26.808], Seg1[Thu 4/13 21:02:56.784-Thu 4/13 22:01:27.355]]
Thu 4/13 22:01:27.356 [Seeker@62933632] MediaFile created subfile:/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-2.mpg MediaFile[id=2939479 A[2849037,2849036,"NHL Hockey",46245@0413.19:00,180,T] mask=TV host=Hoylake encodedBy=HDHomeRun 1040e3e1 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]{SeriesInfoID=2380853, SREv4_Monitored=true}] /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-0.mpg, Seg0[Thu 4/13 19:00:00.003-Thu 4/13 21:01:26.808], Seg1[Thu 4/13 21:02:56.784-Thu 4/13 22:01:27.355]]
Thu 4/13 22:01:27.356 [Seeker@62933632] startEncoding for HDHomeRun 1040e3e1 Tuner 0, file=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-2.mpg, chan=
Thu 4/13 22:01:27.356 [Seeker@62933632] HDHRCaptureDevice->doTuneChannel(20-5-1,true)
Thu 4/13 22:01:27.357 [Seeker@62933632] HDHR_setChannel0(0x2ae4d8339f40, 20-5-1, true, 31)
Thu 4/13 22:01:27.463 [Seeker@62933632] HDHR_setupEncoding0(0x2ae4d8339f40, /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-2.mpg, 0)
Thu 4/13 22:01:27.463 [Seeker@62933632] HDHR_getBroadcastStandard0(0x2ae4d8339f40)
Thu 4/13 22:01:27.464 [VideoFrame-448a5b24fb26@6bd8eadd] VF processing job null nPlayin=false
Thu 4/13 22:01:27.465 [HDHomeRun 1040e3e1 Tuner 0-Encoder@51f9f472] Starting capture thread for HDHomeRun 1040e3e1 Tuner 0
Thu 4/13 22:01:27.466 [MsgManager@76868741] MsgManager (queueSize=1) is processing message:The device input "HDHomeRun 1040e3e1 Tuner 0 Digital TV Tuner" stopped producing data while recording "NHL Hockey" on CBLTDT on physical channel 20-5-1. SageTV will try to reset the device. This may be caused by a channel that is no longer available.
Thu 4/13 22:01:27.467 [VideoFrame-001d6a5ca9ad@1b15b219] VF processing job null nPlayin=false
Thu 4/13 22:01:27.467 [VideoFrame-001d6a4bff9a@24860822] VF processing job null nPlayin=true
Thu 4/13 22:01:27.467 [Seeker@62933632] Checking video directories for new files
Thu 4/13 22:01:27.468 [VideoFrame-448a5b24fb26@6bd8eadd] VF thread is now waiting for 0:00:00.000
Thu 4/13 22:01:27.468 [PluginEventQueue@19c119e9] [[SageTCPServer]]: Event: SystemMessagePosted
Thu 4/13 22:01:27.468 [VideoFrame-001d6a4bff9a@24860822] isRec=false rd=461470 base=257546 eos=false
Thu 4/13 22:01:27.468 [VideoFrame-001d6a4bff9a@24860822] VF thread is now waiting for 0:03:23.674
Thu 4/13 22:01:27.469 [Seeker@62933632] Diskspace checking is running
Thu 4/13 22:01:27.469 [Seeker@62933632] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Thu 4/13 22:01:27.471 [VideoFrame-001d6a5ca9ad@1b15b219] isRec=true rd=3510571 base=2032425 eos=false
Thu 4/13 22:01:27.471 [VideoFrame-001d6a5ca9ad@1b15b219] VF thread is now waiting for 0:00:00.000
Thu 4/13 22:01:27.475 [Seeker@62933632] Enforcing keep at most limits for the files...
Thu 4/13 22:01:27.475 [Seeker@62933632] DONE enforcing keep at most limits for the files.
Thu 4/13 22:01:27.476 [Seeker@62933632] Seeker clearing unwanted and partial files...
Thu 4/13 22:01:27.476 [Seeker@62933632] DONE clearing unwanted and partial files.
Thu 4/13 22:01:27.479 [Seeker@62933632] needFreeSize = 2.025 GB currFreeSize=3402.987791872 GB
Thu 4/13 22:01:27.479 [Seeker@62933632] Seeker waiting for 2 mins.
Thu 4/13 22:01:28.238 [MsgManager@76868741] MsgManager (queueSize=1) is processing message:SageMsg[type=11 prior=100 src=HDHomeRun 1040e3e1 Tuner 0-0 data=AV-INF|f=MPEG2-PS;br=2451000;[bf=vid;f=MPEG2-Video;fps=59.940060;fpsn=60000;fpsd=1001;ar=1.777778;arn=16;ard=9;w=1280;h=720;lace=0;cs=yuv420p;main=yes;tag=e0;index=0;];[bf=aud;f=AC3;sr=48000;ch=6;br=384000;main=yes;lang=eng;tag=bd-80010000;index=1;];[bf=aud;f=AC3;sr=48000;ch=2;br=192000;lang=enm;ty=commentary;tag=bd-81010000;index=2;];  timestamp=Thu 4/13 22:01:28.238]
Thu 4/13 22:01:28.239 [MsgManager@76868741] Setting media file format for MediaFile[id=2939479 A[2849037,2849036,"NHL Hockey",46245@0413.19:00,180,T] mask=TV host=Hoylake encodedBy=HDHomeRun 1040e3e1 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]{SeriesInfoID=2380853, SREv4_Monitored=true}] /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-0.mpg, Seg0[Thu 4/13 19:00:00.003-Thu 4/13 21:01:26.808], Seg1[Thu 4/13 21:02:56.784-Thu 4/13 22:01:27.355], Seg2[Thu 4/13 22:01:27.356-Wed 12/31 19:00:00.000]] to be MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]]
Thu 4/13 22:01:28.239 [VideoFrame-448a5b24fb26@6bd8eadd] VF processing job null nPlayin=false
Thu 4/13 22:01:28.239 [VideoFrame-448a5b24fb26@6bd8eadd] VF thread is now waiting for 0:00:00.000
Thu 4/13 22:01:28.239 [VideoFrame-001d6a4bff9a@24860822] VF processing job null nPlayin=true
Thu 4/13 22:01:28.239 [VideoFrame-001d6a5ca9ad@1b15b219] VF processing job null nPlayin=false
Thu 4/13 22:01:28.241 [VideoFrame-001d6a4bff9a@24860822] isRec=false rd=461470 base=258319 eos=false
Thu 4/13 22:01:28.241 [VideoFrame-001d6a4bff9a@24860822] VF thread is now waiting for 0:03:22.901
Thu 4/13 22:01:28.244 [VideoFrame-001d6a5ca9ad@1b15b219] isRec=true rd=3510571 base=2032425 eos=false
Thu 4/13 22:01:28.244 [VideoFrame-001d6a5ca9ad@1b15b219] VF thread is now waiting for 0:00:00.000

.
.[stuff edited out]
.

Thu 4/13 22:03:42.350 [Seeker@62933632] MediaFile startSegment enc=null MediaFile[id=2939479 A[2849037,2849036,"NHL Hockey",46245@0413.19:00,180,T] mask=TV host=Hoylake encodedBy=HDHomeRun 1040e3e1 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]{SREv4_Monitored=true}] /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-0.mpg, Seg0[Thu 4/13 19:00:00.003-Thu 4/13 21:01:26.808], Seg1[Thu 4/13 21:02:56.784-Thu 4/13 22:01:27.355], Seg2[Thu 4/13 22:01:27.356-Thu 4/13 22:03:42.349]]
Thu 4/13 22:03:42.350 [Seeker@62933632] MediaFile created subfile:/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-3.mpg MediaFile[id=2939479 A[2849037,2849036,"NHL Hockey",46245@0413.19:00,180,T] mask=TV host=Hoylake encodedBy=HDHomeRun 1040e3e1 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]{SREv4_Monitored=true}] /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-0.mpg, Seg0[Thu 4/13 19:00:00.003-Thu 4/13 21:01:26.808], Seg1[Thu 4/13 21:02:56.784-Thu 4/13 22:01:27.355], Seg2[Thu 4/13 22:01:27.356-Thu 4/13 22:03:42.349]]
Thu 4/13 22:03:42.350 [Seeker@62933632] startEncoding for HDHomeRun 1040e3e1 Tuner 0, file=/var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-3.mpg, chan=
Thu 4/13 22:03:42.350 [Seeker@62933632] HDHRCaptureDevice->doTuneChannel(20-5-1,true)
Thu 4/13 22:03:42.350 [Seeker@62933632] HDHR_setChannel0(0x2ae4d8339f40, 20-5-1, true, 31)
Thu 4/13 22:03:42.352 [Seeker@62933632] HDHR_setupEncoding0(0x2ae4d8339f40, /var/media/tv/NHLHockey-TorontoMapleLeafsatWashingtonCapitals-2849037-3.mpg, 0)
Thu 4/13 22:03:42.353 [Seeker@62933632] HDHR_getBroadcastStandard0(0x2ae4d8339f40)
Thu 4/13 22:03:42.353 [MsgManager@76868741] MsgManager (queueSize=1) is processing message:The device input "HDHomeRun 1040e3e1 Tuner 0 Digital TV Tuner" stopped producing data while recording "NHL Hockey" on CBLTDT on physical channel 20-5-1. SageTV will try to reset the device. This may be caused by a channel that is no longer available.
Thu 4/13 22:03:42.353 [HDHomeRun 1040e3e1 Tuner 0-Encoder@1712f9a9] Starting capture thread for HDHomeRun 1040e3e1 Tuner 0
Thu 4/13 22:03:42.354 [VideoFrame-001d6a4bff9a@24860822] VF processing job null nPlayin=true
Thu 4/13 22:03:42.354 [VideoFrame-448a5b24fb26@6bd8eadd] VF processing job null nPlayin=false
Thu 4/13 22:03:42.355 [VideoFrame-448a5b24fb26@6bd8eadd] VF thread is now waiting for 0:00:00.000
Thu 4/13 22:03:42.355 [VideoFrame-001d6a5ca9ad@1b15b219] VF processing job null nPlayin=true
Thu 4/13 22:03:42.355 [VideoFrame-001d6a5ca9ad@1b15b219] isRec=true rd=3510571 base=2170085 eos=false
Thu 4/13 22:03:42.355 [VideoFrame-001d6a5ca9ad@1b15b219] VF thread is now waiting for 0:22:20.236
Thu 4/13 22:03:42.356 [PluginEventQueue@19c119e9] [[SageTCPServer]]: Event: SystemMessagePosted
__________________
New Server - Sage9 on unRAID 2xHD-PVR, HDHR for OTA
Old Server - Sage7 on Win7Pro-i660CPU with 4.6TB, HD-PVR, HDHR OTA, HVR-1850 OTA
Clients - 2xHD-300, 8xHD-200 Extenders, Client+2xPlaceshifter and a WHS which acts as a backup Sage server
Reply With Quote
  #2  
Old 07-10-2017, 10:28 PM
glenner glenner is offline
Sage Advanced User
 
Join Date: May 2010
Location: Toronto, ON
Posts: 99
Quote:
Originally Posted by wayner View Post
I am continually getting errors on my system with OTA recordings. These errors ALWAYS happen within a few minutes of the top of the hour. These recordings are generally 3 hours or longer in length and are of golf, hockey, basketball, etc. For example, I was recording a hockey game last night starting at 19:00 and I had these errors at 21:01 and 22:01. I get a "Halt Detected in Recording" error. After a couple of minutes the recording will recover and restart but it will have a hole of a couple of minutes. There relevant portion of the SageTV log is posted below. There is nothing in the unRAID logs at the time of these errors.

These errors occur almost every long OTA recording that I do - sometimes one hour after the start of the recordings, sometimes two or more hours after the start of the recording.

FYI - the tuner is an HDHR - I have used this tuner for years in my previous Windows based system without any issues. These recordings are being made to a /media share that I have created on my server. The cache setting for this share is No. These same types of errors do not occur on recordings from my HD-PVR tuners.

Is there anything that unRAID or that SageTV could be doing to cause all of this to happen?
Hey Wayner. Did you ever solve this? I have the same issue... on my new unraid setup... and also with CBC. CBC "The National" has had the same issue the last 2 nights in a row starting at 10pm. It starts creating a sub file after the failure... The last 2 nights, my Sage docker app has crashed by around 11pm, and had to be restarted.

Here is my trace. Section pasted below. Full log is attached. Bad stuff starts at 22:00.

I noted the following trace lines:

MAJOR Change to file format detected
Async encoder halt detector found a halt
SEEKER HAS DETECTED A HALT IN THE ENCODER
MediaFile created subfile


These errors repeat themselves for a whole hour till 11:02pm and then Sage crashes, and I need to restart the docker.

Code:
Mon 7/10 22:00:00.000 [Seeker@37201494] Seeker awoken
Mon 7/10 22:00:00.001 [Seeker@37201494] MemStats: Used=433MB Total=805MB Max=805MB
Mon 7/10 22:00:00.002 [Seeker@37201494] MARK 1 currRecord=null enc=HDHomeRun 1055a735 Tuner 0 clients=[] ir=false
Mon 7/10 22:00:00.003 [Seeker@37201494] Seeker in AUTOMATIC mode nextRecord=A[7405789,7405756,"The National",46245@0710.22:00,60,T] nextTTA=0
Mon 7/10 22:00:00.004 [Seeker@37201494] newRecord=A[7405789,7405756,"The National",46245@0710.22:00,60,T]
Mon 7/10 22:00:00.004 [Seeker@37201494] Change in record to another show. Entering device record mode. - LATER
Mon 7/10 22:00:00.005 [Seeker@37201494] MARK 1 currRecord=null enc=HDHomeRun 1055a735 Tuner 1 clients=[] ir=false
Mon 7/10 22:00:00.005 [Seeker@37201494] Seeker in AUTOMATIC mode nextRecord=A[7405565,7405541,"The Daily Show With Trevor Noah",44784@0711.00:05,32,T] nextTTA=7499998
Mon 7/10 22:00:00.006 [Seeker@37201494] newRecord=null
Mon 7/10 22:00:00.006 [Seeker@37201494] NOTHING TO RECORD FOR NOW...
Mon 7/10 22:00:00.006 [Seeker@37201494] Change in record to another show. Entering device record mode. - NOW
Mon 7/10 22:00:00.007 [Seeker@37201494] Seeker.startRecord(HDHomeRun 1055a735 Tuner 0 A[7405789,7405756,"The National",46245@0710.22:00,60,T], currTime=Mon 7/10 22:00:00.002) currRecord=null switch=false
Mon 7/10 22:00:00.007 [Seeker@37201494] Setting up MMC video for recording new show & tuning channel conn=HDHomeRun 1055a735 Tuner 0 Digital TV Tuner
Mon 7/10 22:00:00.008 [Seeker@37201494] Not using desired quality setting of Default because the capture device doesn't support it
Mon 7/10 22:00:00.008 [Seeker@37201494] Using quality setting "" for recording
Mon 7/10 22:00:00.009 [Seeker@37201494] VideoStorage for new file: /var/media/tv - Leave Free 25.0 GB
Mon 7/10 22:00:00.010 [Seeker@37201494] MediaFile created subfile:/var/media/tv/TheNational-7405789-0.mpg MediaFile[id=7704911 A[7405789,7405756,"The National",46245@0710.22:00,60,T] mask=TV host=unraid encodedBy=HDHomeRun 1055a735 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 0 kbps []]
Mon 7/10 22:00:00.011 [Seeker@37201494] Added:MediaFile[id=7704911 A[7405789,7405756,"The National",46245@0710.22:00,60,T] mask=TV host=unraid encodedBy=HDHomeRun 1055a735 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 0 kbps [] /var/media/tv/TheNational-7405789-0.mpg, Seg0[Mon 7/10 22:00:00.003-Wed 12/31 19:00:00.000]]
Mon 7/10 22:00:00.011 [Seeker@37201494] Seeker channel string=20-5-1
Mon 7/10 22:00:00.011 [Seeker@37201494] startEncoding for HDHomeRun 1055a735 Tuner 0, file=/var/media/tv/TheNational-7405789-0.mpg, chan=20-5-1
Mon 7/10 22:00:00.012 [Seeker@37201494] HDHR_setInput0(0x2b3e509acca0, 100, 0, Air, 1, 47545287966721)
Mon 7/10 22:00:00.012 [Seeker@37201494] HDHRCaptureDevice->doTuneChannel(20-5-1,true)
Mon 7/10 22:00:00.012 [Seeker@37201494] HDHR_setChannel0(0x2b3e509acca0, 20-5-1, true, 31)
Mon 7/10 22:00:00.133 [Seeker@37201494] HDHR_setupEncoding0(0x2b3e509acca0, /var/media/tv/TheNational-7405789-0.mpg, 0)
Mon 7/10 22:00:00.134 [Seeker@37201494] HDHR_getBroadcastStandard0(0x2b3e509acca0)
Mon 7/10 22:00:00.135 [HDHomeRun 1055a735 Tuner 0-Encoder@2cf9d71] Starting capture thread for HDHomeRun 1055a735 Tuner 0
Mon 7/10 22:00:00.136 [Seeker@37201494] RootFile=/ fstype=0x9123683e fileLength=0 file=/var/media/tv/TheNational-7405789-0.mpg
Mon 7/10 22:00:00.136 [VideoFrame-0023a500074c@37f906d1] VF processing job null nPlayin=false
Mon 7/10 22:00:00.137 [AsyncPropSaver@26ef06ca] Saving properties file to Sage.properties
Mon 7/10 22:00:00.137 [Seeker@37201494] Checking video directories for new files
Mon 7/10 22:00:00.137 [VideoFrame-0023a500074c@37f906d1] VF thread is now waiting for 0:00:00.000
Mon 7/10 22:00:00.143 [Seeker@37201494] Diskspace checking is running
Mon 7/10 22:00:00.143 [Seeker@37201494] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Mon 7/10 22:00:00.146 [Seeker@37201494] Enforcing keep at most limits for the files...
Mon 7/10 22:00:00.148 [Seeker@37201494] DONE enforcing keep at most limits for the files.
Mon 7/10 22:00:00.149 [Seeker@37201494] Seeker clearing unwanted and partial files...
Mon 7/10 22:00:00.151 [Seeker@37201494] DONE clearing unwanted and partial files.
Mon 7/10 22:00:00.157 [Seeker@37201494] needFreeSize = 2.025 GB currFreeSize=2801.55244288 GB
Mon 7/10 22:00:00.157 [Seeker@37201494] Seeker waiting for 59 mins.
Mon 7/10 22:00:00.158 [AsyncPropSaver@26ef06ca] Done writing out the data to the properties file
Mon 7/10 22:00:00.782 [MsgManager@350a3ffa] Setting media file format for MediaFile[id=7704911 A[7405789,7405756,"The National",46245@0710.22:00,60,T] mask=TV host=unraid encodedBy=HDHomeRun 1055a735 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 0 kbps [] /var/media/tv/TheNational-7405789-0.mpg, Seg0[Mon 7/10 22:00:00.003-Wed 12/31 19:00:00.000]] to be MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]]
Mon 7/10 22:00:00.783 [MsgManager@350a3ffa] MAJOR Change to file format detected...reload any active media players using: MediaFile[id=7704911 A[7405789,7405756,"The National",46245@0710.22:00,60,T] mask=TV host=unraid encodedBy=HDHomeRun 1055a735 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]] /var/media/tv/TheNational-7405789-0.mpg, Seg0[Mon 7/10 22:00:00.003-Wed 12/31 19:00:00.000]]
Mon 7/10 22:00:00.783 [VideoFrame-0023a500074c@37f906d1] VF processing job null nPlayin=false
Mon 7/10 22:00:00.784 [VideoFrame-0023a500074c@37f906d1] VF thread is now waiting for 0:00:00.000
Mon 7/10 22:00:08.163 [Ministry@77a179d2] Ministry is waiting for 180 sec
Mon 7/10 22:00:09.016 [SageTVMiniDiscoveryServer@1093b642] Server got broadcast packet: java.net.DatagramPacket@23654c42
Mon 7/10 22:00:09.018 [SageTVMiniDiscoveryServer@1093b642] Server sent back mini discovery data:java.net.DatagramPacket@23654c42 to /192.168.1.101 8271
Mon 7/10 22:00:19.017 [SageTVMiniDiscoveryServer@1093b642] Server got broadcast packet: java.net.DatagramPacket@3a1e3f9f
Mon 7/10 22:00:19.028 [SageTVMiniDiscoveryServer@1093b642] Server sent back mini discovery data:java.net.DatagramPacket@3a1e3f9f to /192.168.1.101 8271
Mon 7/10 22:00:29.017 [SageTVMiniDiscoveryServer@1093b642] Server got broadcast packet: java.net.DatagramPacket@11b57632
Mon 7/10 22:00:29.028 [SageTVMiniDiscoveryServer@1093b642] Server sent back mini discovery data:java.net.DatagramPacket@11b57632 to /192.168.1.101 8271
Mon 7/10 22:00:39.018 [SageTVMiniDiscoveryServer@1093b642] Server got broadcast packet: java.net.DatagramPacket@3f827573
Mon 7/10 22:00:39.031 [SageTVMiniDiscoveryServer@1093b642] Server sent back mini discovery data:java.net.DatagramPacket@3f827573 to /192.168.1.101 8271
Mon 7/10 22:00:49.019 [SageTVMiniDiscoveryServer@1093b642] Server got broadcast packet: java.net.DatagramPacket@360c399d
Mon 7/10 22:00:49.029 [SageTVMiniDiscoveryServer@1093b642] Server sent back mini discovery data:java.net.DatagramPacket@360c399d to /192.168.1.101 8271
Mon 7/10 22:00:59.018 [SageTVMiniDiscoveryServer@1093b642] Server got broadcast packet: java.net.DatagramPacket@7da51a04
Mon 7/10 22:00:59.030 [SageTVMiniDiscoveryServer@1093b642] Server sent back mini discovery data:java.net.DatagramPacket@7da51a04 to /192.168.1.101 8271
Mon 7/10 22:01:08.153 [Fork-PRQST-1215194@58705f80] Cleaning up orphaned auto compress files...
Mon 7/10 22:01:08.154 [Fork-PRQST-1215194@58705f80] Scanning video directory /var/media/tv
Mon 7/10 22:01:08.155 [Fork-PRQST-1215194@58705f80] Scanning files to auto compress...
Mon 7/10 22:01:08.162 [Fork-PRQST-1215194@58705f80] 0 files to scan
Mon 7/10 22:01:08.162 [Fork-PRQST-1215194@58705f80] AutoCompress generation waiting 5 minutes ...
Mon 7/10 22:01:09.018 [SageTVMiniDiscoveryServer@1093b642] Server got broadcast packet: java.net.DatagramPacket@479bf764
Mon 7/10 22:01:09.030 [SageTVMiniDiscoveryServer@1093b642] Server sent back mini discovery data:java.net.DatagramPacket@479bf764 to /192.168.1.101 8271
Mon 7/10 22:01:18.742 [FSManager@1072c717] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=7704911 A[7405789,7405756,"The National",46245@0710.22:00,60,T] mask=TV host=unraid encodedBy=HDHomeRun 1055a735 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]] /var/media/tv/TheNational-7405789-0.mpg, Seg0[Mon 7/10 22:00:00.003-Wed 12/31 19:00:00.000]] size=4944722
Mon 7/10 22:01:18.743 [Seeker@37201494] Seeker awoken
Mon 7/10 22:01:18.744 [Seeker@37201494] MemStats: Used=441MB Total=805MB Max=805MB
Mon 7/10 22:01:18.744 [Seeker@37201494] MARK 1 currRecord=A[7405789,7405756,"The National",46245@0710.22:00,60,T] enc=HDHomeRun 1055a735 Tuner 0 clients=[] ir=false
Mon 7/10 22:01:18.745 [Seeker@37201494] Seeker in AUTOMATIC mode nextRecord=A[7405799,6667287,"CBC Toronto News at 11",46245@0710.23:00,30,T] nextTTA=3521256
Mon 7/10 22:01:18.746 [Seeker@37201494] newRecord=A[7405789,7405756,"The National",46245@0710.22:00,60,T]
Mon 7/10 22:01:18.747 [Seeker@37201494] Keeping record just the way it is.
Mon 7/10 22:01:18.747 [Seeker@37201494] MARK 1 currRecord=null enc=HDHomeRun 1055a735 Tuner 1 clients=[] ir=false
Mon 7/10 22:01:18.748 [Seeker@37201494] Seeker in AUTOMATIC mode nextRecord=A[7405565,7405541,"The Daily Show With Trevor Noah",44784@0711.00:05,32,T] nextTTA=7421256
Mon 7/10 22:01:18.749 [Seeker@37201494] newRecord=null
Mon 7/10 22:01:18.749 [Seeker@37201494] NOTHING TO RECORD FOR NOW...
Mon 7/10 22:01:18.750 [Seeker@37201494] RootFile=/ fstype=0x9123683e fileLength=4944722 file=/var/media/tv/TheNational-7405789-0.mpg
Mon 7/10 22:01:18.751 [Seeker@37201494] SEEKER HAS DETECTED A HALT IN THE ENCODER...TRYING TO RESET IT file=/var/media/tv/TheNational-7405789-0.mpg size=4944722
Mon 7/10 22:01:18.751 [Seeker@37201494] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/TheNational-7405789-0.mpg])
Mon 7/10 22:01:18.752 [Seeker@37201494] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/TheNational-7405789-0.mpg])
Mon 7/10 22:01:18.752 [VideoFrame-0023a500074c@37f906d1] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/TheNational-7405789-0.mpg] nPlayin=false
Mon 7/10 22:01:18.753 [VideoFrame-0023a500074c@37f906d1] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/TheNational-7405789-0.mpg]
Mon 7/10 22:01:18.753 [Seeker@37201494] stopEncoding for HDHomeRun 1055a735 Tuner 0
Mon 7/10 22:01:18.754 [Seeker@37201494] Waiting for capture thread to terminate
Mon 7/10 22:01:18.754 [VideoFrame-0023a500074c@37f906d1] VF processing job null nPlayin=false
Mon 7/10 22:01:18.754 [VideoFrame-0023a500074c@37f906d1] VF thread is now waiting for 0:00:00.000
Mon 7/10 22:01:18.839 [HDHomeRun 1055a735 Tuner 0-Encoder@2cf9d71] HDHR_closeEncoding0(0x2b3e509acca0)
Mon 7/10 22:01:18.864 [HDHomeRun 1055a735 Tuner 0-Encoder@2cf9d71] HDHomeRun 1055a735 Tuner 0 capture thread terminating
Mon 7/10 22:01:18.865 [Seeker@37201494] MediaFile startSegment enc=null MediaFile[id=7704911 A[7405789,7405756,"The National",46245@0710.22:00,60,T] mask=TV host=unraid encodedBy=HDHomeRun 1055a735 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]] /var/media/tv/TheNational-7405789-0.mpg, Seg0[Mon 7/10 22:00:00.003-Mon 7/10 22:01:18.864]]
Mon 7/10 22:01:18.866 [Seeker@37201494] MediaFile created subfile:/var/media/tv/TheNational-7405789-1.mpg MediaFile[id=7704911 A[7405789,7405756,"The National",46245@0710.22:00,60,T] mask=TV host=unraid encodedBy=HDHomeRun 1055a735 Tuner 0 CBLTDT format=MPEG2-PS 0:00:00 2451 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000 enm]] /var/media/tv/TheNational-7405789-0.mpg, Seg0[Mon 7/10 22:00:00.003-Mon 7/10 22:01:18.864]]
Mon 7/10 22:01:18.867 [Seeker@37201494] startEncoding for HDHomeRun 1055a735 Tuner 0, file=/var/media/tv/TheNational-7405789-1.mpg, chan=
Attached Files
File Type: zip sagetv_3.zip (711.6 KB, 108 views)
__________________

Server hardware: i7-7700@3.6GHz, ASUSTek Prime H270 Pro, R5 case, 32GB, 2x250GB M.2 SSD cache (raid1), 12TB pool, HDHR Dual Tuner, HDHR Extend, CM 4221/7778
Server software: unRAID 6.9.2, SageTV v9, SageMC (high WAF), SD EPG, dockers (SageTV, Plex, Emby, Unifi Controller, Sonarr, OpenVPN, DelugeVPN, Logitech Media Center, etc.), VMs
Clients: 3 x HD300, Placeshifters, 4 x FireStick4K+Android Miniclient, iOS devices+Plex
Reply With Quote
  #3  
Old 07-11-2017, 05:30 AM
Fuzzy's Avatar
Fuzzy Fuzzy is offline
SageTVaholic
 
Join Date: Sep 2005
Location: Jurupa Valley, CA
Posts: 9,957
If you have something like this hapening at a specific time, my guess is it is something outside of sagetv that is triggering on the hour, that is either cluttering up the network, or otherwise taxing the server in a way that it loses data. Sage may see the loss of data, or the HDHR detects a disconnection, either of which will result in sage stopping the recording, and restarting it to a new file (-1, -2, etc).
__________________
Buy Fuzzy a beer! (Fuzzy likes beer)

unRAID Server: i7-6700, 32GB RAM, Dual 128GB SSD cache and 13TB pool, with SageTVv9, openDCT, Logitech Media Server and Plex Media Server each in Dockers.
Sources: HRHR Prime with Charter CableCard. HDHR-US for OTA.
Primary Client: HD-300 through XBoxOne in Living Room, Samsung HLT-6189S
Other Clients: Mi Box in Master Bedroom, HD-200 in kids room
Reply With Quote
  #4  
Old 07-11-2017, 08:25 PM
glenner glenner is offline
Sage Advanced User
 
Join Date: May 2010
Location: Toronto, ON
Posts: 99
Quote:
Originally Posted by Fuzzy View Post
If you have something like this hapening at a specific time, my guess is it is something outside of sagetv that is triggering on the hour, that is either cluttering up the network, or otherwise taxing the server in a way that it loses data. Sage may see the loss of data, or the HDHR detects a disconnection, either of which will result in sage stopping the recording, and restarting it to a new file (-1, -2, etc).
Thanks. It was "something" on my network...

It was the other test Windows laptop I had setup a month+ ago that was running SageTV9 and was setup to record only one show... the 10pm show that was stealing the tuner and killing my unraid.

I had actually anticipated this conflict last week... and so I stopped the SageTV service and set it to "manual" on the laptop. But I still had the Sage "client" up on this machine, and evidently that is enough to run the server and somehow record shows. Weird. I never expected that. I figured if the Sage windows service is down, recordings are offline. Not the case.

In any event... It's 10:22... and my unraid is recording the show ok now....

But just for reference... If you want to crash the unraid SageTV docker... try running another instance of Sage and have it record the same shows on the same tuner.

All good. Unraid is back to "rock solid" status. Thanks again.
__________________

Server hardware: i7-7700@3.6GHz, ASUSTek Prime H270 Pro, R5 case, 32GB, 2x250GB M.2 SSD cache (raid1), 12TB pool, HDHR Dual Tuner, HDHR Extend, CM 4221/7778
Server software: unRAID 6.9.2, SageTV v9, SageMC (high WAF), SD EPG, dockers (SageTV, Plex, Emby, Unifi Controller, Sonarr, OpenVPN, DelugeVPN, Logitech Media Center, etc.), VMs
Clients: 3 x HD300, Placeshifters, 4 x FireStick4K+Android Miniclient, iOS devices+Plex

Last edited by glenner; 07-11-2017 at 08:28 PM.
Reply With Quote
  #5  
Old 07-12-2017, 06:29 AM
wayner wayner is offline
SageTVaholic
 
Join Date: Jan 2008
Location: Toronto, ON
Posts: 7,192
I think my error may also have been caused by another SageTV server trying to access the HDHR as well.
__________________
New Server - Sage9 on unRAID 2xHD-PVR, HDHR for OTA
Old Server - Sage7 on Win7Pro-i660CPU with 4.6TB, HD-PVR, HDHR OTA, HVR-1850 OTA
Clients - 2xHD-300, 8xHD-200 Extenders, Client+2xPlaceshifter and a WHS which acts as a backup Sage server
Reply With Quote
Reply


Currently Active Users Viewing This Thread: 1 (0 members and 1 guests)
 
Thread Tools Search this Thread
Search this Thread:

Advanced Search
Display Modes

Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is On
HTML code is Off

Forum Jump

Similar Threads
Thread Thread Starter Forum Replies Last Post
Is anything logged when there are recording errors? broconne SageTV Beta Test Software 6 10-20-2010 03:07 PM
Finding Recording Errors bsung SageTV Software 1 11-17-2009 08:30 AM
Recording Errors - Unknown Cause jillsy SageTV Software 0 03-06-2009 12:44 AM
Strange favorites recording errors Griff SageTV Software 4 06-27-2005 04:23 PM
Mpeg recording errors ripple SageTV Beta Test Software 6 03-30-2004 02:25 PM


All times are GMT -6. The time now is 09:41 PM.


Powered by vBulletin® Version 3.8.11
Copyright ©2000 - 2021, vBulletin Solutions Inc.
Copyright 2003-2005 SageTV, LLC. All rights reserved.