Wed 7/16 17:30:11.961 user.dir2=C:\Program Files\Frey Technologies\SageTV Wed 7/16 17:30:13.336 hostname=pvr Wed 7/16 17:30:13.789 SageTV V1.4.5 Beta Wed 7/16 17:30:16.352 Wizard starting to load database info... Wed 7/16 17:30:16.367 DBFile at version 39 FileSize=9070970 Wed 7/16 17:30:16.367 Wizard allocating table for Network of size 0 Wed 7/16 17:30:16.367 Wizard allocating table for Channel of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for Title of size 1 Wed 7/16 17:30:16.383 Wizard loading main index for Title Wed 7/16 17:30:16.383 Wizard building alt. index 0 for Title Wed 7/16 17:30:16.383 Wizard allocating table for People of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for Category of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for SubCategory of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for Rated of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for ParentalRating of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for ExtendedRating of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for Year of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for PrimeTitle of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for Bonus of size 0 Wed 7/16 17:30:16.383 Wizard allocating table for Show of size 1 Wed 7/16 17:30:16.383 Wizard loading main index for Show Wed 7/16 17:30:16.383 Wizard building alt. index 0 for Show Wed 7/16 17:30:16.414 Wizard allocating table for Airing of size 0 Wed 7/16 17:30:16.414 Wizard allocating table for ManualRecord of size 0 Wed 7/16 17:30:16.414 Wizard allocating table for MediaFile of size 0 Wed 7/16 17:30:16.414 Wizard allocating table for Watched of size 0 Wed 7/16 17:30:16.414 Wizard allocating table for Agent of size 0 Wed 7/16 17:30:16.414 Wizard allocating table for Wasted of size 0 Wed 7/16 17:30:16.414 Wizard allocating table for Widget of size 0 Wed 7/16 17:30:16.523 Wizard processing transactional records... Wed 7/16 17:30:53.320 DBFile PREMATURELY ended, but still using it. Error:java.io.IOException: BOGUS command length, ending file load now. req=2156554580 atual=9070970 java.io.IOException: BOGUS command length, ending file load now. req=2156554580 atual=9070970 Wed 7/16 17:30:53.336 at sage.bb.gO(Unknown Source) Wed 7/16 17:30:53.336 at sage.bb.N(Unknown Source) Wed 7/16 17:30:53.336 at sage.bb.a(Unknown Source) Wed 7/16 17:30:53.336 at sage.bb.gz(Unknown Source) Wed 7/16 17:30:53.336 at sage.SageTV.(Unknown Source) Wed 7/16 17:30:53.336 at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) Wed 7/16 17:30:53.336 at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) Wed 7/16 17:30:53.336 at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) Wed 7/16 17:30:53.336 at java.lang.reflect.Constructor.newInstance(Unknown Source) Wed 7/16 17:30:53.336 at java.lang.Class.newInstance0(Unknown Source) Wed 7/16 17:30:53.336 at java.lang.Class.newInstance(Unknown Source) Wed 7/16 17:30:53.336 at sage.Sage.main2(Unknown Source) Wed 7/16 17:30:53.352 Wizard DONE loading database info. loadTime=37.0 sec Wed 7/16 17:30:53.352 Wizard backing up database file... Wed 7/16 17:30:53.461 Wizard compressing new file... Wed 7/16 17:30:53.477 Wizard writing out table info for Network Wed 7/16 17:30:53.477 Wizard writing out table info for Channel Wed 7/16 17:30:53.508 Wizard writing out table info for Title Wed 7/16 17:30:53.555 Wizard writing out table info for People Wed 7/16 17:30:53.633 Wizard writing out table info for Category Wed 7/16 17:30:53.633 Wizard writing out table info for SubCategory Wed 7/16 17:30:53.648 Wizard writing out table info for Rated Wed 7/16 17:30:53.648 Wizard writing out table info for ParentalRating Wed 7/16 17:30:53.648 Wizard writing out table info for ExtendedRating Wed 7/16 17:30:53.648 Wizard writing out table info for Year Wed 7/16 17:30:53.648 Wizard writing out table info for PrimeTitle Wed 7/16 17:30:53.648 Wizard writing out table info for Bonus Wed 7/16 17:30:53.648 Wizard writing out table info for Show Wed 7/16 17:30:53.930 Wizard writing out table info for Airing Wed 7/16 17:30:54.070 Wizard writing out table info for ManualRecord Wed 7/16 17:30:54.086 Wizard writing out table info for MediaFile Wed 7/16 17:30:54.086 Wizard writing out table info for Watched Wed 7/16 17:30:54.086 Wizard writing out table info for Agent Wed 7/16 17:30:54.086 Wizard writing out table info for Wasted Wed 7/16 17:30:54.086 Wizard writing out table info for Widget Wed 7/16 17:30:57.727 Wizard DONE saving database info. Wed 7/16 17:30:58.102 DShowFilter=[.RAM file Parser, AC3 Parser Filter, ACELP.net Sipro Lab Audio Decoder, ACM Wrapper, ASF ACM Handler, ASF DIB Handler, ASF DJPEG Handler, ASF embedded stuff Handler, ASF ICM Handler, ASF JPEG Handler, ASF URL Handler, ASX file Parser, ASX v.2 file Parser, AVI Decompressor, AVI Draw, AVI Mux, AVI Splitter, AVI/WAV File Source, BDA MPEG2 Transport Information Filter, Color Space Converter, Deinterlace Filter, DV Muxer, DV Splitter, DV Video Decoder, DVD Navigator, File Source (Async.), File Source (Netshow URL), File Source (URL), File stream renderer, File writer, Full Screen Renderer, G.711 Codec, Hauppauge WinTV File Reader, Hauppauge WinTV File Writer, Hauppauge WinTV MPEG Splitter, Hauppauge WinTV SnapShot, Indeo® audio software, Indeo® video 4.4 Compression Filter, Indeo® video 4.4 Decompression Filter, Indeo® video 5.10 Compression Filter, Indeo® video 5.10 Decompression Filter, Infinite Pin Tee Filter, Internal Script Command Renderer, InterVideo NonCSS Audio Decoder for Hauppauge, InterVideo NonCSS Video Decoder for Hauppauge, IVF source filter, Line 21 Decoder, Line 21 Decoder 2, Lyric Parser, Microsoft MPEG-4 Video Decompressor, Microsoft Screen Video Decompressor, MIDI Parser, MJPEG Decompressor, MPEG Audio Decoder, MPEG Layer-3 Decoder, MPEG Video Decoder, MPEG-2 Demultiplexer, MPEG-2 Sections and Tables, MPEG-2 Splitter, MPEG-I Stream Splitter, MPEG2Dump, Mpeg4 Decoder DMO, MSScreen decoder DMO, Multi-file Parser, NSC file Parser, Null Renderer, Overlay Mixer, Overlay Mixer2, QT Decompressor, QuickTime Movie Parser, SageTV MpegDeMux, SageTV MpegMux, SageTV Music Visualization, SAMI (CC) Parser, SampleGrabber, Shared File Source (Async.), ShotBoundaryDet, Smart Tee, StreamMachine MPEG Audio Compressor, ULead MPEG Audio Decoder, ULead MPEG Encoder, ULead MPEG Splitter, ULead MPEG Video Decoder, VBI Surface Allocator, VGA 16 Color Ditherer, Video Mixing Renderer 9, Video Port Manager, Video Renderer, Video Renderer, Wave Parser, WIA Stream Snapshot Filter, Windows Media Audio Decoder, Windows Media Multiplexer, Windows Media Pad VU Data Grabber, Windows Media source filter, Windows Media Update Filter, Windows Media Video Decoder, Windows Media Video Decoder, WM ASF Reader, WM ASF Writer, WMAudio Decoder DMO, WMP CD Filter, WMVideo 8 Decoder DMO, WMVideo Decoder DMO, WST Decoder, XML Playlist] Wed 7/16 17:30:58.102 videoDevices=[SoundMAX Digital Audio, Hauppauge WinTV PVR PCI II Capture] Wed 7/16 17:30:58.102 hwDecoderFilters=[] Wed 7/16 17:30:58.102 audioRenderFilters=[SoundMAX Digital Audio, Default DirectSound Device, Default WaveOut Device, DirectSound: SoundMAX Digital Audio] Wed 7/16 17:30:58.617 LastChan=309 Wed 7/16 17:30:58.617 PVR250 Encoding Options=[MPEG2 Max Quality, DVD Long Play, Fair, Great, SVCD Standard Play, Good, Best, DVD Standard Play, DVD Extra Long Play, CVD, SVCD Long Play, SVCD Extra Long Play] Wed 7/16 17:30:58.617 Seeker encOptions=[mpeg2 max quality, great, svcd standard play, svcd long play, good, best, svcd extra long play, fair, dvd standard play, dvd long play, dvd extra long play, cvd] Wed 7/16 17:30:58.633 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:30:58.727 CARNY Processing 0 Agents & 23195 Airs Wed 7/16 17:30:58.727 CARNY Negative Energy Size: 0 Wed 7/16 17:30:58.727 CARNY Traitors:[] Wed 7/16 17:30:58.758 setUI(sage.w@22811e) Wed 7/16 17:30:58.852 VF thread is now running... Wed 7/16 17:30:58.852 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:30:59.258 sage.bf@daff8d needs an update in 0:00:00 Wed 7/16 17:30:59.258 EPG needs an update in 0 minutes Wed 7/16 17:30:59.273 Carny got a Std job of null Wed 7/16 17:30:59.602 Scheduler.updateSchedule() called time=Wed 7/16 17:30:59.273 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:30:59.602 # Airs=0 Wed 7/16 17:30:59.602 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:30:59.602 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:30:59.602 Evaluating Potentials Wed 7/16 17:30:59.602 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:30:59.602 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:30:59.602 [] Wed 7/16 17:30:59.602 Scheduler awoken Wed 7/16 17:30:59.695 CARNY Processing 0 Agents & 23195 Airs Wed 7/16 17:30:59.695 CARNY Negative Energy Size: 0 Wed 7/16 17:30:59.695 CARNY Traitors:[] Wed 7/16 17:30:59.695 Carny waiting for awhile... Wed 7/16 17:30:59.695 Scheduler.updateSchedule() called time=Wed 7/16 17:30:59.695 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:30:59.695 # Airs=0 Wed 7/16 17:30:59.695 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:30:59.695 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:30:59.711 Evaluating Potentials Wed 7/16 17:30:59.711 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:30:59.711 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:30:59.711 [] Wed 7/16 17:30:59.711 Scheduler awoken Wed 7/16 17:30:59.711 Scheduler.updateSchedule() called time=Wed 7/16 17:30:59.711 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:30:59.711 # Airs=0 Wed 7/16 17:30:59.711 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:30:59.711 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:30:59.711 Evaluating Potentials Wed 7/16 17:30:59.711 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:30:59.711 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:30:59.711 [] Wed 7/16 17:30:59.727 EPG attempting to expand DIRECTV Minneapolis - Minneapolis Wed 7/16 17:30:59.727 expand called on DIRECTV Minneapolis - Minneapolis at Wed 7/16 17:30:59.727 expandedUntil=Wed 12/31 18:00:00.000 confirmedUntil=Wed 12/31 18:00:00.000 Wed 7/16 17:30:59.727 EPG Expanding DIRECTV Minneapolis - Minneapolis at Wed 7/16 17:30:59.727 Wed 7/16 17:30:59.742 VF processing job null nPlayin=false Wed 7/16 17:30:59.742 Seeker waiting for 153722849641001 mins. Wed 7/16 17:30:59.742 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:30:59.758 Seeker awoken Wed 7/16 17:30:59.758 Seeker adding new Encoder: Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:30:59.758 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[] Wed 7/16 17:30:59.773 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:30:59.773 newRecord=null Wed 7/16 17:30:59.773 NOTHING TO RECORD FOR NOW... Wed 7/16 17:30:59.773 VF processing job null nPlayin=false Wed 7/16 17:30:59.773 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:30:59.773 Seeker waiting for -17639910 mins. Wed 7/16 17:30:59.789 Seeker awoken Wed 7/16 17:30:59.789 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[] Wed 7/16 17:30:59.789 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:30:59.789 newRecord=null Wed 7/16 17:30:59.789 NOTHING TO RECORD FOR NOW... Wed 7/16 17:30:59.789 VF processing job null nPlayin=false Wed 7/16 17:30:59.789 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:30:59.805 Seeker waiting for 153722849641001 mins. Wed 7/16 17:31:00.055 WarlockRipper logged in OK. Wed 7/16 17:31:00.695 Setting the system clock to be Wed 7/16 17:31:00.655 Wed 7/16 17:31:00.764 WarlockRipper got 447 channels Wed 7/16 17:32:03.889 Wizard backing up database file... Wed 7/16 17:32:03.998 Error accessing file system:java.lang.NullPointerException java.lang.NullPointerException Wed 7/16 17:32:03.998 at sage.bb.gP(Unknown Source) Wed 7/16 17:32:03.998 at sage.ak.run(Unknown Source) Wed 7/16 17:32:03.998 at java.lang.Thread.run(Unknown Source) Wed 7/16 17:32:03.998 sage.bf@daff8d needs an update in 23:58:55 Wed 7/16 17:32:03.998 EPG needs an update in 1438 minutes Wed 7/16 17:32:03.998 Carny got a Std job of null Wed 7/16 17:32:04.061 CARNY Processing 0 Agents & 24755 Airs Wed 7/16 17:32:04.061 CARNY Negative Energy Size: 0 Wed 7/16 17:32:04.061 CARNY Traitors:[] Wed 7/16 17:32:04.061 Scheduler awoken Wed 7/16 17:32:04.061 Scheduler.updateSchedule() called time=Wed 7/16 17:32:04.061 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:32:04.061 # Airs=0 Wed 7/16 17:32:04.061 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:32:04.061 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:32:04.061 Evaluating Potentials Wed 7/16 17:32:04.061 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:32:04.061 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:32:04.061 [] Wed 7/16 17:32:04.061 Seeker awoken Wed 7/16 17:32:04.061 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[] Wed 7/16 17:32:04.061 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:32:04.061 newRecord=null Wed 7/16 17:32:04.061 NOTHING TO RECORD FOR NOW... Wed 7/16 17:32:04.061 VF processing job null nPlayin=false Wed 7/16 17:32:04.061 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:32:04.076 Seeker waiting for 153722849641000 mins. Wed 7/16 17:32:04.076 Carny waiting for awhile... Wed 7/16 17:32:04.076 EPG's works is done. Waiting... Wed 7/16 17:32:40.826 setUI(sage.w@1860a43) Wed 7/16 17:32:42.670 setUI(sage.d@1fdcee) Wed 7/16 17:34:18.717 setUI(sage.w@1860a43) Wed 7/16 17:34:18.717 Scheduler awoken Wed 7/16 17:34:18.733 Scheduler.updateSchedule() called time=Wed 7/16 17:34:18.733 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:34:18.733 # Airs=0 Wed 7/16 17:34:18.733 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:34:18.733 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:34:18.733 Evaluating Potentials Wed 7/16 17:34:18.733 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:34:18.983 sage.bf@daff8d needs an update in 23:56:40 Wed 7/16 17:34:18.983 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:34:18.983 [] Wed 7/16 17:34:18.983 Seeker awoken Wed 7/16 17:34:18.983 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[] Wed 7/16 17:34:18.983 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:34:18.983 newRecord=null Wed 7/16 17:34:18.998 NOTHING TO RECORD FOR NOW... Wed 7/16 17:34:18.998 VF processing job null nPlayin=false Wed 7/16 17:34:18.998 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:34:18.998 Seeker waiting for 153722849640998 mins. Wed 7/16 17:34:18.998 EPG needs an update in 1436 minutes Wed 7/16 17:34:18.998 EPG's works is done. Waiting... Wed 7/16 17:34:20.967 VideoFrame.watch(A[96374,96033,"Match Game",14909@0716.17:30,30]) Wed 7/16 17:34:20.983 Called Seeker.requestWatch(A[96374,96033,"Match Game",14909@0716.17:30,30]) hostname=SAGETV PROCESS VF Wed 7/16 17:34:20.998 findBestEncoderForNow(A[96374,96033,"Match Game",14909@0716.17:30,30] record=false host=SAGETV PROCESS VF) Wed 7/16 17:34:20.998 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:34:20.998 defaultRecord=A[96374,96033,"Match Game",14909@0716.17:30,30] Wed 7/16 17:34:20.998 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:34:20.998 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 17:34:20.998 newRecord=A[96374,96033,"Match Game",14909@0716.17:30,30] Wed 7/16 17:34:20.998 Change in record to another show. Entering device record mode. - LATER Wed 7/16 17:34:20.998 Change in record to another show. Entering device record mode. - NOW Wed 7/16 17:34:20.998 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[96374,96033,"Match Game",14909@0716.17:30,30], currTime=Wed 7/16 17:34:20.998) currRecord=null switch=false Wed 7/16 17:34:20.998 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:34:20.998 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 17:34:20.998 Seeker resetting the encoder because it'll pass the max duration Wed 7/16 17:34:20.998 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:34:22.498 Incomplete COM Read on handle 2700 Wed 7/16 17:34:22.858 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[96374,96033,"Match Game",14909@0716.17:30,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-0.mpg, Seg0[Wed 7/16 17:30:00.023-Wed 7/16 17:30:03.008], Seg1[Wed 7/16 17:34:20.998-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 17:34:22.858 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[96374,96033,"Match Game",14909@0716.17:30,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-0.mpg, Seg0[Wed 7/16 17:30:00.023-Wed 7/16 17:30:03.008], Seg1[Wed 7/16 17:34:20.998-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 17:34:23.076 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[96374,96033,"Match Game",14909@0716.17:30,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-0.mpg, Seg0[Wed 7/16 17:30:00.023-Wed 7/16 17:30:03.008], Seg1[Wed 7/16 17:34:20.998-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:34:23.076 setUI(sage.bl@f2a45d) Wed 7/16 17:34:23.076 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:34:23.092 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[96374,96033,"Match Game",14909@0716.17:30,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-0.mpg, Seg0[Wed 7/16 17:30:00.023-Wed 7/16 17:30:03.008], Seg1[Wed 7/16 17:34:20.998-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 17:34:23.092 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[96374,96033,"Match Game",14909@0716.17:30,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-0.mpg, Seg0[Wed 7/16 17:30:00.023-Wed 7/16 17:30:03.008], Seg1[Wed 7/16 17:34:20.998-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:34:23.092 targetTime = Wed 7/16 17:34:20.998 Wed 7/16 17:34:23.092 VideoFrame got an old file currEnc=null newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 17:34:23.608 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 17:34:23.639 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/16 17:34:23.639 VF processing job null nPlayin=true Wed 7/16 17:34:23.639 VF thread is now waiting for 0:25:38.752 Wed 7/16 17:34:43.186 Processing channel change request isDown=false targetChan=null Wed 7/16 17:34:43.233 VideoFrame.watch(A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60]) Wed 7/16 17:34:43.233 Called Seeker.requestWatch(A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60]) hostname=SAGETV PROCESS VF Wed 7/16 17:34:43.233 findBestEncoderForNow(A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] record=false host=SAGETV PROCESS VF) Wed 7/16 17:34:43.248 MARK 1 currRecord=A[96374,96033,"Match Game",14909@0716.17:30,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:34:43.248 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:34:43.248 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 17:34:43.248 newRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] Wed 7/16 17:34:43.248 Change in record, logging recorded data. Wed 7/16 17:34:43.248 Seeker.endRecord(Wed 7/16 17:34:43.248) currRecord=A[96374,96033,"Match Game",14909@0716.17:30,30] Wed 7/16 17:34:43.248 Change in record to another show. Entering device record mode. - LATER Wed 7/16 17:34:43.248 Change in record to another show. Entering device record mode. - NOW Wed 7/16 17:34:43.248 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60], currTime=Wed 7/16 17:34:43.248) currRecord=null switch=true Wed 7/16 17:34:43.248 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:34:43.248 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 17:34:43.248 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 17:34:43.248 Added:MediaFile[A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg, Seg0[Wed 7/16 17:34:43.248-Wed 12/31 18:00:00.000]] Wed 7/16 17:34:43.248 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:34:44.748 Incomplete COM Read on handle 2700 Wed 7/16 17:34:44.748 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-1.mpg]) Wed 7/16 17:34:44.748 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-1.mpg] nPlayin=true Wed 7/16 17:34:44.748 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg, Seg0[Wed 7/16 17:34:43.248-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 17:34:44.764 VF clearing out job because of new watch VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-1.mpg] Wed 7/16 17:34:44.764 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg, Seg0[Wed 7/16 17:34:43.248-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:34:44.764 Added:Watched[id=177497 Airing=A[96374,96033,"Match Game",14909@0716.17:30,30], WatchStart=Wed 7/16 17:30:00.023, WatchEnd=Wed 7/16 17:34:42.019, RealStart=Wed 7/16 17:34:23.092, RealEnd=Wed 7/16 17:34:44.764] Wed 7/16 17:34:44.889 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg, Seg0[Wed 7/16 17:34:43.248-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:34:44.889 VF waiting for data to appear in new file... Wed 7/16 17:34:44.889 VF thread is now waiting for 0:00:00.200 Wed 7/16 17:34:44.998 Scheduler awoken Wed 7/16 17:34:45.014 Scheduler.updateSchedule() called time=Wed 7/16 17:34:45.014 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:34:45.014 # Airs=0 Wed 7/16 17:34:45.014 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:34:45.014 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:34:45.014 Evaluating Potentials Wed 7/16 17:34:45.014 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:34:45.014 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:34:45.014 [] Wed 7/16 17:34:45.014 Seeker awoken Wed 7/16 17:34:45.014 MARK 1 currRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:34:45.014 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:34:45.014 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:34:45.014 newRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] Wed 7/16 17:34:45.014 Keeping record just the way it is. Wed 7/16 17:34:45.014 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 17:34:45.045 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg, Seg0[Wed 7/16 17:34:43.248-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:34:45.045 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg, Seg0[Wed 7/16 17:34:43.248-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:34:45.045 targetTime = Wed 7/16 17:34:43.248 Wed 7/16 17:34:45.045 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 17:34:45.092 needFreeSize = 0.4995 GB currFreeSize=117.081341952 GB Wed 7/16 17:34:45.092 Seeker waiting for 25 mins. Wed 7/16 17:34:45.201 VF processing job null nPlayin=true Wed 7/16 17:34:45.201 VF thread is now waiting for 0:25:16.502 Wed 7/16 17:34:57.405 setUI(sage.w@22811e) Wed 7/16 17:34:57.420 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:34:57.420 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 17:34:57.420 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:34:57.561 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null] Wed 7/16 17:34:57.576 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/16 17:34:57.592 VF Watch Complete Check currFile=MediaFile[A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg, Seg0[Wed 7/16 17:34:43.248-Wed 12/31 18:00:00.000]] loggedWatch=false Wed 7/16 17:34:57.592 VF processing job null nPlayin=false Wed 7/16 17:34:57.592 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:34:58.983 setUI(sage.w@1860a43) Wed 7/16 17:35:00.248 setUI(sage.d@1fdcee) Wed 7/16 17:35:05.045 setUI(sage.w@1860a43) Wed 7/16 17:35:05.155 sage.bf@daff8d needs an update in 23:55:54 Wed 7/16 17:35:05.170 Scheduler awoken Wed 7/16 17:35:05.170 Scheduler.updateSchedule() called time=Wed 7/16 17:35:05.170 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:35:05.170 # Airs=0 Wed 7/16 17:35:05.170 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:35:05.170 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:35:05.186 Evaluating Potentials Wed 7/16 17:35:05.186 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:35:05.186 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:35:05.186 [] Wed 7/16 17:35:05.186 Seeker awoken Wed 7/16 17:35:05.186 MARK 1 currRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:05.186 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:05.186 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:35:05.186 newRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] Wed 7/16 17:35:05.186 Keeping record just the way it is. Wed 7/16 17:35:05.186 RootFile=C:\ fstype=NTFS fileLength=10695948 Wed 7/16 17:35:05.186 VF processing job null nPlayin=false Wed 7/16 17:35:05.186 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:35:05.201 needFreeSize = 0.4995 GB currFreeSize=117.071863808 GB Wed 7/16 17:35:05.201 Seeker waiting for 24 mins. Wed 7/16 17:35:05.201 EPG needs an update in 1435 minutes Wed 7/16 17:35:05.201 EPG's works is done. Waiting... Wed 7/16 17:35:06.233 setUI(sage.d@1fdcee) Wed 7/16 17:35:15.326 WarlockRipper logged in OK. Wed 7/16 17:35:15.592 WarlockRipper got 6 providers Wed 7/16 17:35:24.092 setUI(sage.w@1860a43) Wed 7/16 17:35:24.092 Scheduler awoken Wed 7/16 17:35:24.092 Scheduler.updateSchedule() called time=Wed 7/16 17:35:24.092 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:35:24.092 # Airs=0 Wed 7/16 17:35:24.092 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:35:24.108 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:35:24.108 Evaluating Potentials Wed 7/16 17:35:24.108 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:35:24.155 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:35:24.155 [] Wed 7/16 17:35:24.233 sage.bf@daff8d needs an update in 23:55:35 Wed 7/16 17:35:24.233 EPG needs an update in 1435 minutes Wed 7/16 17:35:24.233 EPG's works is done. Waiting... Wed 7/16 17:35:24.233 Seeker awoken Wed 7/16 17:35:24.233 MARK 1 currRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:24.248 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:24.248 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:35:24.248 newRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] Wed 7/16 17:35:24.248 Keeping record just the way it is. Wed 7/16 17:35:24.248 RootFile=C:\ fstype=NTFS fileLength=20720760 Wed 7/16 17:35:24.248 VF processing job null nPlayin=false Wed 7/16 17:35:24.248 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:35:24.248 needFreeSize = 0.4995 GB currFreeSize=117.061840896 GB Wed 7/16 17:35:24.248 Seeker waiting for 24 mins. Wed 7/16 17:35:25.639 setUI(sage.bl@f2a45d) Wed 7/16 17:35:25.639 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:35:25.639 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/16 17:35:25.655 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/16 17:35:25.655 VF processing job null nPlayin=true Wed 7/16 17:35:25.655 VF thread is now waiting for 0:25:04.231 Wed 7/16 17:35:49.842 Processing channel change request isDown=false targetChan=null Wed 7/16 17:35:49.858 VideoFrame.watch(A[70776,70584,"The Waltons",11221@0716.17:00,60]) Wed 7/16 17:35:49.858 Called Seeker.requestWatch(A[70776,70584,"The Waltons",11221@0716.17:00,60]) hostname=SAGETV PROCESS VF Wed 7/16 17:35:49.858 findBestEncoderForNow(A[70776,70584,"The Waltons",11221@0716.17:00,60] record=false host=SAGETV PROCESS VF) Wed 7/16 17:35:49.858 MARK 1 currRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:49.858 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:49.858 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 17:35:49.858 newRecord=A[70776,70584,"The Waltons",11221@0716.17:00,60] Wed 7/16 17:35:49.858 Change in record, logging recorded data. Wed 7/16 17:35:49.858 Seeker.endRecord(Wed 7/16 17:35:49.858) currRecord=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60] Wed 7/16 17:35:49.858 Change in record to another show. Entering device record mode. - LATER Wed 7/16 17:35:49.858 Change in record to another show. Entering device record mode. - NOW Wed 7/16 17:35:49.858 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[70776,70584,"The Waltons",11221@0716.17:00,60], currTime=Wed 7/16 17:35:49.858) currRecord=null switch=true Wed 7/16 17:35:49.858 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:35:49.873 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 17:35:49.873 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 17:35:49.873 Added:MediaFile[A[70776,70584,"The Waltons",11221@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg, Seg0[Wed 7/16 17:35:49.873-Wed 12/31 18:00:00.000]] Wed 7/16 17:35:49.873 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:35:49.873 Could not open COM2 Wed 7/16 17:35:49.873 ERROR Cannot open serial port for DTV control: COM2 Wed 7/16 17:35:49.873 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg]) Wed 7/16 17:35:49.873 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[70776,70584,"The Waltons",11221@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg, Seg0[Wed 7/16 17:35:49.873-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 17:35:49.889 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg] nPlayin=true Wed 7/16 17:35:49.889 VF clearing out job because of new watch VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg] Wed 7/16 17:35:49.889 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[70776,70584,"The Waltons",11221@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg, Seg0[Wed 7/16 17:35:49.873-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:35:49.889 Added:Watched[id=177499 Airing=A[17832,17030,"America's Funniest Home Videos",10093@0716.17:00,60], WatchStart=Wed 7/16 17:34:43.248, WatchEnd=Wed 7/16 17:35:19.647, RealStart=Wed 7/16 17:34:45.045, RealEnd=Wed 7/16 17:35:49.889] Wed 7/16 17:35:50.045 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70776,70584,"The Waltons",11221@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg, Seg0[Wed 7/16 17:35:49.873-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:50.045 VF waiting for data to appear in new file... Wed 7/16 17:35:50.045 VF thread is now waiting for 0:00:00.200 Wed 7/16 17:35:50.123 Scheduler awoken Wed 7/16 17:35:50.123 Scheduler.updateSchedule() called time=Wed 7/16 17:35:50.123 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:35:50.139 # Airs=0 Wed 7/16 17:35:50.139 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:35:50.139 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:35:50.139 Evaluating Potentials Wed 7/16 17:35:50.139 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:35:50.139 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:35:50.139 [] Wed 7/16 17:35:50.139 Seeker awoken Wed 7/16 17:35:50.139 MARK 1 currRecord=A[70776,70584,"The Waltons",11221@0716.17:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:50.139 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:50.139 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:35:50.139 newRecord=A[70776,70584,"The Waltons",11221@0716.17:00,60] Wed 7/16 17:35:50.139 Keeping record just the way it is. Wed 7/16 17:35:50.139 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 17:35:50.139 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70776,70584,"The Waltons",11221@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg, Seg0[Wed 7/16 17:35:49.873-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:50.139 VF waiting for data to appear in new file... Wed 7/16 17:35:50.139 VF thread is now waiting for 0:00:00.200 Wed 7/16 17:35:50.155 needFreeSize = 0.4995 GB currFreeSize=117.048418304 GB Wed 7/16 17:35:50.155 Seeker waiting for 24 mins. Wed 7/16 17:35:50.342 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70776,70584,"The Waltons",11221@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg, Seg0[Wed 7/16 17:35:49.873-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:50.342 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70776,70584,"The Waltons",11221@0716.17:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg, Seg0[Wed 7/16 17:35:49.873-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:35:50.342 targetTime = Wed 7/16 17:35:49.873 Wed 7/16 17:35:50.342 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 17:35:50.420 VF processing job null nPlayin=true Wed 7/16 17:35:50.420 VF thread is now waiting for 0:24:09.877 Wed 7/16 17:35:54.092 Processing channel change request isDown=false targetChan=null Wed 7/16 17:35:54.123 VideoFrame.watch(A[93033,92749,"Chain of Desire",14877@0716.16:00,120]) Wed 7/16 17:35:54.123 Called Seeker.requestWatch(A[93033,92749,"Chain of Desire",14877@0716.16:00,120]) hostname=SAGETV PROCESS VF Wed 7/16 17:35:54.123 findBestEncoderForNow(A[93033,92749,"Chain of Desire",14877@0716.16:00,120] record=false host=SAGETV PROCESS VF) Wed 7/16 17:35:54.123 MARK 1 currRecord=A[70776,70584,"The Waltons",11221@0716.17:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:54.123 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:54.123 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 17:35:54.123 newRecord=A[93033,92749,"Chain of Desire",14877@0716.16:00,120] Wed 7/16 17:35:54.123 Change in record, logging recorded data. Wed 7/16 17:35:54.123 Seeker.endRecord(Wed 7/16 17:35:54.123) currRecord=A[70776,70584,"The Waltons",11221@0716.17:00,60] Wed 7/16 17:35:54.123 Change in record to another show. Entering device record mode. - LATER Wed 7/16 17:35:54.123 Change in record to another show. Entering device record mode. - NOW Wed 7/16 17:35:54.123 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[93033,92749,"Chain of Desire",14877@0716.16:00,120], currTime=Wed 7/16 17:35:54.123) currRecord=null switch=true Wed 7/16 17:35:54.123 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:35:54.123 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 17:35:54.123 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 17:35:54.123 Added:MediaFile[A[93033,92749,"Chain of Desire",14877@0716.16:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg, Seg0[Wed 7/16 17:35:54.123-Wed 12/31 18:00:00.000]] Wed 7/16 17:35:54.123 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:35:54.139 Could not open COM2 Wed 7/16 17:35:54.139 ERROR Cannot open serial port for DTV control: COM2 Wed 7/16 17:35:54.139 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg]) Wed 7/16 17:35:54.139 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg] nPlayin=true Wed 7/16 17:35:54.139 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[93033,92749,"Chain of Desire",14877@0716.16:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg, Seg0[Wed 7/16 17:35:54.123-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 17:35:54.139 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg] Wed 7/16 17:35:54.139 VF notified of Inactive File Wed 7/16 17:35:54.139 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[93033,92749,"Chain of Desire",14877@0716.16:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg, Seg0[Wed 7/16 17:35:54.123-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:54.139 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[93033,92749,"Chain of Desire",14877@0716.16:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg, Seg0[Wed 7/16 17:35:54.123-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:35:54.233 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[93033,92749,"Chain of Desire",14877@0716.16:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg, Seg0[Wed 7/16 17:35:54.123-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:54.248 VF waiting for data to appear in new file... Wed 7/16 17:35:54.248 VF thread is now waiting for 0:00:00.200 Wed 7/16 17:35:54.389 Scheduler awoken Wed 7/16 17:35:54.389 Scheduler.updateSchedule() called time=Wed 7/16 17:35:54.389 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:35:54.389 # Airs=0 Wed 7/16 17:35:54.389 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:35:54.389 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:35:54.389 Evaluating Potentials Wed 7/16 17:35:54.389 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:35:54.389 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:35:54.389 [] Wed 7/16 17:35:54.389 Seeker awoken Wed 7/16 17:35:54.389 MARK 1 currRecord=A[93033,92749,"Chain of Desire",14877@0716.16:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:54.389 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:54.389 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:35:54.389 newRecord=A[93033,92749,"Chain of Desire",14877@0716.16:00,120] Wed 7/16 17:35:54.389 Keeping record just the way it is. Wed 7/16 17:35:54.405 RootFile=C:\ fstype=NTFS fileLength=72028 Wed 7/16 17:35:54.405 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[93033,92749,"Chain of Desire",14877@0716.16:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg, Seg0[Wed 7/16 17:35:54.123-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:54.405 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[93033,92749,"Chain of Desire",14877@0716.16:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg, Seg0[Wed 7/16 17:35:54.123-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:35:54.405 targetTime = Wed 7/16 17:35:54.123 Wed 7/16 17:35:54.405 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 17:35:54.420 needFreeSize = 0.4995 GB currFreeSize=117.046132736 GB Wed 7/16 17:35:54.420 Seeker waiting for 24 mins. Wed 7/16 17:35:54.451 VF processing job null nPlayin=true Wed 7/16 17:35:54.451 VF thread is now waiting for 0:24:05.627 Wed 7/16 17:35:55.530 Processing channel change request isDown=false targetChan=null Wed 7/16 17:35:55.545 VideoFrame.watch(A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120]) Wed 7/16 17:35:55.545 Called Seeker.requestWatch(A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120]) hostname=SAGETV PROCESS VF Wed 7/16 17:35:55.545 findBestEncoderForNow(A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] record=false host=SAGETV PROCESS VF) Wed 7/16 17:35:55.545 MARK 1 currRecord=A[93033,92749,"Chain of Desire",14877@0716.16:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:55.545 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:55.545 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 17:35:55.545 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 17:35:55.545 Change in record, logging recorded data. Wed 7/16 17:35:55.545 Seeker.endRecord(Wed 7/16 17:35:55.545) currRecord=A[93033,92749,"Chain of Desire",14877@0716.16:00,120] Wed 7/16 17:35:55.545 Change in record to another show. Entering device record mode. - LATER Wed 7/16 17:35:55.545 Change in record to another show. Entering device record mode. - NOW Wed 7/16 17:35:55.545 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120], currTime=Wed 7/16 17:35:55.545) currRecord=null switch=true Wed 7/16 17:35:55.545 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:35:55.561 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 17:35:55.561 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 17:35:55.561 Added:MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] Wed 7/16 17:35:55.561 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 17:35:55.561 Could not open COM2 Wed 7/16 17:35:55.561 ERROR Cannot open serial port for DTV control: COM2 Wed 7/16 17:35:55.561 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg]) Wed 7/16 17:35:55.561 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg] nPlayin=true Wed 7/16 17:35:55.561 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 17:35:55.561 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg] Wed 7/16 17:35:55.561 VF notified of Inactive File Wed 7/16 17:35:55.561 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:55.561 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:35:55.639 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:55.639 VF waiting for data to appear in new file... Wed 7/16 17:35:55.639 VF thread is now waiting for 0:00:00.200 Wed 7/16 17:35:55.826 Scheduler awoken Wed 7/16 17:35:55.826 Scheduler.updateSchedule() called time=Wed 7/16 17:35:55.826 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 17:35:55.826 # Airs=0 Wed 7/16 17:35:55.826 SHOWS LOST TO CONFLICTS:[] Wed 7/16 17:35:55.826 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 17:35:55.826 Evaluating Potentials Wed 7/16 17:35:55.826 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 17:35:55.826 Hauppauge WinTV PVR PCI II Capture Wed 7/16 17:35:55.826 [] Wed 7/16 17:35:55.826 Seeker awoken Wed 7/16 17:35:55.826 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:35:55.826 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:35:55.826 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:35:55.826 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 17:35:55.826 Keeping record just the way it is. Wed 7/16 17:35:55.826 RootFile=C:\ fstype=NTFS fileLength=47024 Wed 7/16 17:35:55.826 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 17:35:55.826 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 17:35:55.826 targetTime = Wed 7/16 17:35:55.561 Wed 7/16 17:35:55.826 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 17:35:55.858 needFreeSize = 0.4995 GB currFreeSize=117.045624832 GB Wed 7/16 17:35:55.858 Seeker waiting for 84 mins. Wed 7/16 17:35:55.967 VF processing job null nPlayin=true Wed 7/16 17:35:55.967 VF thread is now waiting for 1:24:04.189 Wed 7/16 17:40:26.483 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:40:26.483 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 17:40:26.983 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/16 17:40:26.983 VF processing job null nPlayin=true Wed 7/16 17:40:26.983 VF thread is now waiting for 1:19:33.306 Wed 7/16 17:40:27.076 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:40:27.076 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 17:40:27.092 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/16 17:40:27.092 VF processing job null nPlayin=true Wed 7/16 17:40:27.123 VF thread is now waiting for 1:19:33.186 Wed 7/16 17:40:55.858 Seeker awoken Wed 7/16 17:40:55.858 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:40:55.858 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:40:55.858 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:40:55.858 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 17:40:55.858 Keeping record just the way it is. Wed 7/16 17:40:55.873 RootFile=C:\ fstype=NTFS fileLength=158218276 Wed 7/16 17:40:55.873 VF processing job null nPlayin=true Wed 7/16 17:40:55.873 VF thread is now waiting for 1:19:04.425 Wed 7/16 17:40:55.889 needFreeSize = 0.4995 GB currFreeSize=116.887486464 GB Wed 7/16 17:40:55.889 Seeker waiting for 79 mins. Wed 7/16 17:40:59.389 setUI(sage.w@22811e) Wed 7/16 17:40:59.389 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:40:59.389 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null]) Wed 7/16 17:40:59.405 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 17:40:59.498 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null] Wed 7/16 17:40:59.498 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/16 17:40:59.498 VF Watch Complete Check currFile=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 12/31 18:00:00.000]] loggedWatch=false Wed 7/16 17:40:59.498 VF processing job null nPlayin=false Wed 7/16 17:40:59.498 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:41:00.670 setUI(sage.w@1860a43) Wed 7/16 17:45:55.889 Seeker awoken Wed 7/16 17:45:55.889 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:45:55.889 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:45:55.889 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:45:55.889 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 17:45:55.889 Keeping record just the way it is. Wed 7/16 17:45:55.889 RootFile=C:\ fstype=NTFS fileLength=316295540 Wed 7/16 17:45:55.889 VF processing job null nPlayin=false Wed 7/16 17:45:55.889 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:45:55.905 needFreeSize = 0.4995 GB currFreeSize=116.729393152 GB Wed 7/16 17:45:55.905 Seeker waiting for 74 mins. Wed 7/16 17:50:55.905 Seeker awoken Wed 7/16 17:50:55.905 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:50:55.905 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:50:55.905 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:50:55.905 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 17:50:55.905 Keeping record just the way it is. Wed 7/16 17:50:55.905 RootFile=C:\ fstype=NTFS fileLength=474435316 Wed 7/16 17:50:55.905 VF processing job null nPlayin=false Wed 7/16 17:50:55.905 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:50:55.920 needFreeSize = 0.4995 GB currFreeSize=116.571271168 GB Wed 7/16 17:50:55.920 Seeker waiting for 69 mins. Wed 7/16 17:55:55.920 Seeker awoken Wed 7/16 17:55:55.920 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 17:55:55.920 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 17:55:55.920 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 17:55:55.920 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 17:55:55.920 Keeping record just the way it is. Wed 7/16 17:55:55.920 RootFile=C:\ fstype=NTFS fileLength=632585724 Wed 7/16 17:55:55.936 VF processing job null nPlayin=false Wed 7/16 17:55:55.936 needFreeSize = 0.4995 GB currFreeSize=116.413104128 GB Wed 7/16 17:55:55.936 VF thread is now waiting for 0:00:00.000 Wed 7/16 17:55:55.936 Seeker waiting for 64 mins. Wed 7/16 18:00:55.936 Seeker awoken Wed 7/16 18:00:55.936 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:00:55.936 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:00:55.936 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:00:55.936 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:00:55.936 Keeping record just the way it is. Wed 7/16 18:00:55.936 RootFile=C:\ fstype=NTFS fileLength=790638656 Wed 7/16 18:00:55.936 VF processing job null nPlayin=false Wed 7/16 18:00:55.936 VF thread is now waiting for 0:00:00.000 Wed 7/16 18:00:55.951 needFreeSize = 0.4995 GB currFreeSize=116.255064064 GB Wed 7/16 18:00:55.951 Seeker waiting for 59 mins. Wed 7/16 18:01:39.451 setUI(sage.g[loc=java.awt.Point[x=0,y=0] size=java.awt.Dimension[width=0,height=0]]) Wed 7/16 18:05:55.951 Seeker awoken Wed 7/16 18:05:55.951 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:05:55.951 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:05:55.951 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:05:55.951 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:05:55.951 Keeping record just the way it is. Wed 7/16 18:05:55.951 RootFile=C:\ fstype=NTFS fileLength=948726940 Wed 7/16 18:05:55.951 VF processing job null nPlayin=false Wed 7/16 18:05:55.951 VF thread is now waiting for 0:00:00.000 Wed 7/16 18:05:55.967 needFreeSize = 0.4995 GB currFreeSize=116.096970752 GB Wed 7/16 18:05:55.967 Seeker waiting for 54 mins. Wed 7/16 18:10:55.967 Seeker awoken Wed 7/16 18:10:55.967 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:10:55.967 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:10:55.967 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:10:55.967 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:10:55.967 Keeping record just the way it is. Wed 7/16 18:10:55.967 RootFile=C:\ fstype=NTFS fileLength=1106873484 Wed 7/16 18:10:55.967 VF processing job null nPlayin=false Wed 7/16 18:10:55.983 VF thread is now waiting for 0:00:00.000 Wed 7/16 18:10:55.983 needFreeSize = 0.4995 GB currFreeSize=115.938832384 GB Wed 7/16 18:10:55.983 Seeker waiting for 49 mins. Wed 7/16 18:11:45.670 setUI(sage.w@1860a43) Wed 7/16 18:11:47.764 setUI(sage.d@1fdcee) Wed 7/16 18:11:53.576 WarlockRipper logged in OK. Wed 7/16 18:11:53.842 WarlockRipper got 6 providers Wed 7/16 18:12:04.311 setUI(sage.w@1860a43) Wed 7/16 18:12:04.358 Scheduler awoken Wed 7/16 18:12:04.358 Scheduler.updateSchedule() called time=Wed 7/16 18:12:04.358 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:04.358 # Airs=0 Wed 7/16 18:12:04.686 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:04.686 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:04.686 Evaluating Potentials Wed 7/16 18:12:04.686 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:04.686 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:04.686 [] Wed 7/16 18:12:04.686 Seeker awoken Wed 7/16 18:12:04.686 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:04.701 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:04.701 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:04.701 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:12:04.701 Keeping record just the way it is. Wed 7/16 18:12:04.701 RootFile=C:\ fstype=NTFS fileLength=1143114800 Wed 7/16 18:12:04.701 VF processing job null nPlayin=false Wed 7/16 18:12:04.701 VF thread is now waiting for 0:00:00.000 Wed 7/16 18:12:04.748 needFreeSize = 0.4995 GB currFreeSize=115.90252544 GB Wed 7/16 18:12:04.748 Seeker waiting for 47 mins. Wed 7/16 18:12:04.858 sage.bf@daff8d needs an update in 23:18:54 Wed 7/16 18:12:04.858 EPG needs an update in 1398 minutes Wed 7/16 18:12:04.858 EPG's works is done. Waiting... Wed 7/16 18:12:06.248 setUI(sage.bl@f2a45d) Wed 7/16 18:12:06.248 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:12:06.248 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/16 18:12:06.264 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/16 18:12:06.264 VF processing job null nPlayin=true Wed 7/16 18:12:06.264 VF thread is now waiting for 1:19:00.801 Wed 7/16 18:12:13.623 Processing channel change request isDown=false targetChan=null Wed 7/16 18:12:13.670 VideoFrame.watch(A[174124,173578,"Blind Date",30196@0716.18:00,30]) Wed 7/16 18:12:13.670 Called Seeker.requestWatch(A[174124,173578,"Blind Date",30196@0716.18:00,30]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:13.670 findBestEncoderForNow(A[174124,173578,"Blind Date",30196@0716.18:00,30] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:13.670 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:13.670 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:13.670 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:13.670 newRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] Wed 7/16 18:12:13.670 Change in record, logging recorded data. Wed 7/16 18:12:13.670 Seeker.endRecord(Wed 7/16 18:12:13.670) currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:12:13.670 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:13.670 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:13.670 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[174124,173578,"Blind Date",30196@0716.18:00,30], currTime=Wed 7/16 18:12:13.670) currRecord=null switch=true Wed 7/16 18:12:13.670 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:13.670 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:13.670 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 18:12:13.670 Added:MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 12/31 18:00:00.000]] Wed 7/16 18:12:13.686 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:15.201 Incomplete COM Read on handle 2700 Wed 7/16 18:12:15.201 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg]) Wed 7/16 18:12:15.201 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg] nPlayin=true Wed 7/16 18:12:15.201 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:15.217 VF clearing out job because of new watch VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg] Wed 7/16 18:12:15.217 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:15.217 Added:Watched[id=177503 Airing=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120], WatchStart=Wed 7/16 17:35:55.561, WatchEnd=Wed 7/16 17:41:07.900, RealStart=Wed 7/16 17:35:55.826, RealEnd=Wed 7/16 18:12:15.217] Wed 7/16 18:12:15.342 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:15.342 VF waiting for data to appear in new file... Wed 7/16 18:12:15.342 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:15.451 Scheduler awoken Wed 7/16 18:12:15.451 Scheduler.updateSchedule() called time=Wed 7/16 18:12:15.451 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:15.467 # Airs=0 Wed 7/16 18:12:15.467 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:15.467 Processing channel change request isDown=false targetChan=null Wed 7/16 18:12:15.483 VideoFrame.watch(A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240]) Wed 7/16 18:12:15.483 Called Seeker.requestWatch(A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:15.483 findBestEncoderForNow(A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:15.483 MARK 1 currRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:15.483 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:15.483 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:15.483 newRecord=A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] Wed 7/16 18:12:15.483 Change in record, logging recorded data. Wed 7/16 18:12:15.483 Seeker.endRecord(Wed 7/16 18:12:15.483) currRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] Wed 7/16 18:12:15.483 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:15.483 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:15.483 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240], currTime=Wed 7/16 18:12:15.483) currRecord=null switch=true Wed 7/16 18:12:15.483 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:15.483 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:15.483 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 18:12:15.483 Added:MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] Wed 7/16 18:12:15.483 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:15.483 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:15.483 Evaluating Potentials Wed 7/16 18:12:15.483 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:15.483 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:15.483 [] Wed 7/16 18:12:15.545 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483]] ifn=null] nPlayin=true Wed 7/16 18:12:16.983 Incomplete COM Read on handle 2700 Wed 7/16 18:12:16.983 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg]) Wed 7/16 18:12:16.983 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:16.998 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483]] ifn=null] Wed 7/16 18:12:16.998 targetTime = Wed 7/16 18:12:13.670 Wed 7/16 18:12:16.998 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:16.998 Seeker awoken Wed 7/16 18:12:16.998 MARK 1 currRecord=A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:16.998 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:16.998 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:16.998 newRecord=A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] Wed 7/16 18:12:16.998 Keeping record just the way it is. Wed 7/16 18:12:16.998 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 18:12:17.014 needFreeSize = 0.4995 GB currFreeSize=115.896184832 GB Wed 7/16 18:12:17.014 Seeker waiting for 47 mins. Wed 7/16 18:12:17.108 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg] nPlayin=true Wed 7/16 18:12:17.108 VF clearing out job because of new watch VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg] Wed 7/16 18:12:17.123 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:17.155 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:17.155 VF waiting for data to appear in new file... Wed 7/16 18:12:17.155 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:17.248 Scheduler awoken Wed 7/16 18:12:17.248 Scheduler.updateSchedule() called time=Wed 7/16 18:12:17.248 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:17.248 # Airs=0 Wed 7/16 18:12:17.248 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:17.248 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:17.248 Evaluating Potentials Wed 7/16 18:12:17.248 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:17.248 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:17.248 [] Wed 7/16 18:12:17.248 Seeker awoken Wed 7/16 18:12:17.248 MARK 1 currRecord=A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:17.248 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:17.248 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:17.248 newRecord=A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] Wed 7/16 18:12:17.248 Keeping record just the way it is. Wed 7/16 18:12:17.248 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 18:12:17.248 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:17.248 VF waiting for data to appear in new file... Wed 7/16 18:12:17.264 needFreeSize = 0.4995 GB currFreeSize=115.896184832 GB Wed 7/16 18:12:17.264 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:17.264 Seeker waiting for 47 mins. Wed 7/16 18:12:17.467 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:17.467 VF waiting for data to appear in new file... Wed 7/16 18:12:17.467 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:17.670 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:17.670 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg, Seg0[Wed 7/16 18:12:15.483-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:17.670 targetTime = Wed 7/16 18:12:15.483 Wed 7/16 18:12:17.670 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:17.701 VF processing job null nPlayin=true Wed 7/16 18:12:17.701 VF thread is now waiting for 0:47:44.267 Wed 7/16 18:12:18.701 Processing channel change request isDown=true targetChan=null Wed 7/16 18:12:18.733 VideoFrame.watch(A[174124,173578,"Blind Date",30196@0716.18:00,30]) Wed 7/16 18:12:18.733 Called Seeker.requestWatch(A[174124,173578,"Blind Date",30196@0716.18:00,30]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:18.733 findBestEncoderForNow(A[174124,173578,"Blind Date",30196@0716.18:00,30] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:18.733 MARK 1 currRecord=A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:18.733 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:18.733 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:18.733 newRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] Wed 7/16 18:12:18.733 Change in record, logging recorded data. Wed 7/16 18:12:18.733 Seeker.endRecord(Wed 7/16 18:12:18.733) currRecord=A[21239,20984,"CMT Smash Hits of Country",10138@0716.15:00,240] Wed 7/16 18:12:18.733 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:18.733 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:18.733 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[174124,173578,"Blind Date",30196@0716.18:00,30], currTime=Wed 7/16 18:12:18.733) currRecord=null switch=true Wed 7/16 18:12:18.733 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:18.733 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:18.748 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:20.248 Incomplete COM Read on handle 2700 Wed 7/16 18:12:20.248 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg]) Wed 7/16 18:12:20.248 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:20.248 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg] nPlayin=true Wed 7/16 18:12:20.248 VF clearing out job because of new watch VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg] Wed 7/16 18:12:20.248 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:20.295 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:20.295 VF waiting for data to appear in new file... Wed 7/16 18:12:20.295 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:20.498 Scheduler awoken Wed 7/16 18:12:20.498 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:20.498 VF waiting for data to appear in new file... Wed 7/16 18:12:20.514 Scheduler.updateSchedule() called time=Wed 7/16 18:12:20.498 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:20.514 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:20.514 # Airs=0 Wed 7/16 18:12:20.514 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:20.514 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:20.514 Evaluating Potentials Wed 7/16 18:12:20.514 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:20.514 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:20.514 [] Wed 7/16 18:12:20.514 Seeker awoken Wed 7/16 18:12:20.530 MARK 1 currRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:20.530 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:20.530 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:20.530 newRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] Wed 7/16 18:12:20.530 Keeping record just the way it is. Wed 7/16 18:12:20.530 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 18:12:20.530 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:20.530 VF waiting for data to appear in new file... Wed 7/16 18:12:20.530 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:20.545 needFreeSize = 0.4995 GB currFreeSize=115.894673408 GB Wed 7/16 18:12:20.545 Seeker waiting for 17 mins. Wed 7/16 18:12:20.733 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:20.733 VF waiting for data to appear in new file... Wed 7/16 18:12:20.733 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:20.936 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:20.936 VF waiting for data to appear in new file... Wed 7/16 18:12:20.936 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:21.139 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:21.139 VF waiting for data to appear in new file... Wed 7/16 18:12:21.139 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:21.342 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:21.342 VF waiting for data to appear in new file... Wed 7/16 18:12:21.342 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:21.545 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:21.545 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[174124,173578,"Blind Date",30196@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg, Seg0[Wed 7/16 18:12:13.670-Wed 7/16 18:12:15.483], Seg1[Wed 7/16 18:12:18.733-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:21.545 targetTime = Wed 7/16 18:12:18.733 Wed 7/16 18:12:21.545 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:21.576 VF processing job null nPlayin=true Wed 7/16 18:12:21.592 VF thread is now waiting for 0:17:41.017 Wed 7/16 18:12:22.358 Processing channel change request isDown=true targetChan=null Wed 7/16 18:12:22.373 VideoFrame.watch(A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120]) Wed 7/16 18:12:22.373 Called Seeker.requestWatch(A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:22.373 findBestEncoderForNow(A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:22.373 MARK 1 currRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:22.373 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:22.373 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:22.373 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:12:22.373 Change in record, logging recorded data. Wed 7/16 18:12:22.373 Seeker.endRecord(Wed 7/16 18:12:22.373) currRecord=A[174124,173578,"Blind Date",30196@0716.18:00,30] Wed 7/16 18:12:22.373 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:22.373 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:22.373 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120], currTime=Wed 7/16 18:12:22.373) currRecord=null switch=true Wed 7/16 18:12:22.373 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:22.373 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:22.373 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:23.889 Incomplete COM Read on handle 2700 Wed 7/16 18:12:23.889 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-1.mpg]) Wed 7/16 18:12:23.889 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-1.mpg] nPlayin=true Wed 7/16 18:12:23.889 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 7/16 18:12:13.670], Seg1[Wed 7/16 18:12:22.373-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:23.889 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-1.mpg] Wed 7/16 18:12:23.889 VF notified of Inactive File Wed 7/16 18:12:23.889 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 7/16 18:12:13.670], Seg1[Wed 7/16 18:12:22.373-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:23.889 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 7/16 18:12:13.670], Seg1[Wed 7/16 18:12:22.373-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:23.905 Added:Watched[id=177505 Airing=A[174124,173578,"Blind Date",30196@0716.18:00,30], WatchStart=Wed 7/16 18:12:13.670, WatchEnd=Wed 7/16 18:12:20.863, RealStart=Wed 7/16 18:12:21.545, RealEnd=Wed 7/16 18:12:23.889] Wed 7/16 18:12:23.951 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 7/16 18:12:13.670], Seg1[Wed 7/16 18:12:22.373-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:23.951 VF waiting for data to appear in new file... Wed 7/16 18:12:23.967 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:24.139 Scheduler awoken Wed 7/16 18:12:24.155 Scheduler.updateSchedule() called time=Wed 7/16 18:12:24.155 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:24.155 # Airs=0 Wed 7/16 18:12:24.155 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:24.155 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:24.155 Evaluating Potentials Wed 7/16 18:12:24.155 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:24.155 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:24.155 [] Wed 7/16 18:12:24.155 Seeker awoken Wed 7/16 18:12:24.155 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:24.155 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:24.155 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:24.155 newRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:12:24.155 Keeping record just the way it is. Wed 7/16 18:12:24.155 RootFile=C:\ fstype=NTFS fileLength=105216 Wed 7/16 18:12:24.155 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 7/16 18:12:13.670], Seg1[Wed 7/16 18:12:22.373-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:24.155 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg, Seg0[Wed 7/16 17:35:55.561-Wed 7/16 18:12:13.670], Seg1[Wed 7/16 18:12:22.373-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:24.155 targetTime = Wed 7/16 18:12:22.373 Wed 7/16 18:12:24.170 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:24.186 needFreeSize = 0.4995 GB currFreeSize=115.89289984 GB Wed 7/16 18:12:24.186 Seeker waiting for 47 mins. Wed 7/16 18:12:24.201 VF processing job null nPlayin=true Wed 7/16 18:12:24.201 VF thread is now waiting for 0:47:37.377 Wed 7/16 18:12:25.405 Processing channel change request isDown=true targetChan=null Wed 7/16 18:12:25.436 VideoFrame.watch(A[92968,92587,"BookTelevision",14877@0716.18:00,30]) Wed 7/16 18:12:25.436 Called Seeker.requestWatch(A[92968,92587,"BookTelevision",14877@0716.18:00,30]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:25.436 findBestEncoderForNow(A[92968,92587,"BookTelevision",14877@0716.18:00,30] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:25.451 MARK 1 currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:25.451 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:25.451 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:25.451 newRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] Wed 7/16 18:12:25.451 Change in record, logging recorded data. Wed 7/16 18:12:25.451 Seeker.endRecord(Wed 7/16 18:12:25.451) currRecord=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120] Wed 7/16 18:12:25.451 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:25.451 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:25.451 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[92968,92587,"BookTelevision",14877@0716.18:00,30], currTime=Wed 7/16 18:12:25.451) currRecord=null switch=true Wed 7/16 18:12:25.451 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:25.451 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:25.451 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 18:12:25.451 Added:MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 12/31 18:00:00.000]] Wed 7/16 18:12:25.451 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:26.951 Incomplete COM Read on handle 2700 Wed 7/16 18:12:26.951 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-1.mpg]) Wed 7/16 18:12:26.951 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:26.951 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-1.mpg] nPlayin=true Wed 7/16 18:12:26.967 VF clearing out job because of new watch VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-1.mpg] Wed 7/16 18:12:26.967 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:26.967 Updated:Watched[id=177503 Airing=A[56094,55789,"New York Yankees 100th Anniversary",11069@0716.17:00,120], WatchStart=Wed 7/16 17:35:55.561, WatchEnd=Wed 7/16 18:12:25.013, RealStart=Wed 7/16 17:35:55.826, RealEnd=Wed 7/16 18:12:26.967] Wed 7/16 18:12:27.030 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:27.045 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:27.045 targetTime = Wed 7/16 18:12:25.451 Wed 7/16 18:12:27.045 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:27.092 VF processing job null nPlayin=true Wed 7/16 18:12:27.092 VF thread is now waiting for 0:17:34.299 Wed 7/16 18:12:27.201 Scheduler awoken Wed 7/16 18:12:27.217 Scheduler.updateSchedule() called time=Wed 7/16 18:12:27.217 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:27.217 # Airs=0 Wed 7/16 18:12:27.217 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:27.217 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:27.217 Evaluating Potentials Wed 7/16 18:12:27.217 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:27.217 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:27.217 [] Wed 7/16 18:12:27.217 Seeker awoken Wed 7/16 18:12:27.217 MARK 1 currRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:27.217 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:27.217 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:27.217 newRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] Wed 7/16 18:12:27.217 Keeping record just the way it is. Wed 7/16 18:12:27.217 RootFile=C:\ fstype=NTFS fileLength=137384 Wed 7/16 18:12:27.217 VF processing job null nPlayin=true Wed 7/16 18:12:27.217 VF thread is now waiting for 0:17:34.299 Wed 7/16 18:12:27.233 needFreeSize = 0.4995 GB currFreeSize=115.891367936 GB Wed 7/16 18:12:27.233 Seeker waiting for 17 mins. Wed 7/16 18:12:29.061 Processing channel change request isDown=true targetChan=null Wed 7/16 18:12:29.092 VideoFrame.watch(A[70838,70601,"Touched by an Angel",11221@0716.18:00,60]) Wed 7/16 18:12:29.092 Called Seeker.requestWatch(A[70838,70601,"Touched by an Angel",11221@0716.18:00,60]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:29.092 findBestEncoderForNow(A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:29.092 MARK 1 currRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:29.092 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:29.092 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:29.092 newRecord=A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] Wed 7/16 18:12:29.092 Change in record, logging recorded data. Wed 7/16 18:12:29.092 Seeker.endRecord(Wed 7/16 18:12:29.092) currRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] Wed 7/16 18:12:29.092 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:29.092 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:29.092 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[70838,70601,"Touched by an Angel",11221@0716.18:00,60], currTime=Wed 7/16 18:12:29.092) currRecord=null switch=true Wed 7/16 18:12:29.092 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:29.092 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:29.092 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 18:12:29.092 Added:MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] Wed 7/16 18:12:29.092 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:30.592 Incomplete COM Read on handle 2700 Wed 7/16 18:12:30.592 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg]) Wed 7/16 18:12:30.592 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:30.592 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg] nPlayin=true Wed 7/16 18:12:30.592 VF clearing out job because of new watch VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg] Wed 7/16 18:12:30.608 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:30.670 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:30.670 VF waiting for data to appear in new file... Wed 7/16 18:12:30.670 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:30.858 Scheduler awoken Wed 7/16 18:12:30.858 Scheduler.updateSchedule() called time=Wed 7/16 18:12:30.858 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:30.858 # Airs=0 Wed 7/16 18:12:30.858 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:30.858 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:30.858 Evaluating Potentials Wed 7/16 18:12:30.858 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:30.858 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:30.858 [] Wed 7/16 18:12:30.858 Seeker awoken Wed 7/16 18:12:30.858 MARK 1 currRecord=A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:30.858 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:30.858 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:30.858 newRecord=A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] Wed 7/16 18:12:30.858 Keeping record just the way it is. Wed 7/16 18:12:30.858 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 18:12:30.858 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:30.858 VF waiting for data to appear in new file... Wed 7/16 18:12:30.873 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:30.873 needFreeSize = 0.4995 GB currFreeSize=115.889856512 GB Wed 7/16 18:12:30.873 Seeker waiting for 47 mins. Wed 7/16 18:12:31.076 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:31.076 VF waiting for data to appear in new file... Wed 7/16 18:12:31.076 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:31.280 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:31.280 VF waiting for data to appear in new file... Wed 7/16 18:12:31.280 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:31.483 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:31.483 VF waiting for data to appear in new file... Wed 7/16 18:12:31.483 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:31.670 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:31.670 VF waiting for data to appear in new file... Wed 7/16 18:12:31.670 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:31.873 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:31.873 VF waiting for data to appear in new file... Wed 7/16 18:12:31.873 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:32.076 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:32.076 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:32.076 targetTime = Wed 7/16 18:12:29.092 Wed 7/16 18:12:32.076 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:32.108 VF processing job null nPlayin=true Wed 7/16 18:12:32.108 VF thread is now waiting for 0:47:30.658 Wed 7/16 18:12:35.561 setUI(sage.w@22811e) Wed 7/16 18:12:35.561 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:12:35.561 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:12:35.561 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 18:12:35.576 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null] Wed 7/16 18:12:35.795 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/16 18:12:35.795 VF Watch Complete Check currFile=MediaFile[A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg, Seg0[Wed 7/16 18:12:29.092-Wed 12/31 18:00:00.000]] loggedWatch=false Wed 7/16 18:12:35.795 VF processing job null nPlayin=false Wed 7/16 18:12:35.795 VF thread is now waiting for 0:00:00.000 Wed 7/16 18:12:39.014 VideoFrame.watch(A[70838,70601,"Touched by an Angel",11221@0716.18:00,60]) Wed 7/16 18:12:39.014 setUI(sage.bl@f2a45d) Wed 7/16 18:12:39.014 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:12:39.030 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/16 18:12:39.030 VF.submitJob(VFJob[TimeSet r=0.0 t=9223372036854775807 file=null ifn=null]) Wed 7/16 18:12:39.030 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/16 18:12:39.030 VF processing job VFJob[TimeSet r=0.0 t=9223372036854775807 file=null ifn=null] nPlayin=true Wed 7/16 18:12:39.045 VF processing on UI Thread VFJob[TimeSet r=0.0 t=9223372036854775807 file=null ifn=null] Wed 7/16 18:12:39.389 VF processing job null nPlayin=true Wed 7/16 18:12:39.389 VF thread is now waiting for 0:47:22.205 Wed 7/16 18:12:40.639 Processing channel change request isDown=false targetChan=null Wed 7/16 18:12:40.670 VideoFrame.watch(A[92968,92587,"BookTelevision",14877@0716.18:00,30]) Wed 7/16 18:12:40.670 Called Seeker.requestWatch(A[92968,92587,"BookTelevision",14877@0716.18:00,30]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:40.670 findBestEncoderForNow(A[92968,92587,"BookTelevision",14877@0716.18:00,30] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:40.670 MARK 1 currRecord=A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:40.670 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:40.670 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:40.670 newRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] Wed 7/16 18:12:40.670 Change in record, logging recorded data. Wed 7/16 18:12:40.670 Seeker.endRecord(Wed 7/16 18:12:40.670) currRecord=A[70838,70601,"Touched by an Angel",11221@0716.18:00,60] Wed 7/16 18:12:40.670 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:40.670 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:40.670 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[92968,92587,"BookTelevision",14877@0716.18:00,30], currTime=Wed 7/16 18:12:40.670) currRecord=null switch=true Wed 7/16 18:12:40.670 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:40.670 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:40.670 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:42.170 Incomplete COM Read on handle 2700 Wed 7/16 18:12:42.170 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg]) Wed 7/16 18:12:42.170 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg] nPlayin=true Wed 7/16 18:12:42.170 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:42.186 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg] Wed 7/16 18:12:42.186 VF notified of Inactive File Wed 7/16 18:12:42.186 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:42.186 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:42.186 Added:Watched[id=177508 Airing=A[70838,70601,"Touched by an Angel",11221@0716.18:00,60], WatchStart=Wed 7/16 18:12:29.092, WatchEnd=Wed 7/16 18:12:39.907, RealStart=Wed 7/16 18:12:32.076, RealEnd=Wed 7/16 18:12:42.186] Wed 7/16 18:12:42.280 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:42.280 VF waiting for data to appear in new file... Wed 7/16 18:12:42.280 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:42.436 Scheduler awoken Wed 7/16 18:12:42.436 Scheduler.updateSchedule() called time=Wed 7/16 18:12:42.436 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:42.436 # Airs=0 Wed 7/16 18:12:42.451 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:42.451 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:42.451 Evaluating Potentials Wed 7/16 18:12:42.451 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:42.451 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:42.451 [] Wed 7/16 18:12:42.451 Seeker awoken Wed 7/16 18:12:42.451 MARK 1 currRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:42.451 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:42.451 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:42.451 newRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] Wed 7/16 18:12:42.451 Keeping record just the way it is. Wed 7/16 18:12:42.451 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 18:12:42.451 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:42.451 VF waiting for data to appear in new file... Wed 7/16 18:12:42.451 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:42.467 needFreeSize = 0.4995 GB currFreeSize=115.884490752 GB Wed 7/16 18:12:42.467 Seeker waiting for 17 mins. Wed 7/16 18:12:42.655 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=true Wed 7/16 18:12:42.655 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:42.655 targetTime = Wed 7/16 18:12:40.670 Wed 7/16 18:12:42.655 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:42.686 VF processing job null nPlayin=true Wed 7/16 18:12:42.686 VF thread is now waiting for 0:17:19.080 Wed 7/16 18:12:45.717 setUI(sage.ai@93595a) Wed 7/16 18:12:45.717 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:12:45.717 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:12:45.748 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 18:12:46.061 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null] Wed 7/16 18:12:46.061 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/16 18:12:46.061 VF Watch Complete Check currFile=MediaFile[A[92968,92587,"BookTelevision",14877@0716.18:00,30] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg, Seg0[Wed 7/16 18:12:25.451-Wed 7/16 18:12:29.092], Seg1[Wed 7/16 18:12:40.670-Wed 12/31 18:00:00.000]] loggedWatch=false Wed 7/16 18:12:46.076 VF processing job null nPlayin=false Wed 7/16 18:12:46.076 VF thread is now waiting for 0:00:00.000 Wed 7/16 18:12:50.186 VideoFrame.watch(A[111483,111310,"ControlFreak",16361@0716.18:00,120]) Wed 7/16 18:12:50.186 Called Seeker.requestWatch(A[111483,111310,"ControlFreak",16361@0716.18:00,120]) hostname=SAGETV PROCESS VF Wed 7/16 18:12:50.186 findBestEncoderForNow(A[111483,111310,"ControlFreak",16361@0716.18:00,120] record=false host=SAGETV PROCESS VF) Wed 7/16 18:12:50.186 MARK 1 currRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:50.186 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:50.186 FORCE WATCH executing clients=[SAGETV PROCESS VF] Wed 7/16 18:12:50.186 newRecord=A[111483,111310,"ControlFreak",16361@0716.18:00,120] Wed 7/16 18:12:50.186 Change in record, logging recorded data. Wed 7/16 18:12:50.186 Seeker.endRecord(Wed 7/16 18:12:50.186) currRecord=A[92968,92587,"BookTelevision",14877@0716.18:00,30] Wed 7/16 18:12:50.186 Change in record to another show. Entering device record mode. - LATER Wed 7/16 18:12:50.186 Change in record to another show. Entering device record mode. - NOW Wed 7/16 18:12:50.186 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[111483,111310,"ControlFreak",16361@0716.18:00,120], currTime=Wed 7/16 18:12:50.186) currRecord=null switch=true Wed 7/16 18:12:50.186 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:50.186 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture S-Video Wed 7/16 18:12:50.186 VideoStorage for new file: C:\Program Files\Frey Technologies\SageTV\Video - Use All Space Wed 7/16 18:12:50.186 Added:MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] Wed 7/16 18:12:50.186 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:12:51.686 Incomplete COM Read on handle 2700 Wed 7/16 18:12:51.686 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-1.mpg]) Wed 7/16 18:12:51.701 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-1.mpg] nPlayin=false Wed 7/16 18:12:51.701 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null]) Wed 7/16 18:12:51.701 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-1.mpg] Wed 7/16 18:12:51.701 VF notified of Inactive File Wed 7/16 18:12:51.733 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 18:12:51.733 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:51.733 Added:Watched[id=177510 Airing=A[92968,92587,"BookTelevision",14877@0716.18:00,30], WatchStart=Wed 7/16 18:12:25.451, WatchEnd=Wed 7/16 18:12:43.891, RealStart=Wed 7/16 18:12:42.655, RealEnd=Wed 7/16 18:12:51.733] Wed 7/16 18:12:51.905 setUI(sage.bl@f2a45d) Wed 7/16 18:12:51.905 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:12:51.905 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 18:12:51.905 VF waiting for data to appear in new file... Wed 7/16 18:12:51.905 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:51.951 Scheduler awoken Wed 7/16 18:12:51.951 Scheduler.updateSchedule() called time=Wed 7/16 18:12:51.951 manual=[] schedules={Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0 Wed 7/16 18:12:51.967 # Airs=0 Wed 7/16 18:12:51.967 SHOWS LOST TO CONFLICTS:[] Wed 7/16 18:12:51.967 MUST SEE FINAL-Hauppauge WinTV PVR PCI II Capture-[] Wed 7/16 18:12:51.967 Evaluating Potentials Wed 7/16 18:12:51.967 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Wed 7/16 18:12:51.967 Hauppauge WinTV PVR PCI II Capture Wed 7/16 18:12:51.967 [] Wed 7/16 18:12:51.967 Seeker awoken Wed 7/16 18:12:51.967 MARK 1 currRecord=A[111483,111310,"ControlFreak",16361@0716.18:00,120] enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF] Wed 7/16 18:12:51.967 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Wed 7/16 18:12:51.967 FORCE WATCH abandoned because it's currently being recorded clients=[SAGETV PROCESS VF] Wed 7/16 18:12:51.967 newRecord=A[111483,111310,"ControlFreak",16361@0716.18:00,120] Wed 7/16 18:12:51.967 Keeping record just the way it is. Wed 7/16 18:12:51.967 RootFile=C:\ fstype=NTFS fileLength=0 Wed 7/16 18:12:51.967 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 18:12:51.967 VF waiting for data to appear in new file... Wed 7/16 18:12:51.967 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:51.983 needFreeSize = 0.4995 GB currFreeSize=115.879604224 GB Wed 7/16 18:12:51.983 Seeker waiting for 107 mins. Wed 7/16 18:12:52.170 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 18:12:52.170 VF waiting for data to appear in new file... Wed 7/16 18:12:52.170 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:52.373 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 18:12:52.373 VF waiting for data to appear in new file... Wed 7/16 18:12:52.373 VF thread is now waiting for 0:00:00.200 Wed 7/16 18:12:52.576 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] nPlayin=false Wed 7/16 18:12:52.576 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[111483,111310,"ControlFreak",16361@0716.18:00,120] host=pvr C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg, Seg0[Wed 7/16 18:12:50.186-Wed 12/31 18:00:00.000]] ifn=null] Wed 7/16 18:12:52.576 targetTime = Wed 7/16 18:12:50.186 Wed 7/16 18:12:52.576 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Great - 2GB per hr Wed 7/16 18:12:52.733 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/16 18:12:52.733 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/16 18:12:52.733 VF processing job null nPlayin=true Wed 7/16 18:12:52.733 VF thread is now waiting for 1:47:09.564 Wed 7/16 18:12:57.280 PopupChoice [Close, Detailed Info] default=Detailed Info Wed 7/16 18:12:58.530 PopupChoice=Close Wed 7/16 18:13:03.889 Sage.exit() called. Wed 7/16 18:13:03.889 Killed Carny. Wed 7/16 18:13:03.889 Added:Watched[id=177511 Airing=A[111483,111310,"ControlFreak",16361@0716.18:00,120], WatchStart=Wed 7/16 18:12:50.186, WatchEnd=Wed 7/16 18:13:01.312, RealStart=Wed 7/16 18:12:52.576, RealEnd=Wed 7/16 18:13:03.889] Wed 7/16 18:13:03.920 Seeker.endRecord(Wed 7/16 18:13:03.920) currRecord=A[111483,111310,"ControlFreak",16361@0716.18:00,120] Wed 7/16 18:13:03.920 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture) Wed 7/16 18:13:03.920 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg]) Wed 7/16 18:13:03.920 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg] nPlayin=false Wed 7/16 18:13:03.920 VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Program Files\Frey Technologies\SageTV\Video\ControlFreak-111483-0.mpg] Wed 7/16 18:13:03.920 VideoFrame thread is now exiting Wed 7/16 18:13:04.045 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\100000Pyramid-96142-0.mpg Wed 7/16 18:13:04.045 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-0.mpg Wed 7/16 18:13:04.061 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\MatchGame-96374-1.mpg Wed 7/16 18:13:04.061 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\AmericasFunniestHomeVideos-17832-0.mpg Wed 7/16 18:13:04.061 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\TheWaltons-TheFestival-70776-0.mpg Wed 7/16 18:13:04.061 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\ChainofDesire-93033-0.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-0.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\NewYorkYankees100thAnniversary-56094-1.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-0.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\BlindDate-174124-1.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\CMTSmashHitsofCountry-21239-0.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-0.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\BookTelevision-92968-1.mpg Wed 7/16 18:13:04.076 Deleted media segment file C:\Program Files\Frey Technologies\SageTV\Video\TouchedbyanAngel-Legacy-70838-0.mpg Wed 7/16 18:13:04.076 Killed Seeker. Wed 7/16 18:13:04.076 Killed EPG. Wed 7/16 18:13:04.108 Killed Wizard. Wed 7/16 18:13:04.233 Killed MMC. Wed 7/16 18:13:04.233 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null]) Wed 7/16 18:13:04.264 Killed UIManager. Wed 7/16 18:13:04.264 Bye-bye. Wed 7/16 18:13:04.280 SageTV SHUTDOWN is activating!