Sat 2/24 13:50:00.015 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2159) Sat 2/24 13:50:05.471 [EPG@c0738f] Fixing existing Show objects for new SeriesInfo data legacyID=1586173 showcardID=518788 SeriesInfo[32949274,Turning Point] Sat 2/24 13:50:05.472 [EPG@c0738f] Fixed 1 EP01586173 prefixed entries of 2 Sat 2/24 13:50:05.472 [EPG@c0738f] Fixed 0 SH01586173 prefixed entries of 1 Sat 2/24 13:50:23.586 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:50:23.586 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:50:23.586 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:50:23.587 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:50:38.257 [Seeker@1479697] Seeker awoken Sat 2/24 13:50:38.257 [Seeker@1479697] MemStats: Used=579MB Total=1037MB Max=1037MB Sat 2/24 13:50:38.263 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:50:38.263 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] nextTTA=381737 Sat 2/24 13:50:38.263 [Seeker@1479697] newRecord=null Sat 2/24 13:50:38.263 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:50:38.264 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:50:38.264 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] nextTTA=381737 Sat 2/24 13:50:38.264 [Seeker@1479697] newRecord=null Sat 2/24 13:50:38.264 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:50:38.264 [Seeker@1479697] MARK 1 currRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:50:38.264 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783531,35779496,"2018 Winter Olympics",48639@0224.20:30,210,T] nextTTA=23781737 Sat 2/24 13:50:38.264 [Seeker@1479697] newRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] Sat 2/24 13:50:38.264 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:50:38.264 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:50:38.265 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778145,29804598,"Beat Bobby Flay",50747@0224.14:30,30,T] nextTTA=2181737 Sat 2/24 13:50:38.265 [Seeker@1479697] newRecord=null Sat 2/24 13:50:38.265 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:50:38.265 [Seeker@1479697] MARK 1 currRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:50:38.265 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Sat 2/24 13:50:38.265 [Seeker@1479697] newRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] Sat 2/24 13:50:38.265 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:50:38.265 [Seeker@1479697] MARK 1 currRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:50:38.266 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783530,35779495,"2018 Winter Olympics",48639@0224.17:00,210,T] nextTTA=11181737 Sat 2/24 13:50:38.266 [Seeker@1479697] newRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] Sat 2/24 13:50:38.266 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:50:38.266 [Seeker@1479697] MARK 1 currRecord=null enc=Encoder on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:50:38.266 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783212,35560891,"Witness for the Prosecution",58530@0225.07:10,135,T] nextTTA=62181737 Sat 2/24 13:50:38.266 [Seeker@1479697] newRecord=null Sat 2/24 13:50:38.266 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:50:38.266 [Seeker@1479697] MARK 1 currRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] enc=Hauppauge HD PVR Capture Device clients=[] ir=false Sat 2/24 13:50:38.266 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35780909,35642909,"Torn Curtain",36225@0225.01:24,130,T] nextTTA=41421737 Sat 2/24 13:50:38.267 [Seeker@1479697] newRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] Sat 2/24 13:50:38.267 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:50:38.267 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=16535048004 file=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts Sat 2/24 13:50:38.267 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=9391259648 file=h:\videos\BadDayatBlackRock-35777317-0.ts Sat 2/24 13:50:38.267 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=4941280516 file=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts Sat 2/24 13:50:38.268 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=656507656 file=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts Sat 2/24 13:50:38.268 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:50:38.268 [Seeker@1479697] Checking video directories for new files Sat 2/24 13:50:38.268 [AsyncPropSaver@8de82d] Saving properties file to C:\sagetv\SageTV\Sage.properties Sat 2/24 13:50:38.268 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:50:38.269 [ReProcessHook@6ad5b3] CD STV:NumberRunning = 0 Sat 2/24 13:50:38.275 [Seeker@1479697] Diskspace checking is running Sat 2/24 13:50:38.275 [Seeker@1479697] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Sat 2/24 13:50:38.278 [ReProcessHook@6ad5b3] CD STV:NumberRunning = 0 Sat 2/24 13:50:38.286 [AsyncPropSaver@8de82d] Done writing out the data to the properties file Sat 2/24 13:50:39.206 [Seeker@1479697] Enforcing keep at most limits for the files... Sat 2/24 13:50:39.223 [Seeker@1479697] DONE enforcing keep at most limits for the files. Sat 2/24 13:50:39.223 [Seeker@1479697] Seeker clearing unwanted and partial files... Sat 2/24 13:50:39.262 [Seeker@1479697] DONE clearing unwanted and partial files. Sat 2/24 13:50:39.786 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3039.597128704 GB Sat 2/24 13:50:39.787 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3045.93085952 GB Sat 2/24 13:50:39.787 [Seeker@1479697] Seeker waiting for 1 mins. Sat 2/24 13:50:41.229 [EPG@c0738f] Fixing existing Show objects for new SeriesInfo data legacyID=2103137 showcardID=946075 SeriesInfo[33564592,Jewish Jesus] Sat 2/24 13:50:41.230 [EPG@c0738f] Fixed 1 EP02103137 prefixed entries of 2 Sat 2/24 13:50:41.230 [EPG@c0738f] Fixed 0 SH02103137 prefixed entries of 1 Sat 2/24 13:50:44.872 [Fork-OPUS4-108092@ee7fbb] Updating weather Sat 2/24 13:50:44.872 [Fork-BASE-71232@8de82d] Full Weather Update string URL = http://api.wunderground.com/api/f577e42a17aabb02/conditions/forecast10day/astronomy/alerts/q/KAFF.xml Sat 2/24 13:50:44.873 [Fork-BASE-71232@8de82d] downloading from:http://api.wunderground.com/api/f577e42a17aabb02/conditions/forecast10day/astronomy/alerts/q/KAFF.xml Sat 2/24 13:51:00.018 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2160) Sat 2/24 13:51:00.018 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2161) Sat 2/24 13:51:00.029 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2164) Sat 2/24 13:51:16.401 [EPG@c0738f] SDEPG Attempting to import images for 7281 people... Sat 2/24 13:51:23.586 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:51:23.586 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:51:23.586 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:51:23.586 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:51:59.999 [Seeker@1479697] Seeker awoken Sat 2/24 13:52:00.000 [Seeker@1479697] MemStats: Used=703MB Total=1037MB Max=1037MB Sat 2/24 13:52:00.004 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:52:00.005 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] nextTTA=299996 Sat 2/24 13:52:00.005 [Seeker@1479697] newRecord=null Sat 2/24 13:52:00.005 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:52:00.005 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:52:00.005 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] nextTTA=299996 Sat 2/24 13:52:00.005 [Seeker@1479697] newRecord=null Sat 2/24 13:52:00.005 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:52:00.005 [Seeker@1479697] MARK 1 currRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:52:00.006 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783531,35779496,"2018 Winter Olympics",48639@0224.20:30,210,T] nextTTA=23699996 Sat 2/24 13:52:00.006 [Seeker@1479697] newRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] Sat 2/24 13:52:00.006 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:52:00.006 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:52:00.006 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778145,29804598,"Beat Bobby Flay",50747@0224.14:30,30,T] nextTTA=2099996 Sat 2/24 13:52:00.006 [Seeker@1479697] newRecord=null Sat 2/24 13:52:00.006 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:52:00.006 [Seeker@1479697] MARK 1 currRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:52:00.007 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Sat 2/24 13:52:00.007 [Seeker@1479697] newRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] Sat 2/24 13:52:00.007 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:52:00.007 [Seeker@1479697] MARK 1 currRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:52:00.007 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783530,35779495,"2018 Winter Olympics",48639@0224.17:00,210,T] nextTTA=11099996 Sat 2/24 13:52:00.007 [Seeker@1479697] newRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] Sat 2/24 13:52:00.007 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:52:00.007 [Seeker@1479697] MARK 1 currRecord=null enc=Encoder on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:52:00.007 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783212,35560891,"Witness for the Prosecution",58530@0225.07:10,135,T] nextTTA=62099996 Sat 2/24 13:52:00.008 [Seeker@1479697] newRecord=null Sat 2/24 13:52:00.008 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:52:00.008 [Seeker@1479697] MARK 1 currRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] enc=Hauppauge HD PVR Capture Device clients=[] ir=false Sat 2/24 13:52:00.008 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35780909,35642909,"Torn Curtain",36225@0225.01:24,130,T] nextTTA=41339996 Sat 2/24 13:52:00.008 [Seeker@1479697] newRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] Sat 2/24 13:52:00.008 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:52:00.008 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=16699018972 file=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts Sat 2/24 13:52:00.009 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=9521823744 file=h:\videos\BadDayatBlackRock-35777317-0.ts Sat 2/24 13:52:00.009 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=5066354096 file=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts Sat 2/24 13:52:00.009 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=694622776 file=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts Sat 2/24 13:52:00.009 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:52:00.009 [Seeker@1479697] Checking video directories for new files Sat 2/24 13:52:00.010 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:52:00.010 [AsyncPropSaver@f875] Saving properties file to C:\sagetv\SageTV\Sage.properties Sat 2/24 13:52:00.017 [Seeker@1479697] Diskspace checking is running Sat 2/24 13:52:00.017 [Seeker@1479697] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Sat 2/24 13:52:00.017 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2165) Sat 2/24 13:52:00.017 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2122) Sat 2/24 13:52:00.020 [ReProcessHook@8de82d] CD STV:NumberRunning = 0 Sat 2/24 13:52:00.025 [ReProcessHook@8de82d] CD STV:NumberRunning = 0 Sat 2/24 13:52:00.028 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2166) Sat 2/24 13:52:00.028 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2167) Sat 2/24 13:52:00.030 [AsyncPropSaver@f875] Done writing out the data to the properties file Sat 2/24 13:52:00.939 [Seeker@1479697] Enforcing keep at most limits for the files... Sat 2/24 13:52:00.956 [Seeker@1479697] DONE enforcing keep at most limits for the files. Sat 2/24 13:52:00.956 [Seeker@1479697] Seeker clearing unwanted and partial files... Sat 2/24 13:52:00.998 [Seeker@1479697] DONE clearing unwanted and partial files. Sat 2/24 13:52:01.558 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3039.396260864 GB Sat 2/24 13:52:01.559 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3045.67461376 GB Sat 2/24 13:52:01.559 [Seeker@1479697] Seeker waiting for 4 mins. Sat 2/24 13:52:19.625 [Ministry@e75ab3] Ministry is waiting for 180 sec Sat 2/24 13:52:22.772 [ThreadMonitor@c0e9db] Top 10 Cumulative CPU(sec) PooledThread:447 9654489@qtp-18994252-5:238 VideoFrame-20cf30f40378:197 PooledThread:162 Scheduler:133 ClientListenerParallelizer:124 Seeker:81 KeepAlive:38 PooledThread:13 PooledThread:12 Sat 2/24 13:52:22.773 [ThreadMonitor@c0e9db] Top 10 %CPU (last 300s) PooledThread:2% EPG:1% Sat 2/24 13:52:23.585 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:52:23.585 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:52:23.585 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:52:23.585 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:53:00.035 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2168) Sat 2/24 13:53:00.035 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2169) Sat 2/24 13:53:00.084 [pool-14-thread-11@18801ca] SDEPG Unable to get head-shots for: SDPerson{personId='976382', nameId='1045586', name='Lyric Lewis', role='Actor', characterName='Stef', billingOrder='04'} Sat 2/24 13:53:00.084 [pool-14-thread-11@18801ca] sage.epg.sd.SDException: SAGETV_UNKNOWN Sat 2/24 13:53:00.084 [pool-14-thread-11@18801ca] at sage.epg.sd.SDErrors.throwErrorForCode(SDErrors.java:116) Sat 2/24 13:53:00.084 [pool-14-thread-11@18801ca] at sage.epg.sd.SDUtils.getStream(SDUtils.java:148) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at sage.epg.sd.SDSageSession.get(SDSageSession.java:216) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at sage.epg.sd.SDSageSession.get(SDSageSession.java:172) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at sage.epg.sd.SDSession.getJson(SDSession.java:671) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at sage.epg.sd.SDSession.getCelebrityImages(SDSession.java:1033) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at sage.epg.sd.SDRipper$1.run(SDRipper.java:2234) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) Sat 2/24 13:53:00.085 [pool-14-thread-11@18801ca] at java.lang.Thread.run(Unknown Source) Sat 2/24 13:53:20.528 [pool-14-thread-3@fab910] SDEPG Unable to get head-shots for: SDPerson{personId='459251', nameId='468126', name='Robin Sales', role='Film Editor', characterName='null', billingOrder='13'} Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] sage.epg.sd.SDException: SAGETV_UNKNOWN Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at sage.epg.sd.SDErrors.throwErrorForCode(SDErrors.java:116) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at sage.epg.sd.SDUtils.getStream(SDUtils.java:148) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at sage.epg.sd.SDSageSession.get(SDSageSession.java:216) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at sage.epg.sd.SDSageSession.get(SDSageSession.java:172) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at sage.epg.sd.SDSession.getJson(SDSession.java:671) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at sage.epg.sd.SDSession.getCelebrityImages(SDSession.java:1033) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at sage.epg.sd.SDRipper$1.run(SDRipper.java:2234) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) Sat 2/24 13:53:20.529 [pool-14-thread-3@fab910] at java.lang.Thread.run(Unknown Source) Sat 2/24 13:53:20.954 [pool-14-thread-3@fab910] SDEPG Unable to get head-shots for: SDPerson{personId='284766', nameId='494899', name='Bennie Richburg', role='Executive Producer', characterName='null', billingOrder='04'} Sat 2/24 13:53:20.954 [pool-14-thread-3@fab910] sage.epg.sd.SDException: SAGETV_UNKNOWN Sat 2/24 13:53:20.954 [pool-14-thread-3@fab910] at sage.epg.sd.SDErrors.throwErrorForCode(SDErrors.java:116) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at sage.epg.sd.SDUtils.getStream(SDUtils.java:148) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at sage.epg.sd.SDSageSession.get(SDSageSession.java:216) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at sage.epg.sd.SDSageSession.get(SDSageSession.java:172) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at sage.epg.sd.SDSession.getJson(SDSession.java:671) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at sage.epg.sd.SDSession.getCelebrityImages(SDSession.java:1033) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at sage.epg.sd.SDRipper$1.run(SDRipper.java:2234) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) Sat 2/24 13:53:20.955 [pool-14-thread-3@fab910] at java.lang.Thread.run(Unknown Source) Sat 2/24 13:53:23.584 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:53:23.585 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:53:23.585 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:53:23.585 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:53:31.112 [EPG@c0738f] SDEPG Imported images for 3140 people (+0) Sat 2/24 13:53:31.112 [EPG@c0738f] SDEPG No updates left to process. Sat 2/24 13:53:31.231 [EPG@c0738f] SDEPG Downloaded 2957 programs containing 70 teams for 112020 airings for: SDAccountLineup{lineup='USA-CO05427-X', name='Comcast - Digital', transport='Cable', location='Colorado Springs', uri='/20141201/lineups/USA-CO05427-X', isDeleted=false} Sat 2/24 13:53:31.232 [EPG@c0738f] SDEPG Using 133 airings for recommendations Sat 2/24 13:53:31.247 [EPG@c0738f] SDEPG Using 4772 recordings for recommendations Sat 2/24 13:53:31.249 [EPG@c0738f] SDEPG Using 4999 watched shows for recommendations Sat 2/24 13:53:48.805 [EPG@c0738f] SDEPG Got 1724 recommendations Sat 2/24 13:53:48.812 [EPG@c0738f] SDEPG Using 2179 watched shows for recommendations Sat 2/24 13:54:00.030 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2130) Sat 2/24 13:54:19.619 [EPG@c0738f] SDEPG Got 154 recommendations Sat 2/24 13:54:19.813 [EPG@c0738f] SDEPG Got 0 usable recommendations, 0 are already editorials Sat 2/24 13:54:19.814 [EPG@c0738f] SDEPG Got less than 6 recommendations, adding intelligent recordings Sat 2/24 13:54:19.814 [EPG@c0738f] SDEPG Using 133 airings for recommendations Sat 2/24 13:54:19.816 [EPG@c0738f] SDEPG Using 1000 intelligent airings for recommendations Sat 2/24 13:54:19.827 [EPG@c0738f] SDEPG Using 4029 recordings for recommendations Sat 2/24 13:54:19.828 [EPG@c0738f] SDEPG Using 5000 watched shows for recommendations Sat 2/24 13:54:23.583 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:54:23.583 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:54:23.584 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:54:23.584 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:54:30.492 [EPG@c0738f] SDEPG Got 1783 recommendations Sat 2/24 13:54:30.496 [EPG@c0738f] SDEPG Using 2586 watched shows for recommendations Sat 2/24 13:55:00.013 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2129) Sat 2/24 13:55:13.069 [EPG@c0738f] SDEPG Got 215 recommendations Sat 2/24 13:55:13.288 [EPG@c0738f] SDEPG Got 0 usable recommendations, 0 are already editorials Sat 2/24 13:55:13.489 [EPG@c0738f] SDEPG Recommended: [] Sat 2/24 13:55:13.490 [EPG@c0738f] EPG attempting to expand DCT-HDHomeRun Prime Tuner 131154A2-0 on 127.0.0.1:9000 Digital TV Tuner Sat 2/24 13:55:13.490 [EPG@c0738f] expand called on DCT-HDHomeRun Prime Tuner 131154A2-0 on 127.0.0.1:9000 Digital TV Tuner at Sat 2/24 13:55:13.490 expandedUntil=Sun 2/25 3:10:48.174 scannedUntil=Sun 8/17 0:12:55.807 Sat 2/24 13:55:13.490 [EPG@c0738f] Saving properties file to C:\sagetv\SageTV\Sage.properties Sat 2/24 13:55:13.510 [EPG@c0738f] Done writing out the data to the properties file Sat 2/24 13:55:13.510 [EPG@c0738f] sage.EPGDataSource@6a347e needs an update in 2562047788015:12:55 Sat 2/24 13:55:13.510 [EPG@c0738f] sage.epg.sd.SDRipper@e66a85 needs an update in 23:50:17 Sat 2/24 13:55:13.510 [EPG@c0738f] sage.EPGDataSource@1a9a434 needs an update in 13:15:34 Sat 2/24 13:55:13.510 [EPG@c0738f] EPG needs an update in 243 minutes Sat 2/24 13:55:13.511 [EPG@c0738f] EPG's works is done. Waiting... Sat 2/24 13:55:19.624 [Ministry@e75ab3] Ministry is waiting for 180 sec Sat 2/24 13:55:23.583 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:55:23.583 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:55:23.583 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:55:23.583 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:55:44.907 [Fork-OPUS4-108092@ee7fbb] Updating weather Sat 2/24 13:55:44.907 [Fork-BASE-71232@f875] Not time to update weather yet. Sat 2/24 13:56:00.020 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2131) Sat 2/24 13:56:00.030 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2127) Sat 2/24 13:56:23.583 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:56:23.583 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:56:23.583 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:56:23.583 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:56:59.996 [Seeker@1479697] Seeker awoken Sat 2/24 13:56:59.997 [Seeker@1479697] MemStats: Used=771MB Total=1037MB Max=1037MB Sat 2/24 13:57:00.002 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:57:00.002 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] nextTTA=0 Sat 2/24 13:57:00.002 [Seeker@1479697] newRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] Sat 2/24 13:57:00.002 [Seeker@1479697] Change in record to another show. Entering device record mode. - LATER Sat 2/24 13:57:00.002 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:57:00.002 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] nextTTA=0 Sat 2/24 13:57:00.003 [Seeker@1479697] newRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] Sat 2/24 13:57:00.003 [Seeker@1479697] Change in record to another show. Entering device record mode. - LATER Sat 2/24 13:57:00.003 [Seeker@1479697] MARK 1 currRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:57:00.003 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783531,35779496,"2018 Winter Olympics",48639@0224.20:30,210,T] nextTTA=23399998 Sat 2/24 13:57:00.003 [Seeker@1479697] newRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] Sat 2/24 13:57:00.003 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:57:00.003 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:57:00.003 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778145,29804598,"Beat Bobby Flay",50747@0224.14:30,30,T] nextTTA=1799998 Sat 2/24 13:57:00.003 [Seeker@1479697] newRecord=null Sat 2/24 13:57:00.003 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:57:00.004 [Seeker@1479697] MARK 1 currRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:57:00.004 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Sat 2/24 13:57:00.004 [Seeker@1479697] newRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] Sat 2/24 13:57:00.004 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:57:00.004 [Seeker@1479697] MARK 1 currRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:57:00.004 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783530,35779495,"2018 Winter Olympics",48639@0224.17:00,210,T] nextTTA=10799998 Sat 2/24 13:57:00.004 [Seeker@1479697] newRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] Sat 2/24 13:57:00.004 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:57:00.004 [Seeker@1479697] MARK 1 currRecord=null enc=Encoder on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:57:00.005 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783212,35560891,"Witness for the Prosecution",58530@0225.07:10,135,T] nextTTA=61799998 Sat 2/24 13:57:00.005 [Seeker@1479697] newRecord=null Sat 2/24 13:57:00.005 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:57:00.005 [Seeker@1479697] MARK 1 currRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] enc=Hauppauge HD PVR Capture Device clients=[] ir=false Sat 2/24 13:57:00.005 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35780909,35642909,"Torn Curtain",36225@0225.01:24,130,T] nextTTA=41039998 Sat 2/24 13:57:00.005 [Seeker@1479697] newRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] Sat 2/24 13:57:00.005 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:57:00.005 [Seeker@1479697] Change in record to another show. Entering device record mode. - NOW Sat 2/24 13:57:00.006 [Seeker@1479697] Seeker.startRecord(DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T], currTime=Sat 2/24 13:57:00.002) currRecord=null switch=false Sat 2/24 13:57:00.006 [Seeker@1479697] Setting up MMC video for recording new show & tuning channel conn=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 Digital TV Tuner Sat 2/24 13:57:00.006 [Seeker@1479697] Not using desired quality setting of Great-H.264 because the capture device doesn't support it Sat 2/24 13:57:00.006 [Seeker@1479697] Using quality setting "" for recording Sat 2/24 13:57:02.377 [Seeker@1479697] VideoStorage for new file: k:\Videos - Leave Free 10.0 GB Sat 2/24 13:57:02.378 [Seeker@1479697] MediaFile created subfile:k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts MediaFile[id=36057444 A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:00 0 kbps []] Sat 2/24 13:57:02.378 [Seeker@1479697] Added:MediaFile[id=36057444 A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:00 0 kbps [] k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts, Seg0[Sat 2/24 13:57:00.003-Wed 12/31 17:00:00.000]] Sat 2/24 13:57:02.378 [Seeker@1479697] Seeker channel string=772 Sat 2/24 13:57:05.814 [Seeker@1479697] Change in record to another show. Entering device record mode. - NOW Sat 2/24 13:57:05.814 [PluginEventQueue@17833e9] CD: sageEvent: event received = RecordingStarted Sat 2/24 13:57:05.814 [Seeker@1479697] Seeker.startRecord(DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T], currTime=Sat 2/24 13:57:00.002) currRecord=null switch=false Sat 2/24 13:57:05.814 [PluginEventQueue@17833e9] CD: SystemStatus: Number running = 0 Sat 2/24 13:57:05.814 [Seeker@1479697] Setting up MMC video for recording new show & tuning channel conn=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 Digital TV Tuner Sat 2/24 13:57:05.815 [PluginEventQueue@17833e9] CD: SystemStatus: No jobs running. Sat 2/24 13:57:05.815 [Seeker@1479697] Not using desired quality setting of Great-H.264 because the capture device doesn't support it Sat 2/24 13:57:05.815 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: complete. null Sat 2/24 13:57:05.815 [Seeker@1479697] Using quality setting "" for recording Sat 2/24 13:57:05.815 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: found 0 Sat 2/24 13:57:05.815 [PluginEventQueue@17833e9] CD: SystemStatus: No jobs in queue. Sat 2/24 13:57:05.815 [PluginEventQueue@17833e9] CD: skipThisChannel: ChannelName and ChannelNumber NBCSNHD:772 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 750 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 751 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 752 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 753 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 861 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 863 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: isRunningOrQueued: ID for job to check 36057444 Sat 2/24 13:57:05.816 [PluginEventQueue@17833e9] CD: ComskipManager.getIDsForRunningJobs: No jobs running Sat 2/24 13:57:05.817 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: complete. null Sat 2/24 13:57:05.817 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: found 0 Sat 2/24 13:57:05.817 [PluginEventQueue@17833e9] CD: ComskipManager.getIDsForQueuedJobs: No queued jobs. Sat 2/24 13:57:05.817 [PluginEventQueue@17833e9] CD: isRunningOrQueued: MediaFile not running or queued. Sat 2/24 13:57:05.817 [PluginEventQueue@17833e9] CD: sageEvent: Skipping because not set to start immediately. Sat 2/24 13:57:05.818 [PluginEventQueue@17833e9] [[SageTCPServer]]: Event: RecordingStarted Sat 2/24 13:57:05.829 [Timer-15@509ee1] Doing pre-emptive file format detection on recording MediaFile....k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts Sat 2/24 13:57:05.830 [Timer-15@509ee1] Using internal format detector first for: k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts Sat 2/24 13:57:05.833 [Timer-15@509ee1] AV Format:Ret:0 Program:1 Duration:1088572 Format:AV-INF|f=MPEG2-TS;br=3701000;[bf=vid;f=MPEG2-Video;fps=29.970030;fpsn=30000;fpsd=1001;ar=1.777778;arn=16;ard=9;w=1920;h=1080;lace=1;cs=yuv420p;main=yes;tag=0100;pid=256;index=0;];[bf=aud;f=AC3;sr=48000;ch=6;br=384000;main=yes;lang=eng;tag=0101;pid=257;index=1;];[bf=aud;f=AC3;sr=48000;ch=2;br=192000;lang=spa;tag=0102;pid=258;index=2;];(0:0:01.733'566.6) Sat 2/24 13:57:05.833 [Timer-15@509ee1] File Format Parsed-2a k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] Sat 2/24 13:57:05.834 [Timer-15@509ee1] File Format Parsed-2b k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] Sat 2/24 13:57:06.043 [Seeker@1479697] VideoStorage for new file: k:\Videos - Leave Free 10.0 GB Sat 2/24 13:57:06.044 [Seeker@1479697] MediaFile created subfile:k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts MediaFile[id=36057445 A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:00:00 0 kbps []] Sat 2/24 13:57:06.044 [Seeker@1479697] Added:MediaFile[id=36057445 A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:00:00 0 kbps [] k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts, Seg0[Sat 2/24 13:57:00.003-Wed 12/31 17:00:00.000]] Sat 2/24 13:57:06.045 [Seeker@1479697] Seeker channel string=766 Sat 2/24 13:57:20.336 [PluginEventQueue@17833e9] CD: sageEvent: event received = RecordingStarted Sat 2/24 13:57:20.336 [PluginEventQueue@17833e9] CD: SystemStatus: Number running = 0 Sat 2/24 13:57:20.336 [PluginEventQueue@17833e9] CD: SystemStatus: No jobs running. Sat 2/24 13:57:20.336 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: complete. null Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: found 0 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: SystemStatus: No jobs in queue. Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: skipThisChannel: ChannelName and ChannelNumber FOODHD:766 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 750 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 751 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 752 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 753 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 861 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: skipThisChannel: Skip = 863 Sat 2/24 13:57:20.337 [PluginEventQueue@17833e9] CD: isRunningOrQueued: ID for job to check 36057445 Sat 2/24 13:57:20.338 [PluginEventQueue@17833e9] CD: ComskipManager.getIDsForRunningJobs: No jobs running Sat 2/24 13:57:20.338 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: complete. null Sat 2/24 13:57:20.338 [PluginEventQueue@17833e9] CD: ComskipManager.readQueuedJobs: found 0 Sat 2/24 13:57:20.338 [PluginEventQueue@17833e9] CD: ComskipManager.getIDsForQueuedJobs: No queued jobs. Sat 2/24 13:57:20.338 [PluginEventQueue@17833e9] CD: isRunningOrQueued: MediaFile not running or queued. Sat 2/24 13:57:20.338 [PluginEventQueue@17833e9] CD: sageEvent: Skipping because not set to start immediately. Sat 2/24 13:57:20.339 [PluginEventQueue@17833e9] [[SageTCPServer]]: Event: RecordingStarted Sat 2/24 13:57:21.336 [Seeker@1479697] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:21.336 [Seeker@1479697] RootFile=k:\ fstype=NTFS fileLength=0 file=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts Sat 2/24 13:57:22.348 [Timer-15@509ee1] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:22.770 [ThreadMonitor@c0e9db] Top 10 Cumulative CPU(sec) PooledThread:447 9654489@qtp-18994252-5:238 VideoFrame-20cf30f40378:197 PooledThread:162 Scheduler:133 ClientListenerParallelizer:124 Seeker:81 KeepAlive:38 ReProcessHook:23 ReProcessHook:13 Sat 2/24 13:57:22.770 [ThreadMonitor@c0e9db] Top 10 %CPU (last 300s) ReProcessHook:3% Sat 2/24 13:57:23.581 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:57:23.582 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:57:23.582 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:57:23.582 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:57:24.348 [Seeker@1479697] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:24.348 [Seeker@1479697] RootFile=k:\ fstype=NTFS fileLength=0 file=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts Sat 2/24 13:57:26.360 [Seeker@1479697] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:28.370 [Seeker@1479697] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:29.371 [Seeker@1479697] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:29.371 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=0 file=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts Sat 2/24 13:57:29.371 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=10047406080 file=h:\videos\BadDayatBlackRock-35777317-0.ts Sat 2/24 13:57:30.557 [FSManager@ac6c1c] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:31.422 [Seeker@1479697] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:31.422 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=0 file=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts Sat 2/24 13:57:32.567 [FSManager@ac6c1c] Error communicating with encoding server:java.lang.NumberFormatException: For input string: "ERROR failed request" Sat 2/24 13:57:32.944 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=843291484 file=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts Sat 2/24 13:57:32.945 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:57:32.945 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:57:32.945 [AsyncPropSaver@6ad5b3] Saving properties file to C:\sagetv\SageTV\Sage.properties Sat 2/24 13:57:32.945 [Seeker@1479697] Checking video directories for new files Sat 2/24 13:57:32.946 [ReProcessHook@f875] CD STV:NumberRunning = 0 Sat 2/24 13:57:32.952 [Seeker@1479697] Diskspace checking is running Sat 2/24 13:57:32.952 [Seeker@1479697] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Sat 2/24 13:57:32.955 [ReProcessHook@f875] CD STV:NumberRunning = 0 Sat 2/24 13:57:32.972 [AsyncPropSaver@6ad5b3] Done writing out the data to the properties file Sat 2/24 13:57:33.863 [Seeker@1479697] Enforcing keep at most limits for the files... Sat 2/24 13:57:33.879 [Seeker@1479697] DONE enforcing keep at most limits for the files. Sat 2/24 13:57:33.880 [Seeker@1479697] Seeker clearing unwanted and partial files... Sat 2/24 13:57:33.917 [Seeker@1479697] DONE clearing unwanted and partial files. Sat 2/24 13:57:34.442 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3038.612487168 GB Sat 2/24 13:57:34.442 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3044.632779776 GB Sat 2/24 13:57:34.443 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=6093.245266944 GB Sat 2/24 13:57:34.443 [Seeker@1479697] Seeker waiting for 5 mins. Sat 2/24 13:58:00.022 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2170) Sat 2/24 13:58:00.022 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2171) Sat 2/24 13:58:00.023 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2172) Sat 2/24 13:58:00.034 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2162) Sat 2/24 13:58:19.622 [Ministry@e75ab3] Ministry is waiting for 180 sec Sat 2/24 13:58:23.581 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:58:23.581 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:58:23.582 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:58:23.582 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:58:33.092 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36057445 A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:00:00 0 kbps [{SREv4_Monitored=false}] k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts, Seg0[Sat 2/24 13:57:00.003-Wed 12/31 17:00:00.000]] size=0 Sat 2/24 13:58:33.093 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36044891 A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts, Seg0[Sat 2/24 11:27:00.004-Wed 12/31 17:00:00.000]] size=17339564112 Sat 2/24 13:58:33.093 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36044894 A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 KOAADT format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts, Seg0[Sat 2/24 12:57:00.003-Wed 12/31 17:00:00.000]] size=5551281108 Sat 2/24 13:58:33.094 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36044895 A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:05:14 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101]{SREv4_Monitored=false}] e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts, Seg0[Sat 2/24 13:27:00.002-Wed 12/31 17:00:00.000]] size=843291484 Sat 2/24 13:58:33.095 [Seeker@1479697] Seeker awoken Sat 2/24 13:58:33.095 [Seeker@1479697] MemStats: Used=804MB Total=1037MB Max=1037MB Sat 2/24 13:58:33.099 [Seeker@1479697] MARK 1 currRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:58:33.099 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35782624,35779237,"2018 Winter Olympics",51690@0224.18:00,180,T] nextTTA=14306901 Sat 2/24 13:58:33.099 [Seeker@1479697] newRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] Sat 2/24 13:58:33.099 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:58:33.099 [Seeker@1479697] MARK 1 currRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:58:33.100 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778146,29909219,"Beat Bobby Flay",50747@0224.15:00,30,T] nextTTA=3506901 Sat 2/24 13:58:33.100 [Seeker@1479697] newRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] Sat 2/24 13:58:33.100 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:58:33.100 [Seeker@1479697] MARK 1 currRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:58:33.100 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783531,35779496,"2018 Winter Olympics",48639@0224.20:30,210,T] nextTTA=23306901 Sat 2/24 13:58:33.100 [Seeker@1479697] newRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] Sat 2/24 13:58:33.100 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:58:33.100 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:58:33.100 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778145,29804598,"Beat Bobby Flay",50747@0224.14:30,30,T] nextTTA=1706901 Sat 2/24 13:58:33.100 [Seeker@1479697] newRecord=null Sat 2/24 13:58:33.101 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:58:33.101 [Seeker@1479697] MARK 1 currRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:58:33.101 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Sat 2/24 13:58:33.101 [Seeker@1479697] newRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] Sat 2/24 13:58:33.101 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:58:33.101 [Seeker@1479697] MARK 1 currRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:58:33.101 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783530,35779495,"2018 Winter Olympics",48639@0224.17:00,210,T] nextTTA=10706901 Sat 2/24 13:58:33.101 [Seeker@1479697] newRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] Sat 2/24 13:58:33.101 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:58:33.101 [Seeker@1479697] MARK 1 currRecord=null enc=Encoder on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:58:33.102 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783212,35560891,"Witness for the Prosecution",58530@0225.07:10,135,T] nextTTA=61706901 Sat 2/24 13:58:33.102 [Seeker@1479697] newRecord=null Sat 2/24 13:58:33.102 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:58:33.102 [Seeker@1479697] MARK 1 currRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] enc=Hauppauge HD PVR Capture Device clients=[] ir=false Sat 2/24 13:58:33.102 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35780909,35642909,"Torn Curtain",36225@0225.01:24,130,T] nextTTA=40946901 Sat 2/24 13:58:33.102 [Seeker@1479697] newRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] Sat 2/24 13:58:33.102 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:58:33.103 [Seeker@1479697] RootFile=k:\ fstype=NTFS fileLength=29699300 file=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts Sat 2/24 13:58:33.104 [Seeker@1479697] RootFile=k:\ fstype=NTFS fileLength=0 file=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts Sat 2/24 13:58:33.105 [Seeker@1479697] SEEKER HAS DETECTED A HALT IN THE ENCODER...TRYING TO RESET IT file=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts size=0 Sat 2/24 13:58:33.105 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts]) Sat 2/24 13:58:33.105 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts]) Sat 2/24 13:58:33.105 [VideoFrame-20cf30f40378@7a0d91] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts] nPlayin=false Sat 2/24 13:58:33.106 [VideoFrame-20cf30f40378@7a0d91] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts] Sat 2/24 13:58:33.106 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:58:33.106 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:58:34.110 [Seeker@1479697] MediaFile failing verify testFile=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts isFile=true len=0 isTV=true isRecording=false Sat 2/24 13:58:34.110 [Seeker@1479697] MediaFile startSegment enc=null MediaFile[id=36057445 A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:00:00 0 kbps [{SREv4_Monitored=false}] k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts, Seg0[Sat 2/24 13:57:00.003-Sat 2/24 13:58:34.109]] Sat 2/24 13:58:34.111 [Seeker@1479697] MediaFile created subfile:k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-1.ts MediaFile[id=36057445 A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:00:00 0 kbps [{SREv4_Monitored=false}] k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts, Seg0[Sat 2/24 13:57:00.003-Sat 2/24 13:58:34.109]] Sat 2/24 13:58:48.095 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36057444 A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts, Seg0[Sat 2/24 13:57:00.003-Wed 12/31 17:00:00.000]] size=29699300 Sat 2/24 13:58:48.095 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36057445 A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:00:00 0 kbps [{SREv4_Monitored=false}] k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts, Seg0[Sat 2/24 13:57:00.003-Sat 2/24 13:58:34.109], Seg1[Sat 2/24 13:58:34.110-Wed 12/31 17:00:00.000]] size=0 Sat 2/24 13:58:48.096 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36044891 A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts, Seg0[Sat 2/24 11:27:00.004-Wed 12/31 17:00:00.000]] size=17339564112 Sat 2/24 13:58:48.096 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36044894 A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 KOAADT format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts, Seg0[Sat 2/24 12:57:00.003-Wed 12/31 17:00:00.000]] size=5551281108 Sat 2/24 13:58:48.096 [FSManager@ac6c1c] Async encoder halt detector found a halt; kick the Seeker so it can restart the device file=MediaFile[id=36044895 A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:05:14 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101]{SREv4_Monitored=false}] e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts, Seg0[Sat 2/24 13:27:00.002-Wed 12/31 17:00:00.000]] size=843291484 Sat 2/24 13:58:49.662 [PluginEventQueue@17833e9] [[SageTCPServer]]: Event: SystemMessagePosted Sat 2/24 13:58:49.663 [DistributeHookNetClients@1d6182d] Sending hook to client for SystemStatusChanged args=[] Sat 2/24 13:58:49.663 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=17339564112 file=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts Sat 2/24 13:58:49.663 [Seeker@1479697] SEEKER HAS DETECTED A HALT IN THE ENCODER...TRYING TO RESET IT file=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts size=17339564112 Sat 2/24 13:58:49.663 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts]) Sat 2/24 13:58:49.663 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts]) Sat 2/24 13:58:49.664 [VideoFrame-20cf30f40378@7a0d91] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts] nPlayin=false Sat 2/24 13:58:49.664 [VideoFrame-20cf30f40378@7a0d91] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts] Sat 2/24 13:58:49.664 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:58:49.664 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:58:49.664 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:58:49.669 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:58:49.684 [FinalRender-20cf30f40378@2592e0] Loading RawImage of size 47x46 for C:\sagetv\SageTV\STVs\SageTV7\Themes\Standard\MarkerSysAlert2.png fileSize=2222 Sat 2/24 13:58:49.684 [FinalRender-20cf30f40378@2592e0] Loading 0x0 image from file C:\sagetv\SageTV\STVs\SageTV7\Themes\Standard\MarkerSysAlert2.png Sat 2/24 13:58:49.693 [FinalRender-20cf30f40378@2592e0] Allocated image-5 for C:\sagetv\SageTV\STVs\SageTV7\Themes\Standard\MarkerSysAlert2.png width=47 height=46 ptr=2331 Sat 2/24 13:58:50.247 [Timer-23@169be3] Screen Saver Timeout expired....tv=false playin=false fs=false sleepy=false currSS=false fsVideo=false Sat 2/24 13:58:50.665 [Seeker@1479697] MediaFile startSegment enc=null MediaFile[id=36044891 A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts, Seg0[Sat 2/24 11:27:00.004-Sat 2/24 13:58:50.665]] Sat 2/24 13:58:50.691 [Seeker@1479697] MediaFile created subfile:e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-1.ts MediaFile[id=36044891 A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-0.ts, Seg0[Sat 2/24 11:27:00.004-Sat 2/24 13:58:50.665]] Sat 2/24 13:58:53.995 [DistributeHookNetClients@6f4b86] Sending hook to client for SystemStatusChanged args=[] Sat 2/24 13:58:53.996 [PluginEventQueue@17833e9] [[SageTCPServer]]: Event: SystemMessagePosted Sat 2/24 13:58:53.996 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=10181951488 file=h:\videos\BadDayatBlackRock-35777317-0.ts Sat 2/24 13:58:53.996 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:58:53.996 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=5551281108 file=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts Sat 2/24 13:58:53.996 [Seeker@1479697] SEEKER HAS DETECTED A HALT IN THE ENCODER...TRYING TO RESET IT file=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts size=5551281108 Sat 2/24 13:58:53.997 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts]) Sat 2/24 13:58:53.997 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts]) Sat 2/24 13:58:53.997 [VideoFrame-20cf30f40378@7a0d91] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts] nPlayin=false Sat 2/24 13:58:53.998 [VideoFrame-20cf30f40378@7a0d91] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts] Sat 2/24 13:58:53.998 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:58:53.998 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:58:54.001 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:58:54.998 [Seeker@1479697] MediaFile startSegment enc=null MediaFile[id=36044894 A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 KOAADT format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts, Seg0[Sat 2/24 12:57:00.003-Sat 2/24 13:58:54.998]] Sat 2/24 13:58:54.999 [Seeker@1479697] MediaFile created subfile:h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-1.ts MediaFile[id=36044894 A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 KOAADT format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-0.ts, Seg0[Sat 2/24 12:57:00.003-Sat 2/24 13:58:54.998]] Sat 2/24 13:58:58.459 [PluginEventQueue@17833e9] [[SageTCPServer]]: Event: SystemMessagePosted Sat 2/24 13:58:58.459 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=843291484 file=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts Sat 2/24 13:58:58.459 [Seeker@1479697] SEEKER HAS DETECTED A HALT IN THE ENCODER...TRYING TO RESET IT file=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts size=843291484 Sat 2/24 13:58:58.460 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts]) Sat 2/24 13:58:58.460 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts]) Sat 2/24 13:58:58.460 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:58:58.460 [VideoFrame-20cf30f40378@7a0d91] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts] nPlayin=false Sat 2/24 13:58:58.461 [VideoFrame-20cf30f40378@7a0d91] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts] Sat 2/24 13:58:58.461 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:58:58.461 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:58:58.461 [DistributeHookNetClients@1d6182d] Sending hook to client for SystemStatusChanged args=[] Sat 2/24 13:58:58.465 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:58:59.462 [Seeker@1479697] MediaFile startSegment enc=null MediaFile[id=36044895 A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:05:14 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101]{SREv4_Monitored=false}] e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts, Seg0[Sat 2/24 13:27:00.002-Sat 2/24 13:58:59.462]] Sat 2/24 13:58:59.463 [Seeker@1479697] MediaFile created subfile:e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-1.ts MediaFile[id=36044895 A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 FOODHD format=MPEG2-TS 0:05:14 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101]{SREv4_Monitored=false}] e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-0.ts, Seg0[Sat 2/24 13:27:00.002-Sat 2/24 13:58:59.462]] Sat 2/24 13:59:00.015 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2163) Sat 2/24 13:59:00.015 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2173) Sat 2/24 13:59:14.918 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:59:14.918 [DistributeHookNetClients@6f4b86] Sending hook to client for SystemStatusChanged args=[] Sat 2/24 13:59:14.918 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:59:14.919 [Seeker@1479697] Checking video directories for new files Sat 2/24 13:59:14.919 [AsyncPropSaver@8de82d] Saving properties file to C:\sagetv\SageTV\Sage.properties Sat 2/24 13:59:14.920 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:59:14.926 [Seeker@1479697] Diskspace checking is running Sat 2/24 13:59:14.926 [Seeker@1479697] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Sat 2/24 13:59:14.929 [ReProcessHook@1b7ddd7] CD STV:NumberRunning = 0 Sat 2/24 13:59:14.937 [PluginEventQueue@17833e9] [[SageTCPServer]]: Event: SystemMessagePosted Sat 2/24 13:59:14.939 [ReProcessHook@6ad5b3] CD STV:NumberRunning = 0 Sat 2/24 13:59:14.943 [ReProcessHook@6ad5b3] CD STV:NumberRunning = 0 Sat 2/24 13:59:14.948 [AsyncPropSaver@8de82d] Done writing out the data to the properties file Sat 2/24 13:59:15.860 [Seeker@1479697] Enforcing keep at most limits for the files... Sat 2/24 13:59:15.877 [Seeker@1479697] DONE enforcing keep at most limits for the files. Sat 2/24 13:59:15.877 [Seeker@1479697] Seeker clearing unwanted and partial files... Sat 2/24 13:59:15.919 [Seeker@1479697] DONE clearing unwanted and partial files. Sat 2/24 13:59:16.450 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3038.565366784 GB Sat 2/24 13:59:16.451 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3044.420312064 GB Sat 2/24 13:59:16.451 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=6092.985678848 GB Sat 2/24 13:59:16.452 [Seeker@1479697] Seeker waiting for 3 mins. Sat 2/24 13:59:16.453 [Seeker@1479697] Seeker awoken Sat 2/24 13:59:16.453 [Seeker@1479697] MemStats: Used=829MB Total=1037MB Max=1037MB Sat 2/24 13:59:16.459 [Seeker@1479697] MARK 1 currRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:59:16.459 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35782624,35779237,"2018 Winter Olympics",51690@0224.18:00,180,T] nextTTA=14263541 Sat 2/24 13:59:16.459 [Seeker@1479697] newRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] Sat 2/24 13:59:16.460 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:59:16.460 [Seeker@1479697] MARK 1 currRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:59:16.460 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778146,29909219,"Beat Bobby Flay",50747@0224.15:00,30,T] nextTTA=3463541 Sat 2/24 13:59:16.460 [Seeker@1479697] newRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] Sat 2/24 13:59:16.460 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:59:16.460 [Seeker@1479697] MARK 1 currRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:59:16.460 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783531,35779496,"2018 Winter Olympics",48639@0224.20:30,210,T] nextTTA=23263541 Sat 2/24 13:59:16.460 [Seeker@1479697] newRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] Sat 2/24 13:59:16.460 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:59:16.460 [Seeker@1479697] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 131154A2-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:59:16.461 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778145,29804598,"Beat Bobby Flay",50747@0224.14:30,30,T] nextTTA=1663541 Sat 2/24 13:59:16.461 [Seeker@1479697] newRecord=null Sat 2/24 13:59:16.461 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:59:16.461 [Seeker@1479697] MARK 1 currRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:59:16.461 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Sat 2/24 13:59:16.461 [Seeker@1479697] newRecord=A[35776125,35773433,"2018 Winter Olympics",51690@0224.13:00,180,T] Sat 2/24 13:59:16.461 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:59:16.461 [Seeker@1479697] MARK 1 currRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-2 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:59:16.462 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783530,35779495,"2018 Winter Olympics",48639@0224.17:00,210,T] nextTTA=10663541 Sat 2/24 13:59:16.462 [Seeker@1479697] newRecord=A[35778143,34786914,"Beat Bobby Flay",50747@0224.13:30,30,T] Sat 2/24 13:59:16.462 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:59:16.462 [Seeker@1479697] MARK 1 currRecord=null enc=Encoder on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 13:59:16.462 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783212,35560891,"Witness for the Prosecution",58530@0225.07:10,135,T] nextTTA=61663541 Sat 2/24 13:59:16.462 [Seeker@1479697] newRecord=null Sat 2/24 13:59:16.462 [Seeker@1479697] NOTHING TO RECORD FOR NOW... Sat 2/24 13:59:16.462 [Seeker@1479697] MARK 1 currRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] enc=Hauppauge HD PVR Capture Device clients=[] ir=false Sat 2/24 13:59:16.462 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35780909,35642909,"Torn Curtain",36225@0225.01:24,130,T] nextTTA=40903541 Sat 2/24 13:59:16.463 [Seeker@1479697] newRecord=A[35777317,35773847,"Bad Day at Black Rock",64312@0224.12:15,105,T] Sat 2/24 13:59:16.463 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 13:59:16.464 [Seeker@1479697] RootFile=k:\ fstype=NTFS fileLength=29699300 file=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts Sat 2/24 13:59:16.464 [Seeker@1479697] SEEKER HAS DETECTED A HALT IN THE ENCODER...TRYING TO RESET IT file=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts size=29699300 Sat 2/24 13:59:16.464 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts]) Sat 2/24 13:59:16.464 [Seeker@1479697] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts]) Sat 2/24 13:59:16.465 [VideoFrame-20cf30f40378@7a0d91] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts] nPlayin=false Sat 2/24 13:59:16.465 [VideoFrame-20cf30f40378@7a0d91] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts] Sat 2/24 13:59:16.465 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:59:16.465 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:59:17.468 [Seeker@1479697] MediaFile startSegment enc=null MediaFile[id=36057444 A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts, Seg0[Sat 2/24 13:57:00.003-Sat 2/24 13:59:17.466]] Sat 2/24 13:59:17.469 [Seeker@1479697] MediaFile created subfile:k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-1.ts MediaFile[id=36057444 A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] mask=TV host=sagetv1 encodedBy=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 NBCSNHD format=MPEG2-TS 0:00:01 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]{SREv4_Monitored=false}] k:\Videos\2018WinterOlympics-MensHockeyOlympicIce-35776925-0.ts, Seg0[Sat 2/24 13:57:00.003-Sat 2/24 13:59:17.466]] Sat 2/24 13:59:20.869 [DistributeHookNetClients@f875] Sending hook to client for SystemStatusChanged args=[] Sat 2/24 13:59:20.869 [Seeker@1479697] RootFile=k:\ fstype=NTFS fileLength=20198344 file=k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-1.ts Sat 2/24 13:59:20.870 [Seeker@1479697] Doing pre-emptive file format detection on recording MediaFile....k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts Sat 2/24 13:59:20.870 [ReProcessHook@6ad5b3] CD STV:NumberRunning = 0 Sat 2/24 13:59:20.870 [PluginEventQueue@17833e9] [[SageTCPServer]]: Event: SystemMessagePosted Sat 2/24 13:59:20.871 [Seeker@1479697] Returning null format for zero-length file Sat 2/24 13:59:20.871 [Seeker@1479697] Doing pre-emptive file format detection on recording MediaFile....k:\Videos\BeatBobbyFlay-S10E13-LikeFatherLikeDaughter-35778144-0.ts Sat 2/24 13:59:20.872 [Seeker@1479697] Returning null format for zero-length file Sat 2/24 13:59:20.872 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=55708348 file=e:\videos\2018WinterOlympics-WomensCurlingGreatBritainvsJapan-36026478-1.ts Sat 2/24 13:59:20.873 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=10224975872 file=h:\videos\BadDayatBlackRock-35777317-0.ts Sat 2/24 13:59:20.873 [Seeker@1479697] RootFile=h:\ fstype=NTFS fileLength=33412676 file=h:\videos\2018WinterOlympics-SpeedSkatingMensCrossCountrySkiing-35776125-1.ts Sat 2/24 13:59:20.873 [Seeker@1479697] RootFile=e:\ fstype=NTFS fileLength=0 file=e:\videos\BeatBobbyFlay-S13E13-WereAllFamilyHere-35778143-1.ts Sat 2/24 13:59:20.873 [VideoFrame-20cf30f40378@7a0d91] VF processing job null nPlayin=false Sat 2/24 13:59:20.874 [VideoFrame-20cf30f40378@7a0d91] VF thread is now waiting for 0:00:00.000 Sat 2/24 13:59:20.874 [Seeker@1479697] Checking video directories for new files Sat 2/24 13:59:20.875 [ReProcessHook@6ad5b3] CD STV:NumberRunning = 0 Sat 2/24 13:59:20.875 [Seeker@1479697] Diskspace checking is running Sat 2/24 13:59:20.875 [Seeker@1479697] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Sat 2/24 13:59:20.884 [ReProcessHook@8de82d] CD STV:NumberRunning = 0 Sat 2/24 13:59:20.889 [ReProcessHook@8de82d] CD STV:NumberRunning = 0 Sat 2/24 13:59:21.088 [Seeker@1479697] Enforcing keep at most limits for the files... Sat 2/24 13:59:21.106 [Seeker@1479697] DONE enforcing keep at most limits for the files. Sat 2/24 13:59:21.106 [Seeker@1479697] Seeker clearing unwanted and partial files... Sat 2/24 13:59:21.145 [Seeker@1479697] DONE clearing unwanted and partial files. Sat 2/24 13:59:21.672 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3038.553242624 GB Sat 2/24 13:59:21.672 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=3044.393311232 GB Sat 2/24 13:59:21.673 [Seeker@1479697] needFreeSize = 4.05 GB currFreeSize=6092.946553856 GB Sat 2/24 13:59:21.673 [Seeker@1479697] Seeker waiting for 3 mins. Sat 2/24 13:59:23.580 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 13:59:23.580 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 13:59:23.580 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 13:59:23.580 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 13:59:30.604 [Timer-12@d65daf] YT: FavoriteManager: Looking for new Favorites. Sat 2/24 13:59:30.605 [Timer-12@d65daf] YT: FavoriteManager: Number of subscribed channels after removing duplicates 0 Sat 2/24 13:59:30.605 [Timer-12@d65daf] YT: FavoriteManager: No favorites defined. Exiting. Sat 2/24 14:00:00.024 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2174) Sat 2/24 14:00:00.024 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2175) Sat 2/24 14:00:00.024 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2176) Sat 2/24 14:00:00.035 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2177) Sat 2/24 14:00:00.035 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2178) Sat 2/24 14:00:23.579 [Timer-1@1ccdd42] CD: RestartRestricted: Checking. Sat 2/24 14:00:23.579 [Timer-1@1ccdd42] CD: ComskipManager.inRestrictedTime: No Restricted times. Sat 2/24 14:00:23.579 [Timer-1@1ccdd42] CD: RestartRestricted: Restarting. Sat 2/24 14:00:23.579 [Timer-1@1ccdd42] CD: ComskipManager.startMaxJobs: Restarting queued jobs. Sat 2/24 14:00:23.580 [Timer-1@1ccdd42] CD: ComskipManager.readQueuedJobs: complete. null Sat 2/24 14:00:23.580 [Timer-1@1ccdd42] CD: ComskipManager.readQueuedJobs: found 0 Sat 2/24 14:00:23.580 [Timer-1@1ccdd42] CD: ComskipManager.startFirstInQueue: No jobs queued. Sat 2/24 14:00:23.580 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 14:00:23.580 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 14:00:23.580 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 14:00:23.580 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 14:00:44.932 [Fork-OPUS4-108092@ee7fbb] Updating weather Sat 2/24 14:00:44.932 [Fork-BASE-71232@6ad5b3] Not time to update weather yet. Sat 2/24 14:01:00.014 [FinalRender-20cf30f40378@2592e0] unloadImageMini(2192) Sat 2/24 14:01:19.620 [Ministry@e75ab3] Ministry is waiting for 180 sec Sat 2/24 14:01:23.579 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 14:01:23.579 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 14:01:23.579 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 14:01:23.579 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 14:02:22.768 [ThreadMonitor@c0e9db] Top 10 Cumulative CPU(sec) ReProcessHook:447 9654489@qtp-18994252-5:238 VideoFrame-20cf30f40378:197 PooledThread:170 Scheduler:133 ClientListenerParallelizer:124 Seeker:84 KeepAlive:38 DistributeHookNetClients:25 PooledThread:13 Sat 2/24 14:02:22.768 [ThreadMonitor@c0e9db] Top 10 %CPU (last 300s) PooledThread:2% Sat 2/24 14:02:23.578 [Timer-0@5a08b] CD: MonitorClient: Looking for work. Sat 2/24 14:02:23.579 [Timer-0@5a08b] CD: CSC.getFirstStatus: item before remove null Sat 2/24 14:02:23.579 [Timer-0@5a08b] CD: CSC.getFirstStatus: null Status. Sat 2/24 14:02:23.579 [Timer-0@5a08b] CD: MonitorClient: Finished. Sat 2/24 14:02:59.998 [Seeker@1479697] Seeker awoken Sat 2/24 14:02:59.998 [Seeker@1479697] MemStats: Used=908MB Total=1037MB Max=1037MB Sat 2/24 14:03:00.002 [Seeker@1479697] MARK 1 currRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 14:03:00.003 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35782624,35779237,"2018 Winter Olympics",51690@0224.18:00,180,T] nextTTA=14039998 Sat 2/24 14:03:00.003 [Seeker@1479697] newRecord=A[35776925,35773634,"2018 Winter Olympics",48639@0224.14:00,180,T] Sat 2/24 14:03:00.003 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 14:03:00.003 [Seeker@1479697] MARK 1 currRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] enc=DCT-HDHomeRun Prime Tuner 131154A2-1 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 14:03:00.003 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35778146,29909219,"Beat Bobby Flay",50747@0224.15:00,30,T] nextTTA=3239998 Sat 2/24 14:03:00.003 [Seeker@1479697] newRecord=A[35778144,30117066,"Beat Bobby Flay",50747@0224.14:00,30,T] Sat 2/24 14:03:00.003 [Seeker@1479697] Keeping record just the way it is. Sat 2/24 14:03:00.004 [Seeker@1479697] MARK 1 currRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] enc=DCT-HDHomeRun Prime Tuner 1321411B-0 on 127.0.0.1:9000 clients=[] ir=false Sat 2/24 14:03:00.004 [Seeker@1479697] Current record is over. Sat 2/24 14:03:00.004 [Seeker@1479697] Seeker.endRecord(Sat 2/24 14:03:00.002) currRecord=A[36026478,35773632,"2018 Winter Olympics",48639@0224.11:30,150,T] readySwitch=true Sat 2/24 14:03:00.004 [Seeker@1479697] Seeker in AUTOMATIC mode nextRecord=A[35783531,35779496,"2018 Winter Olympics",48639@0224.20:30,210,T] nextTTA=23039998 Sat 2/24 14:03:00.004 [Seeker@1479697] newRecord=null Sat 2/24 14:03:00.004 [Seeker@1479697] NOTHING TO RECORD FOR NOW...