Sat 7/5 17:01:18.657 CRASH occurred during saving or properties file. Using crash backup file.
Sat 7/5 17:01:19.078 user.dir2=C:\Program Files\Frey Technologies\SageTV
Sat 7/5 17:01:19.308 hostname=apollo
Sat 7/5 17:01:19.829 SageTV V1.4.2 Beta
Sat 7/5 17:01:22.913 Wizard starting to load database info...
Sat 7/5 17:01:22.983 DBFile at version 38 FileSize=4983701
Sat 7/5 17:01:22.993 Wizard allocating table for Network
Sat 7/5 17:01:22.993 Wizard loading main index for Network
Sat 7/5 17:01:23.013 Wizard allocating table for Channel
Sat 7/5 17:01:23.013 Wizard loading main index for Channel
Sat 7/5 17:01:23.013 Wizard allocating table for Title
Sat 7/5 17:01:23.013 Wizard loading main index for Title
Sat 7/5 17:01:23.143 Wizard building alt. index 0 for Title
Sat 7/5 17:01:23.164 Wizard allocating table for People
Sat 7/5 17:01:23.164 Wizard loading main index for People
Sat 7/5 17:01:23.494 Wizard building alt. index 0 for People
Sat 7/5 17:01:23.534 Wizard allocating table for Category
Sat 7/5 17:01:23.534 Wizard loading main index for Category
Sat 7/5 17:01:23.544 Wizard allocating table for SubCategory
Sat 7/5 17:01:23.544 Wizard loading main index for SubCategory
Sat 7/5 17:01:23.544 Wizard allocating table for Rated
Sat 7/5 17:01:23.544 Wizard loading main index for Rated
Sat 7/5 17:01:23.544 Wizard allocating table for ParentalRating
Sat 7/5 17:01:23.544 Wizard allocating table for ExtendedRating
Sat 7/5 17:01:23.544 Wizard loading main index for ExtendedRating
Sat 7/5 17:01:23.544 Wizard allocating table for Year
Sat 7/5 17:01:23.544 Wizard loading main index for Year
Sat 7/5 17:01:23.544 Wizard allocating table for PrimeTitle
Sat 7/5 17:01:23.544 Wizard loading main index for PrimeTitle
Sat 7/5 17:01:23.554 Wizard building alt. index 0 for PrimeTitle
Sat 7/5 17:01:23.564 Wizard allocating table for Bonus
Sat 7/5 17:01:23.564 Wizard loading main index for Bonus
Sat 7/5 17:01:23.564 Wizard allocating table for Show
Sat 7/5 17:01:23.564 Wizard loading main index for Show
Sat 7/5 17:01:26.338 Wizard building alt. index 0 for Show
Sat 7/5 17:01:26.408 Wizard allocating table for Airing
Sat 7/5 17:01:26.408 Wizard loading main index for Airing
Sat 7/5 17:01:28.311 Wizard building alt. index 0 for Airing
Sat 7/5 17:01:28.341 Wizard building alt. index 1 for Airing
Sat 7/5 17:01:28.371 Wizard allocating table for ManualRecord
Sat 7/5 17:01:28.371 Wizard loading main index for ManualRecord
Sat 7/5 17:01:28.371 Wizard allocating table for MediaFile
Sat 7/5 17:01:28.391 Wizard loading main index for MediaFile
Sat 7/5 17:01:28.391 Wizard building alt. index 0 for MediaFile
Sat 7/5 17:01:28.391 Wizard allocating table for Watched
Sat 7/5 17:01:28.401 Wizard loading main index for Watched
Sat 7/5 17:01:28.431 Wizard building alt. index 0 for Watched
Sat 7/5 17:01:28.441 Wizard allocating table for Agent
Sat 7/5 17:01:28.441 Wizard loading main index for Agent
Sat 7/5 17:01:28.471 Wizard building alt. index 0 for Agent
Sat 7/5 17:01:28.471 Wizard allocating table for Wasted
Sat 7/5 17:01:28.471 Wizard loading main index for Wasted
Sat 7/5 17:01:28.481 Wizard allocating table for Widget
Sat 7/5 17:01:28.481 Wizard processing transactional records...
Sat 7/5 17:01:28.491 Wizard performing validation on database objects...
Sat 7/5 17:01:28.531 Wizard DONE loading database info. loadTime=5.618 sec
Sat 7/5 17:01:28.681 DShowFilter=[.RAM file Parser, 9x8Resize, AC3 Parser Filter, ACELP.net Sipro Lab Audio Decoder, ACM Wrapper, Allocator Fix, 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, Bitmap Generate, Color Space Converter, CyberLink Audio Decoder, CyberLink Audio Effect, CyberLink DVD Navigator, CyberLink DxVA Filter 2, CyberLink Video/SP Decoder, Deinterlace Filter, DV Muxer, DV Splitter, DV Video Decoder, DVD Navigator, Elecard MPEG2 Demultiplexer, Elecard MPEG2 Video Decoder, Elecard PIM2 Null, Elecard PVA Demultiplexer, File Source (Async.), File Source (Netshow URL), File Source (URL), File stream renderer, File writer, Frame Eater, Full Screen Renderer, G.711 Codec, Hauppauge WinTV Color Format Converter, 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 Audio Decoder, InterVideo Audio Processor, InterVideo Navigator, InterVideo NonCSS Audio Decoder for Hauppauge, InterVideo NonCSS Video Decoder for Hauppauge, InterVideo Video Decoder, IVF source filter, Line 21 Decoder, Line 21 Decoder 2, Lyric Parser, MGI SoftDVD MAX Audio Decoder, Microsoft MPEG-4 Video Decompressor, Microsoft Screen Video Decompressor, MIDI Parser, Minimal Null, MJPEG Decompressor, MJTSReaderFilter, MJTSWriterFilter, Moonlight NicePheratu, Moonlight Odio Dekoda, MPEG Audio Decoder, MPEG Layer-3 Decoder, MPEG Video Decoder, MPEG-2 Demultiplexer, MPEG-2 Sections and Tables, MPEG-2 Splitter, MPEG-2 Video Stream Analyzer, MPEG-I Stream Splitter, MPEG2Dump, Mpeg4 Decoder DMO, Mpeg43 Decoder DMO, Mpeg4s Decoder DMO, Multi-file Parser, NSC file Parser, Null Renderer, Overlay Mixer, Overlay Mixer2, QT Decompressor, QuickTime Movie Parser, Record Queue, SageTV MpegDeMux, SageTV MpegMux, SageTV Music Visualization, SAMI (CC) Parser, SampleGrabber, Shared File Source (Async.), ShotBoundaryDet, Smart Tee, Sonic Cinemaster® ATI SPDIF Adapter, Sonic Cinemaster® DS Audio Decoder, Sonic Cinemaster® DS VCD Navigator, Sonic Cinemaster® DS Video Decoder, SP to TSP, StreamBufferSink, StreamBufferSource, StreamMachine MPEG Audio Compressor, Stretch Video, ULead MPEG Audio Decoder, ULead MPEG Encoder, ULead MPEG Splitter, ULead MPEG Video Decoder, Uncompressed Domain Shot Detection Filter, 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, WMSpeech Decoder DMO, WMT AudioAnalyzer, WMT Black Frame Generator, WMT DirectX Transform Wrapper, WMT DV Extract, WMT Format Conversion, WMT Import Filter, WMT Interlacer, WMT Log Filter, WMT MuxDeMux Filter, WMT Sample Information Filter, WMT Screen Capture filter, WMT Switch Filter, WMT VIH2 Fix, WMT Virtual Renderer, WMT Virtual Source, WMT Volume, WMV Screen decoder DMO, WMVideo Decoder DMO, WST Decoder, XML Playlist]
Sat 7/5 17:01:28.681 videoDevices=[Hauppauge WinTV PVR PCI II Capture, Hauppauge WinTV PVR PCI II Capture, HiPix DTV-200 Capture]
Sat 7/5 17:01:28.681 hwDecoderFilters=[]
Sat 7/5 17:01:28.681 audioRenderFilters=[M-Audio Revolution, Sonic Cinemaster® ATI SPDIF Adapter, Default DirectSound Device, Default WaveOut Device, DirectSound: M-Audio Revolution]
Sat 7/5 17:01:29.052 Initializing IR Transmission device...
Sat 7/5 17:01:29.052 SUCCESSFULLY opened IRTuner on port 1
Sat 7/5 17:01:30.244 LastChan=52
Sat 7/5 17:01:30.244 PVR250 Encoding Options=[MPEG2 Max Quality, Fair(Ntsc), DVD Long Play, Best(Ntsc), Great(Ntsc), SVCD Standard Play, Good(Ntsc), DVD Standard Play, DVD Extra Long Play, CVD, SVCD Long Play, SVCD Extra Long Play]
Sat 7/5 17:01:30.244 PVR250 Encoding Options=[MPEG2 Max Quality, Fair(Ntsc), DVD Long Play, Best(Ntsc), Great(Ntsc), SVCD Standard Play, Good(Ntsc), DVD Standard Play, DVD Extra Long Play, CVD, SVCD Long Play, SVCD Extra Long Play]
Sat 7/5 17:01:30.244 Seeker encOptions=[mpeg2 max quality, great(ntsc), best(ntsc), svcd standard play, svcd long play, fair(ntsc), svcd extra long play, dvd standard play, good(ntsc), dvd long play, dvd extra long play, cvd]
Sat 7/5 17:01:30.274 MMC.setCurrEncoder(HiPix DTV-200 Capture)
Sat 7/5 17:01:37.935 LastChan=
Sat 7/5 17:01:37.935 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 17:01:39.817 LastChan=52
Sat 7/5 17:01:39.817 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture)
Sat 7/5 17:01:42.351 LastChan=52
Sat 7/5 17:01:42.381 CARNY Processing 226 Agents & 8140 Airs
Sat 7/5 17:01:44.524 CARNY Negative Energy Size: 1914
Sat 7/5 17:01:44.524 CARNY Traitors:[]
Sat 7/5 17:01:44.534 VF thread is now running...
Sat 7/5 17:01:44.534 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:01:44.534 sage.be@1036b16 needs an update in 23:04:35
Sat 7/5 17:01:44.534 sage.be@16ba008 needs an update in 23:08:08
Sat 7/5 17:01:44.534 EPG needs an update in 1384 minutes
Sat 7/5 17:01:44.534 EPG's works is done. Waiting...
Sat 7/5 17:01:44.534 Carny got a Std job of null
Sat 7/5 17:01:44.564 Scheduler.updateSchedule() called time=Sat 7/5 17:01:44.564 manual=[ManualRecord[A[1125333,1124994,"Women of the Night IV",18429@0704.02:40,60] time=Fri 7/4 2:40:00.000 dur=1:00:00], ManualRecord[A[1117764,1117347,"Roundabout",16617@0704.13:00,60] time=Fri 7/4 13:00:00.000 dur=1:00:00], ManualRecord[A[1122102,1121779,"Scissors",18086@0704.23:30,110] time=Fri 7/4 23:30:00.000 dur=1:50:00], ManualRecord[A[1113031,1106816,"Manhattan",16153@0704.15:15,105] time=Fri 7/4 15:15:00.000 dur=1:45:00], ManualRecord[A[1050208,628947,"Star Wars: Episode II -- Attack of the Clones",10240@0705.16:00,150] time=Sat 7/5 16:00:00.000 dur=2:30:00], ManualRecord[A[1110659,1110544,"To Sleep With Anger",14873@0709.14:30,105] time=Wed 7/9 14:30:00.000 dur=1:45:00]] schedules={Hauppauge WinTV PVR PCI II Capture #2=[Sched=[] MustSee=[]], Hauppauge WinTV PVR PCI II Capture=[Sched=[] MustSee=[]]} scheduleRandSize=0
Sat 7/5 17:01:44.564 CARNY Processing 226 Agents & 8140 Airs
Sat 7/5 17:01:44.975 # Airs=197
Sat 7/5 17:01:45.005 CONFLICT FOUND IN MUST SEE INFORMATION*********
Sat 7/5 17:01:45.796 setUI(sage.w@1247c45)
Sat 7/5 17:01:45.876 VF.submitJob(VFJob[Sleep r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:01:45.876 VF processing job VFJob[Sleep r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:01:45.926 VF processing on UI Thread VFJob[Sleep r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:01:45.926 Seeker.finishWatch(SAGETV PROCESS VF)
Sat 7/5 17:01:45.926 VF processing job null nPlayin=false
Sat 7/5 17:01:45.926 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:01:45.976 VF processing job null nPlayin=false
Sat 7/5 17:01:45.976 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:01:46.016 Seeker waiting for 153722849656931 mins.
Sat 7/5 17:01:46.026 Seeker awoken
Sat 7/5 17:01:46.026 Seeker adding new Encoder: Hauppauge WinTV PVR PCI II Capture #2
Sat 7/5 17:01:46.026 Seeker adding new Encoder: Hauppauge WinTV PVR PCI II Capture
Sat 7/5 17:01:46.026 VF processing job null nPlayin=false
Sat 7/5 17:01:46.026 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:01:46.036 Seeker waiting for 153722849656931 mins.
Sat 7/5 17:01:53.177 CARNY Negative Energy Size: 1914
Sat 7/5 17:01:53.177 CARNY Traitors:[]
Sat 7/5 17:01:53.177 Carny waiting for awhile...
Sat 7/5 17:01:57.333 UIManager.gotoSleep(false) isTaskbar=true
Sat 7/5 17:01:57.453 setUI(sage.w@1247c45)
Sat 7/5 17:02:00.417 VideoFrame.watch(A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60])
Sat 7/5 17:02:00.417 Called Seeker.requestWatch(A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60]) hostname=SAGETV PROCESS VF
Sat 7/5 17:02:00.417 findBestEncoderForNow(A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] record=false host=SAGETV PROCESS VF)
Sat 7/5 17:02:00.417 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[SAGETV PROCESS VF]
Sat 7/5 17:02:00.417 defaultRecord=A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60]
Sat 7/5 17:02:00.417 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 17:02:00.417 FORCE WATCH executing clients=[SAGETV PROCESS VF]
Sat 7/5 17:02:00.417 newRecord=A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60]
Sat 7/5 17:02:00.417 Change in record to another show. Entering device record mode. - LATER
Sat 7/5 17:02:00.417 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[]
Sat 7/5 17:02:00.417 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 17:02:00.417 newRecord=null
Sat 7/5 17:02:00.417 NOTHING TO RECORD FOR NOW...
Sat 7/5 17:02:00.417 Change in record to another show. Entering device record mode. - NOW
Sat 7/5 17:02:00.417 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture #2 A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60], currTime=Sat 7/5 17:02:00.417) currRecord=null switch=false
Sat 7/5 17:02:00.417 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 17:02:02.400 LastChan=52
Sat 7/5 17:02:02.400 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture #2 S-Video
Sat 7/5 17:02:02.400 Seeker resetting the encoder because it'll pass the max duration
Sat 7/5 17:02:02.400 VideoStorage for new file: D:\Sage Files - Leave Free 20.0 GB
Sat 7/5 17:02:02.400 Added:MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]]
Sat 7/5 17:02:02.410 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 17:02:02.410 Playing IR tune command of 52
Sat 7/5 17:02:02.730 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]] ifn=null])
Sat 7/5 17:02:02.730 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 17:02:02.730 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 17:02:02.730 setUI(sage.bk@2cb5c6)
Sat 7/5 17:02:02.730 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:02:02.750 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 17:02:02.750 VF waiting for data to appear in new file...
Sat 7/5 17:02:02.750 VF thread is now waiting for 0:00:00.200
Sat 7/5 17:02:02.951 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 17:02:02.951 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 17:02:02.951 VF forcing to live.
Sat 7/5 17:02:02.951 targetTime = Sat 7/5 17:02:02.951
Sat 7/5 17:02:02.951 VideoFrame got an old file currEnc=null newEnc=Hauppauge WinTV PVR PCI II Capture #2 Best - 3GB per hr
Sat 7/5 17:02:03.471 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:02:03.471 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:02:03.471 VF processing job null nPlayin=true
Sat 7/5 17:02:03.482 VF thread is now waiting for 0:57:57.350
Sat 7/5 17:02:13.676 setUI(sage.ai@12faeca)
Sat 7/5 17:02:13.676 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:02:13.686 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:02:13.686 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:02:13.876 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:02:13.906 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:02:13.906 VF Watch Complete Check currFile=MediaFile[A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] host=apollo D:\Sage Files\TheRealFortKnox-1060433-0.mpg, Seg0[Sat 7/5 17:02:02.400-Wed 12/31 19:00:00.000]] loggedWatch=false
Sat 7/5 17:02:13.906 VF processing job null nPlayin=false
Sat 7/5 17:02:13.906 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:02:29.769 VideoFrame.watch(A[1061155,1060765,"Faking It",11158@0705.17:00,60])
Sat 7/5 17:02:29.769 Called Seeker.requestWatch(A[1061155,1060765,"Faking It",11158@0705.17:00,60]) hostname=SAGETV PROCESS VF
Sat 7/5 17:02:29.769 findBestEncoderForNow(A[1061155,1060765,"Faking It",11158@0705.17:00,60] record=false host=SAGETV PROCESS VF)
Sat 7/5 17:02:29.769 MARK 1 currRecord=A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60] enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[]
Sat 7/5 17:02:29.769 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 17:02:29.769 newRecord=null
Sat 7/5 17:02:29.769 Change in record, logging recorded data.
Sat 7/5 17:02:29.769 Seeker.endRecord(Sat 7/5 17:02:29.769) currRecord=A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60]
Sat 7/5 17:02:29.769 NOTHING TO RECORD FOR NOW...
Sat 7/5 17:02:29.769 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 17:02:29.769 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheRealFortKnox-1060433-0.mpg])
Sat 7/5 17:02:29.769 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheRealFortKnox-1060433-0.mpg] nPlayin=false
Sat 7/5 17:02:29.879 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF]
Sat 7/5 17:02:29.879 defaultRecord=A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60]
Sat 7/5 17:02:29.879 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 17:02:29.879 FORCE WATCH executing clients=[SAGETV PROCESS VF]
Sat 7/5 17:02:29.879 newRecord=A[1061155,1060765,"Faking It",11158@0705.17:00,60]
Sat 7/5 17:02:29.879 Change in record to another show. Entering device record mode. - LATER
Sat 7/5 17:02:29.879 Change in record to another show. Entering device record mode. - NOW
Sat 7/5 17:02:29.879 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[1061155,1060765,"Faking It",11158@0705.17:00,60], currTime=Sat 7/5 17:02:29.769) currRecord=null switch=false
Sat 7/5 17:02:29.879 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture)
Sat 7/5 17:02:29.879 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture TV Tuner
Sat 7/5 17:02:29.879 Seeker resetting the encoder because it'll pass the max duration
Sat 7/5 17:02:29.879 VideoStorage for new file: D:\Sage Files - Leave Free 20.0 GB
Sat 7/5 17:02:29.889 Added:MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Wed 12/31 19:00:00.000]]
Sat 7/5 17:02:29.889 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture)
Sat 7/5 17:02:30.671 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Wed 12/31 19:00:00.000]] ifn=null])
Sat 7/5 17:02:30.691 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheRealFortKnox-1060433-0.mpg]
Sat 7/5 17:02:30.691 VF notified of Inactive File
Sat 7/5 17:02:30.691 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 17:02:30.691 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 17:02:30.691 Added:Watched[id=1181783 Airing=A[1060433,1059814,"The Real Fort Knox",11150@0705.17:00,60], WatchStart=Sat 7/5 17:02:02.400, WatchEnd=Sat 7/5 17:02:12.737, RealStart=Sat 7/5 17:02:02.951, RealEnd=Sat 7/5 17:02:30.691]
Sat 7/5 17:02:30.821 setUI(sage.bk@2cb5c6)
Sat 7/5 17:02:30.821 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:02:30.831 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 17:02:30.831 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 17:02:30.841 targetTime = Sat 7/5 17:02:29.889
Sat 7/5 17:02:30.841 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture #2 Best - 3GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Best - 3GB per hr
Sat 7/5 17:02:30.841 VIDEOFRAME Needs to do A FULL SWITCH on the file due to ENCODING CHANGE
Sat 7/5 17:02:31.111 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:02:31.121 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:02:31.121 VF processing job null nPlayin=true
Sat 7/5 17:02:31.121 VF thread is now waiting for 0:57:29.861
Sat 7/5 17:06:46.038 Seeker awoken
Sat 7/5 17:06:46.038 Seeker.endRecord(Sat 7/5 17:06:46.038) currRecord=A[1061155,1060765,"Faking It",11158@0705.17:00,60]
Sat 7/5 17:06:46.048 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture)
Sat 7/5 17:06:46.048 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg])
Sat 7/5 17:06:46.048 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg] nPlayin=true
Sat 7/5 17:06:46.048 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg]
Sat 7/5 17:06:46.048 VF notified of Inactive File
Sat 7/5 17:06:46.048 VF processing job null nPlayin=true
Sat 7/5 17:06:46.048 VF thread is now waiting for 0:00:01.268
Sat 7/5 17:06:46.158 VF processing job null nPlayin=true
Sat 7/5 17:06:46.158 VF thread is now waiting for 0:00:01.158
Sat 7/5 17:06:46.188 Seeker waiting for 153722849656926 mins.
Sat 7/5 17:06:47.320 VF processing job null nPlayin=true
Sat 7/5 17:06:47.320 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:06:47.320 watchMe=null
Sat 7/5 17:06:47.320 VF processing job VFJob[StdComplete r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:06:47.320 VF processing on UI Thread VFJob[StdComplete r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:06:47.320 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:06:47.320 Added:Watched[id=1181784 Airing=A[1061155,1060765,"Faking It",11158@0705.17:00,60], WatchStart=Sat 7/5 17:02:29.889, WatchEnd=Sat 7/5 17:06:45.793, RealStart=Sat 7/5 17:02:30.841, RealEnd=Sat 7/5 17:06:47.320]
Sat 7/5 17:06:47.330 setUI(sage.ai@12faeca)
Sat 7/5 17:06:47.330 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:06:47.330 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:06:47.350 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:06:47.410 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:06:47.410 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:06:47.410 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:06:47.850 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:06:47.850 VF Watch Complete Check currFile=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Sat 7/5 17:06:46.038]] loggedWatch=true
Sat 7/5 17:06:47.850 VF processing job null nPlayin=false
Sat 7/5 17:06:47.850 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:06:55.962 setUI(sage.w@1247c45)
Sat 7/5 17:06:59.006 setUI(sage.w@cd5b3d)
Sat 7/5 17:07:01.520 setUI(sage.ai@12faeca)
Sat 7/5 17:07:03.102 PopupChoice  [Close, Detailed Info, Watched, Add to Favorites, Don't Like, Record, Additional Airings] default=Detailed Info
Sat 7/5 17:07:06.647 PopupChoice=Record
Sat 7/5 17:07:06.647 Called Seeker.record(A[1061155,1060765,"Faking It",11158@0705.17:00,60])
Sat 7/5 17:07:06.677 findBestEncoderForNow(A[1061155,1060765,"Faking It",11158@0705.17:00,60] record=true host=SAGETV PROCESS VF)
Sat 7/5 17:07:06.677 Added:ManualRecord[A[1061155,1060765,"Faking It",11158@0705.17:00,60] time=Sat 7/5 17:00:00.000 dur=1:00:00]
Sat 7/5 17:07:09.361 VideoFrame.watch(A[1061155,1060765,"Faking It",11158@0705.17:00,60])
Sat 7/5 17:07:09.361 setUI(sage.bk@2cb5c6)
Sat 7/5 17:07:09.361 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:07:09.371 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:07:09.371 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:07:09.371 VF processing job null nPlayin=true
Sat 7/5 17:07:09.371 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:09.371 watchMe=null
Sat 7/5 17:07:09.371 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:13.487 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Sat 7/5 17:07:13.487 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:13.487 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Sat 7/5 17:07:13.497 VF processing job null nPlayin=true
Sat 7/5 17:07:13.497 VF thread is now waiting for 0:00:04.750
Sat 7/5 17:07:13.507 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Sat 7/5 17:07:13.507 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:13.517 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Sat 7/5 17:07:13.517 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Sat 7/5 17:07:13.537 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:13.537 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Sat 7/5 17:07:13.547 VF processing job null nPlayin=true
Sat 7/5 17:07:13.547 VF thread is now waiting for 0:00:24.750
Sat 7/5 17:07:38.293 VF processing job null nPlayin=true
Sat 7/5 17:07:38.293 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:38.293 watchMe=null
Sat 7/5 17:07:38.293 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:38.483 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:38.483 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:38.483 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:38.493 VF processing job null nPlayin=true
Sat 7/5 17:07:38.493 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:38.493 watchMe=null
Sat 7/5 17:07:38.493 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:39.174 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:39.174 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:39.174 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:39.174 VF processing job null nPlayin=true
Sat 7/5 17:07:39.174 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:39.174 watchMe=null
Sat 7/5 17:07:39.174 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:39.675 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:39.675 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:39.675 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:39.685 VF processing job null nPlayin=true
Sat 7/5 17:07:39.685 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:39.685 watchMe=null
Sat 7/5 17:07:39.685 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:41.698 setUI(sage.ai@12faeca)
Sat 7/5 17:07:41.698 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:07:41.698 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:07:41.708 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:41.718 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:07:41.728 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:07:41.728 VF Watch Complete Check currFile=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Sat 7/5 17:06:46.038]] loggedWatch=true
Sat 7/5 17:07:41.728 VF processing job null nPlayin=false
Sat 7/5 17:07:41.728 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:44.342 PopupChoice  [Close, Detailed Info, Watched, Add to Favorites, Don't Like, Cancel Recording, Change Recording Quality, Modify Start Time, Modify Stop Time, Additional Airings] default=Detailed Info
Sat 7/5 17:07:55.037 PopupChoice=Close
Sat 7/5 17:07:55.888 VideoFrame.watch(A[1061155,1060765,"Faking It",11158@0705.17:00,60])
Sat 7/5 17:07:55.888 setUI(sage.bk@2cb5c6)
Sat 7/5 17:07:55.888 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:07:55.888 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:07:55.898 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:07:55.898 VF processing job null nPlayin=true
Sat 7/5 17:07:55.898 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:55.898 watchMe=null
Sat 7/5 17:07:55.898 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:57.761 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:57.761 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:57.761 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:57.761 VF processing job null nPlayin=true
Sat 7/5 17:07:57.771 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:57.771 watchMe=null
Sat 7/5 17:07:57.771 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:58.272 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:58.272 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:58.272 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:58.272 VF processing job null nPlayin=true
Sat 7/5 17:07:58.272 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:58.272 watchMe=null
Sat 7/5 17:07:58.272 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:58.863 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:58.873 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:58.873 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:58.873 VF processing job null nPlayin=true
Sat 7/5 17:07:58.873 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:58.873 watchMe=null
Sat 7/5 17:07:58.873 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:58.883 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:58.883 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:58.883 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:58.883 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:58.893 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:58.893 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:58.893 VF processing job null nPlayin=true
Sat 7/5 17:07:58.893 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:58.893 watchMe=null
Sat 7/5 17:07:58.893 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:59.373 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:59.373 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:59.373 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:59.383 VF processing job null nPlayin=true
Sat 7/5 17:07:59.383 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:59.383 watchMe=null
Sat 7/5 17:07:59.383 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:07:59.383 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:59.383 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:07:59.383 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:59.383 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:59.393 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:07:59.393 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:07:59.403 VF processing job null nPlayin=true
Sat 7/5 17:07:59.403 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:07:59.403 watchMe=null
Sat 7/5 17:07:59.403 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:00.234 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:00.234 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:00.234 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:00.245 VF processing job null nPlayin=true
Sat 7/5 17:08:00.245 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:00.245 watchMe=null
Sat 7/5 17:08:00.245 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:00.775 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:00.775 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:00.775 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:00.775 VF processing job null nPlayin=true
Sat 7/5 17:08:00.775 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:00.775 watchMe=null
Sat 7/5 17:08:00.775 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:01.206 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:01.206 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:01.206 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:01.206 VF processing job null nPlayin=true
Sat 7/5 17:08:01.206 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:01.206 watchMe=null
Sat 7/5 17:08:01.206 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:02.117 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Sat 7/5 17:08:02.127 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:02.127 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Sat 7/5 17:08:02.127 VF processing job null nPlayin=true
Sat 7/5 17:08:02.127 VF thread is now waiting for 0:00:09.750
Sat 7/5 17:08:02.668 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:02.668 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:02.678 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:02.708 VF processing job null nPlayin=true
Sat 7/5 17:08:02.708 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:02.708 watchMe=null
Sat 7/5 17:08:02.708 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:04.350 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:04.350 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:04.350 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:04.350 VF processing job null nPlayin=true
Sat 7/5 17:08:04.350 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:04.350 watchMe=null
Sat 7/5 17:08:04.350 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:05.372 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:05.372 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:05.382 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:05.382 VF processing job null nPlayin=true
Sat 7/5 17:08:05.382 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:05.382 watchMe=null
Sat 7/5 17:08:05.382 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:05.382 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:05.382 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:05.392 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:05.392 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:05.392 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:05.392 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:05.412 VF processing job null nPlayin=true
Sat 7/5 17:08:05.412 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:05.412 watchMe=null
Sat 7/5 17:08:05.412 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:06.654 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:08:06.664 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:06.664 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:08:06.664 VF processing job null nPlayin=true
Sat 7/5 17:08:06.664 VideoFrame watching a live old airing, fixing it.
Sat 7/5 17:08:06.664 watchMe=null
Sat 7/5 17:08:06.664 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:07.865 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:08:07.865 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 17:08:07.875 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:08:07.875 VF processing job null nPlayin=false
Sat 7/5 17:08:07.875 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:08.496 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Sat 7/5 17:08:08.496 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:08.506 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Sat 7/5 17:08:08.506 VF processing job null nPlayin=false
Sat 7/5 17:08:08.506 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:09.778 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Sat 7/5 17:08:09.778 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:09.778 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Sat 7/5 17:08:09.808 VF processing job null nPlayin=false
Sat 7/5 17:08:09.808 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:10.459 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:10.459 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:10.469 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:10.499 VF processing job null nPlayin=false
Sat 7/5 17:08:10.499 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:10.880 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:10.880 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:10.880 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:10.910 VF processing job null nPlayin=false
Sat 7/5 17:08:10.910 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:11.330 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:11.330 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:11.330 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:11.330 VF processing job null nPlayin=false
Sat 7/5 17:08:11.330 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:11.721 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:11.721 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:11.721 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:11.731 VF processing job null nPlayin=false
Sat 7/5 17:08:11.731 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:11.991 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:11.991 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.001 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.011 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.011 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.011 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.011 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.031 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.031 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.041 VF processing job null nPlayin=false
Sat 7/5 17:08:12.041 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:12.282 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.282 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.282 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.302 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.302 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.302 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.302 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.322 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.322 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.332 VF processing job null nPlayin=false
Sat 7/5 17:08:12.332 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:12.502 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.502 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.502 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.512 VF processing job null nPlayin=false
Sat 7/5 17:08:12.512 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:12.512 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.512 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sat 7/5 17:08:12.512 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.512 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.522 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:12.522 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sat 7/5 17:08:12.522 VF processing job null nPlayin=false
Sat 7/5 17:08:12.522 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:13.974 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:08:13.984 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:13.984 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:08:13.984 VF processing job null nPlayin=false
Sat 7/5 17:08:13.984 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:15.086 setUI(sage.ai@12faeca)
Sat 7/5 17:08:15.086 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:08:15.086 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:15.086 VF Watch Complete Check currFile=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Sat 7/5 17:06:46.038]] loggedWatch=true
Sat 7/5 17:08:15.086 VF processing job null nPlayin=false
Sat 7/5 17:08:15.086 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:20.213 VideoFrame.watch(A[1061155,1060765,"Faking It",11158@0705.17:00,60])
Sat 7/5 17:08:20.213 setUI(sage.bk@2cb5c6)
Sat 7/5 17:08:31.309 UIManager.gotoSleep(true) isTaskbar=false
Sat 7/5 17:08:31.309 Sage is going to SLEEP
Sat 7/5 17:08:31.309 setUI(sage.w@1247c45)
Sat 7/5 17:08:31.309 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:08:31.309 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:31.309 VF Watch Complete Check currFile=MediaFile[A[1061155,1060765,"Faking It",11158@0705.17:00,60] host=apollo D:\Sage Files\FakingIt-IvyLeaguetoBigLeague-1061155-0.mpg, Seg0[Sat 7/5 17:02:29.889-Sat 7/5 17:06:46.038]] loggedWatch=true
Sat 7/5 17:08:31.309 VF processing job null nPlayin=false
Sat 7/5 17:08:31.309 VF.submitJob(VFJob[Sleep r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:08:31.389 VF processing job VFJob[Sleep r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:31.419 VF processing on UI Thread VFJob[Sleep r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:08:31.419 Seeker.finishWatch(SAGETV PROCESS VF)
Sat 7/5 17:08:31.419 Seeker awoken
Sat 7/5 17:08:31.449 Seeker waiting for 153722849656924 mins.
Sat 7/5 17:08:31.499 Seeker awoken
Sat 7/5 17:08:31.499 VF processing job null nPlayin=false
Sat 7/5 17:08:31.499 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:31.509 Seeker waiting for 153722849656924 mins.
Sat 7/5 17:08:34.734 UIManager.gotoSleep(false) isTaskbar=true
Sat 7/5 17:08:34.854 setUI(sage.w@1247c45)
Sat 7/5 17:08:34.924 setUI(sage.w@1247c45)
Sat 7/5 17:08:37.037 UIManager.gotoSleep(true) isTaskbar=false
Sat 7/5 17:08:37.037 Sage is going to SLEEP
Sat 7/5 17:08:37.037 setUI(sage.w@1247c45)
Sat 7/5 17:08:37.037 VF.submitJob(VFJob[Sleep r=0.0 t=0 file=null ifn=null])
Sat 7/5 17:08:37.097 VF processing job VFJob[Sleep r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 17:08:37.108 VF processing on UI Thread VFJob[Sleep r=0.0 t=0 file=null ifn=null]
Sat 7/5 17:08:37.108 Seeker.finishWatch(SAGETV PROCESS VF)
Sat 7/5 17:08:37.108 Seeker awoken
Sat 7/5 17:08:37.118 VF processing job null nPlayin=false
Sat 7/5 17:08:37.118 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:37.168 VF processing job null nPlayin=false
Sat 7/5 17:08:37.168 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:08:37.188 Seeker waiting for 153722849656924 mins.
Sat 7/5 17:08:40.202 UIManager.gotoSleep(false) isTaskbar=true
Sat 7/5 17:08:40.212 setUI(sage.w@1247c45)
Sat 7/5 17:08:40.362 setUI(sage.w@1247c45)
Sat 7/5 17:08:41.624 setUI(sage.w@cd5b3d)
Sat 7/5 17:08:42.856 setUI(sage.ai@139f0bb)
Sat 7/5 17:09:02.704 setUI(sage.w@1247c45)
Sat 7/5 17:09:04.867 setUI(sage.w@1a1c1f5)
Sat 7/5 17:09:08.683 setUI(sage.w@1247c45)
Sat 7/5 17:09:10.055 setUI(sage.w@cd5b3d)
Sat 7/5 17:09:11.187 setUI(sage.aw$1@4fe90)
Sat 7/5 17:09:15.933 setUI(sage.ai@12faeca)
Sat 7/5 17:13:37.189 Seeker awoken
Sat 7/5 17:13:37.209 VF processing job null nPlayin=false
Sat 7/5 17:13:37.209 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:13:37.259 Seeker waiting for 153722849656919 mins.
Sat 7/5 17:18:37.260 Seeker awoken
Sat 7/5 17:18:37.271 VF processing job null nPlayin=false
Sat 7/5 17:18:37.271 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:18:37.291 Seeker waiting for 153722849656914 mins.
Sat 7/5 17:23:37.292 Seeker awoken
Sat 7/5 17:23:37.312 VF processing job null nPlayin=false
Sat 7/5 17:23:37.312 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:23:37.332 Seeker waiting for 153722849656909 mins.
Sat 7/5 17:28:37.333 Seeker awoken
Sat 7/5 17:28:37.333 VF processing job null nPlayin=false
Sat 7/5 17:28:37.343 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:28:37.363 Seeker waiting for 153722849656904 mins.
Sat 7/5 17:29:20.976 setUI(sage.g[loc=java.awt.Point[x=0,y=0] size=java.awt.Dimension[width=0,height=0]])
Sat 7/5 17:33:37.365 Seeker awoken
Sat 7/5 17:33:37.365 VF processing job null nPlayin=false
Sat 7/5 17:33:37.365 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:33:37.375 Seeker waiting for 153722849656899 mins.
Sat 7/5 17:38:37.376 Seeker awoken
Sat 7/5 17:38:37.376 VF processing job null nPlayin=false
Sat 7/5 17:38:37.376 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:38:37.386 Seeker waiting for 153722849656894 mins.
Sat 7/5 17:43:37.388 Seeker awoken
Sat 7/5 17:43:37.388 VF processing job null nPlayin=false
Sat 7/5 17:43:37.398 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:43:37.398 Seeker waiting for 153722849656889 mins.
Sat 7/5 17:48:37.399 Seeker awoken
Sat 7/5 17:48:37.399 VF processing job null nPlayin=false
Sat 7/5 17:48:37.399 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:48:37.409 Seeker waiting for 153722849656884 mins.
Sat 7/5 17:53:37.410 Seeker awoken
Sat 7/5 17:53:37.410 VF processing job null nPlayin=false
Sat 7/5 17:53:37.410 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:53:37.420 Seeker waiting for 153722849656879 mins.
Sat 7/5 17:58:37.422 Seeker awoken
Sat 7/5 17:58:37.422 VF processing job null nPlayin=false
Sat 7/5 17:58:37.422 VF thread is now waiting for 0:00:00.000
Sat 7/5 17:58:37.432 Seeker waiting for 153722849656874 mins.
Sat 7/5 18:01:53.183 Carny waiting for awhile...
Sat 7/5 18:03:37.433 Seeker awoken
Sat 7/5 18:03:37.433 VF processing job null nPlayin=false
Sat 7/5 18:03:37.433 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:03:37.443 Seeker waiting for 153722849656869 mins.
Sat 7/5 18:08:37.445 Seeker awoken
Sat 7/5 18:08:37.445 VF processing job null nPlayin=false
Sat 7/5 18:08:37.445 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:08:37.455 Seeker waiting for 153722849656864 mins.
Sat 7/5 18:13:37.456 Seeker awoken
Sat 7/5 18:13:37.456 VF processing job null nPlayin=false
Sat 7/5 18:13:37.456 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:13:37.466 Seeker waiting for 153722849656859 mins.
Sat 7/5 18:18:37.467 Seeker awoken
Sat 7/5 18:18:37.467 VF processing job null nPlayin=false
Sat 7/5 18:18:37.467 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:18:37.477 Seeker waiting for 153722849656854 mins.
Sat 7/5 18:23:37.479 Seeker awoken
Sat 7/5 18:23:37.489 VF processing job null nPlayin=false
Sat 7/5 18:23:37.489 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:23:37.499 Seeker waiting for 153722849656849 mins.
Sat 7/5 18:28:37.500 Seeker awoken
Sat 7/5 18:28:37.500 VF processing job null nPlayin=false
Sat 7/5 18:28:37.500 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:28:37.510 Seeker waiting for 153722849656844 mins.
Sat 7/5 18:33:37.512 Seeker awoken
Sat 7/5 18:33:37.512 VF processing job null nPlayin=false
Sat 7/5 18:33:37.512 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:33:37.522 Seeker waiting for 153722849656839 mins.
Sat 7/5 18:38:37.523 Seeker awoken
Sat 7/5 18:38:37.523 VF processing job null nPlayin=false
Sat 7/5 18:38:37.523 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:38:37.533 Seeker waiting for 153722849656834 mins.
Sat 7/5 18:43:37.534 Seeker awoken
Sat 7/5 18:43:37.534 VF processing job null nPlayin=false
Sat 7/5 18:43:37.534 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:43:37.544 Seeker waiting for 153722849656829 mins.
Sat 7/5 18:48:37.546 Seeker awoken
Sat 7/5 18:48:37.546 VF processing job null nPlayin=false
Sat 7/5 18:48:37.546 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:48:37.556 Seeker waiting for 153722849656824 mins.
Sat 7/5 18:53:37.557 Seeker awoken
Sat 7/5 18:53:37.557 VF processing job null nPlayin=false
Sat 7/5 18:53:37.557 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:53:37.567 Seeker waiting for 153722849656819 mins.
Sat 7/5 18:58:37.569 Seeker awoken
Sat 7/5 18:58:37.579 VF processing job null nPlayin=false
Sat 7/5 18:58:37.579 VF thread is now waiting for 0:00:00.000
Sat 7/5 18:58:37.579 Seeker waiting for 153722849656814 mins.
Sat 7/5 19:01:53.180 Carny waiting for awhile...
Sat 7/5 19:03:37.580 Seeker awoken
Sat 7/5 19:03:37.580 VF processing job null nPlayin=false
Sat 7/5 19:03:37.580 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:03:37.590 Seeker waiting for 153722849656809 mins.
Sat 7/5 19:08:37.591 Seeker awoken
Sat 7/5 19:08:37.591 VF processing job null nPlayin=false
Sat 7/5 19:08:37.591 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:08:37.601 Seeker waiting for 153722849656804 mins.
Sat 7/5 19:13:37.603 Seeker awoken
Sat 7/5 19:13:37.603 VF processing job null nPlayin=false
Sat 7/5 19:13:37.603 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:13:37.603 Seeker waiting for 153722849656799 mins.
Sat 7/5 19:18:37.614 Seeker awoken
Sat 7/5 19:18:37.614 VF processing job null nPlayin=false
Sat 7/5 19:18:37.614 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:18:37.624 Seeker waiting for 153722849656794 mins.
Sat 7/5 19:23:37.625 Seeker awoken
Sat 7/5 19:23:37.625 VF processing job null nPlayin=false
Sat 7/5 19:23:37.625 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:23:37.625 Seeker waiting for 153722849656789 mins.
Sat 7/5 19:28:37.627 Seeker awoken
Sat 7/5 19:28:37.637 VF processing job null nPlayin=false
Sat 7/5 19:28:37.637 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:28:37.637 Seeker waiting for 153722849656784 mins.
Sat 7/5 19:33:37.638 Seeker awoken
Sat 7/5 19:33:37.638 VF processing job null nPlayin=false
Sat 7/5 19:33:37.638 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:33:37.648 Seeker waiting for 153722849656779 mins.
Sat 7/5 19:38:37.650 Seeker awoken
Sat 7/5 19:38:37.650 VF processing job null nPlayin=false
Sat 7/5 19:38:37.650 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:38:37.660 Seeker waiting for 153722849656774 mins.
Sat 7/5 19:43:37.661 Seeker awoken
Sat 7/5 19:43:37.671 VF processing job null nPlayin=false
Sat 7/5 19:43:37.671 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:43:37.671 Seeker waiting for 153722849656769 mins.
Sat 7/5 19:48:37.672 Seeker awoken
Sat 7/5 19:48:37.672 VF processing job null nPlayin=false
Sat 7/5 19:48:37.672 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:48:37.692 Seeker waiting for 153722849656764 mins.
Sat 7/5 19:53:37.694 Seeker awoken
Sat 7/5 19:53:37.694 VF processing job null nPlayin=false
Sat 7/5 19:53:37.694 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:53:37.704 Seeker waiting for 153722849656759 mins.
Sat 7/5 19:58:37.705 Seeker awoken
Sat 7/5 19:58:37.705 VF processing job null nPlayin=false
Sat 7/5 19:58:37.705 VF thread is now waiting for 0:00:00.000
Sat 7/5 19:58:37.705 Seeker waiting for 153722849656754 mins.
Sat 7/5 20:01:53.186 Carny waiting for awhile...
Sat 7/5 20:03:37.707 Seeker awoken
Sat 7/5 20:03:37.707 VF processing job null nPlayin=false
Sat 7/5 20:03:37.717 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:03:37.717 Seeker waiting for 153722849656749 mins.
Sat 7/5 20:08:37.718 Seeker awoken
Sat 7/5 20:08:37.718 VF processing job null nPlayin=false
Sat 7/5 20:08:37.718 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:08:37.728 Seeker waiting for 153722849656744 mins.
Sat 7/5 20:13:37.729 Seeker awoken
Sat 7/5 20:13:37.729 VF processing job null nPlayin=false
Sat 7/5 20:13:37.729 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:13:37.739 Seeker waiting for 153722849656739 mins.
Sat 7/5 20:18:37.741 Seeker awoken
Sat 7/5 20:18:37.741 VF processing job null nPlayin=false
Sat 7/5 20:18:37.741 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:18:37.751 Seeker waiting for 153722849656734 mins.
Sat 7/5 20:23:37.752 Seeker awoken
Sat 7/5 20:23:37.752 VF processing job null nPlayin=false
Sat 7/5 20:23:37.752 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:23:37.762 Seeker waiting for 153722849656729 mins.
Sat 7/5 20:28:37.764 Seeker awoken
Sat 7/5 20:28:37.764 VF processing job null nPlayin=false
Sat 7/5 20:28:37.764 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:28:37.774 Seeker waiting for 153722849656724 mins.
Sat 7/5 20:33:37.775 Seeker awoken
Sat 7/5 20:33:37.775 VF processing job null nPlayin=false
Sat 7/5 20:33:37.775 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:33:37.785 Seeker waiting for 153722849656719 mins.
Sat 7/5 20:38:37.786 Seeker awoken
Sat 7/5 20:38:37.786 VF processing job null nPlayin=false
Sat 7/5 20:38:37.786 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:38:37.796 Seeker waiting for 153722849656714 mins.
Sat 7/5 20:43:37.798 Seeker awoken
Sat 7/5 20:43:37.798 VF processing job null nPlayin=false
Sat 7/5 20:43:37.798 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:43:37.808 Seeker waiting for 153722849656709 mins.
Sat 7/5 20:45:52.401 setUI(sage.ai@12faeca)
Sat 7/5 20:46:22.865 setUI(sage.ai@12faeca)
Sat 7/5 20:46:23.646 setUI(sage.ai@12faeca)
Sat 7/5 20:46:39.018 VideoFrame.watch(A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120])
Sat 7/5 20:46:39.018 Called Seeker.requestWatch(A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120]) hostname=SAGETV PROCESS VF
Sat 7/5 20:46:39.018 findBestEncoderForNow(A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] record=false host=SAGETV PROCESS VF)
Sat 7/5 20:46:39.018 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[SAGETV PROCESS VF]
Sat 7/5 20:46:39.018 defaultRecord=A[1060220,1060011,"American Chopper",11150@0705.20:00,60]
Sat 7/5 20:46:39.018 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 20:46:39.018 FORCE WATCH executing clients=[SAGETV PROCESS VF]
Sat 7/5 20:46:39.018 newRecord=A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120]
Sat 7/5 20:46:39.018 Change in record to another show. Entering device record mode. - LATER
Sat 7/5 20:46:39.018 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[]
Sat 7/5 20:46:39.018 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 20:46:39.018 newRecord=null
Sat 7/5 20:46:39.018 NOTHING TO RECORD FOR NOW...
Sat 7/5 20:46:39.018 Change in record to another show. Entering device record mode. - NOW
Sat 7/5 20:46:39.018 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture #2 A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120], currTime=Sat 7/5 20:46:39.018) currRecord=null switch=false
Sat 7/5 20:46:39.018 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 20:46:39.018 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture #2 S-Video
Sat 7/5 20:46:39.018 VideoStorage for new file: D:\Sage Files - Leave Free 20.0 GB
Sat 7/5 20:46:39.018 Added:MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Wed 12/31 19:00:00.000]]
Sat 7/5 20:46:39.018 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 20:46:39.018 Playing IR tune command of 55
Sat 7/5 20:46:39.329 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Wed 12/31 19:00:00.000]] ifn=null])
Sat 7/5 20:46:39.329 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 20:46:39.329 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 20:46:39.329 setUI(sage.bk@2cb5c6)
Sat 7/5 20:46:39.399 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 20:46:39.399 VF waiting for data to appear in new file...
Sat 7/5 20:46:39.399 VF thread is now waiting for 0:00:00.200
Sat 7/5 20:46:39.599 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false
Sat 7/5 20:46:39.599 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 20:46:39.599 targetTime = Sat 7/5 20:46:39.018
Sat 7/5 20:46:39.599 VideoFrame got an old file currEnc=null newEnc=Hauppauge WinTV PVR PCI II Capture #2 Best - 3GB per hr
Sat 7/5 20:46:39.900 VF processing job null nPlayin=true
Sat 7/5 20:46:39.900 VF thread is now waiting for 1:13:20.732
Sat 7/5 20:48:37.809 Seeker awoken
Sat 7/5 20:48:37.809 Seeker.endRecord(Sat 7/5 20:48:37.809) currRecord=A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120]
Sat 7/5 20:48:37.809 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 20:48:37.809 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheWizardofOz-1084572-0.mpg])
Sat 7/5 20:48:37.809 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheWizardofOz-1084572-0.mpg] nPlayin=true
Sat 7/5 20:48:37.809 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheWizardofOz-1084572-0.mpg]
Sat 7/5 20:48:37.809 VF notified of Inactive File
Sat 7/5 20:48:37.809 VF processing job null nPlayin=true
Sat 7/5 20:48:37.809 VF thread is now waiting for 0:00:00.789
Sat 7/5 20:48:37.919 VF processing job null nPlayin=true
Sat 7/5 20:48:37.919 VF thread is now waiting for 0:00:00.680
Sat 7/5 20:48:37.939 Seeker waiting for 153722849656704 mins.
Sat 7/5 20:48:38.600 VF processing job null nPlayin=true
Sat 7/5 20:48:38.600 VideoFrame watching a live old airing, fixing it.
Sat 7/5 20:48:38.600 watchMe=null
Sat 7/5 20:48:38.600 VF processing job VFJob[StdComplete r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 20:48:38.600 VF processing on UI Thread VFJob[StdComplete r=0.0 t=0 file=null ifn=null]
Sat 7/5 20:48:38.600 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 20:48:38.600 Added:Watched[id=1181787 Airing=A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120], WatchStart=Sat 7/5 20:46:39.018, WatchEnd=Sat 7/5 20:48:37.560, RealStart=Sat 7/5 20:46:39.599, RealEnd=Sat 7/5 20:48:38.600]
Sat 7/5 20:48:38.600 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 20:48:38.600 setUI(sage.ai@12faeca)
Sat 7/5 20:48:38.600 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 20:48:38.610 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null])
Sat 7/5 20:48:38.620 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 20:48:38.620 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 20:48:38.620 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 20:48:38.731 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 20:48:38.731 VF Watch Complete Check currFile=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809]] loggedWatch=true
Sat 7/5 20:48:38.731 VF processing job null nPlayin=false
Sat 7/5 20:48:38.731 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:48:46.642 VideoFrame.watch(A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120])
Sat 7/5 20:48:46.642 setUI(sage.bk@2cb5c6)
Sat 7/5 20:48:46.642 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null])
Sat 7/5 20:48:46.652 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 20:48:46.652 VF processing on UI Thread VFJob[Play r=0.0 t=0 file=null ifn=null]
Sat 7/5 20:48:46.652 VF processing job null nPlayin=true
Sat 7/5 20:48:46.652 VideoFrame watching a live old airing, fixing it.
Sat 7/5 20:48:46.652 watchMe=null
Sat 7/5 20:48:46.662 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:48:53.311 Processing channel change request isDown=false targetChan=null
Sat 7/5 20:48:53.321 VideoFrame.watch(A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120])
Sat 7/5 20:48:53.321 Called Seeker.requestWatch(A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120]) hostname=SAGETV PROCESS VF
Sat 7/5 20:48:53.321 findBestEncoderForNow(A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] record=false host=SAGETV PROCESS VF)
Sat 7/5 20:48:53.321 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[SAGETV PROCESS VF]
Sat 7/5 20:48:53.321 defaultRecord=A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120]
Sat 7/5 20:48:53.321 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 20:48:53.321 FORCE WATCH executing clients=[SAGETV PROCESS VF]
Sat 7/5 20:48:53.321 newRecord=A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120]
Sat 7/5 20:48:53.321 Change in record to another show. Entering device record mode. - LATER
Sat 7/5 20:48:53.321 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[]
Sat 7/5 20:48:53.321 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 20:48:53.321 newRecord=null
Sat 7/5 20:48:53.321 NOTHING TO RECORD FOR NOW...
Sat 7/5 20:48:53.332 Change in record to another show. Entering device record mode. - NOW
Sat 7/5 20:48:53.332 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture #2 A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120], currTime=Sat 7/5 20:48:53.321) currRecord=null switch=false
Sat 7/5 20:48:53.332 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 20:48:53.332 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture #2 S-Video
Sat 7/5 20:48:53.332 VideoStorage for new file: D:\Sage Files - Leave Free 20.0 GB
Sat 7/5 20:48:53.332 Added:MediaFile[A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] host=apollo D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg, Seg0[Sat 7/5 20:48:53.332-Wed 12/31 19:00:00.000]]
Sat 7/5 20:48:53.332 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 20:48:53.332 Playing IR tune command of 56
Sat 7/5 20:48:53.622 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] host=apollo D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg, Seg0[Sat 7/5 20:48:53.332-Wed 12/31 19:00:00.000]] ifn=null])
Sat 7/5 20:48:53.622 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] host=apollo D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg, Seg0[Sat 7/5 20:48:53.332-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true
Sat 7/5 20:48:53.622 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] host=apollo D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg, Seg0[Sat 7/5 20:48:53.332-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 20:48:53.642 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] host=apollo D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg, Seg0[Sat 7/5 20:48:53.332-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true
Sat 7/5 20:48:53.642 VF waiting for data to appear in new file...
Sat 7/5 20:48:53.642 VF thread is now waiting for 0:00:00.200
Sat 7/5 20:48:53.842 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] host=apollo D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg, Seg0[Sat 7/5 20:48:53.332-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true
Sat 7/5 20:48:53.842 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] host=apollo D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg, Seg0[Sat 7/5 20:48:53.332-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 20:48:53.842 targetTime = Sat 7/5 20:48:53.332
Sat 7/5 20:48:53.852 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture #2 Best - 3GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture #2 Best - 3GB per hr
Sat 7/5 20:48:53.982 VF processing job null nPlayin=true
Sat 7/5 20:48:53.982 VF thread is now waiting for 0:11:06.418
Sat 7/5 20:49:00.001 Processing channel change request isDown=true targetChan=null
Sat 7/5 20:49:00.001 VideoFrame.watch(A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120])
Sat 7/5 20:49:00.011 Called Seeker.requestWatch(A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120]) hostname=SAGETV PROCESS VF
Sat 7/5 20:49:00.011 findBestEncoderForNow(A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] record=false host=SAGETV PROCESS VF)
Sat 7/5 20:49:00.011 MARK 1 currRecord=A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120] enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[]
Sat 7/5 20:49:00.011 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 20:49:00.011 newRecord=null
Sat 7/5 20:49:00.011 Change in record, logging recorded data.
Sat 7/5 20:49:00.011 Seeker.endRecord(Sat 7/5 20:49:00.011) currRecord=A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120]
Sat 7/5 20:49:00.011 NOTHING TO RECORD FOR NOW...
Sat 7/5 20:49:00.011 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture #2)
Sat 7/5 20:49:00.011 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg])
Sat 7/5 20:49:00.011 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg] nPlayin=true
Sat 7/5 20:49:00.121 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[SAGETV PROCESS VF]
Sat 7/5 20:49:00.121 defaultRecord=A[1169899,1169870,"Trading Spaces",11158@0705.20:00,60]
Sat 7/5 20:49:00.121 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Sat 7/5 20:49:00.121 FORCE WATCH executing clients=[SAGETV PROCESS VF]
Sat 7/5 20:49:00.121 newRecord=A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120]
Sat 7/5 20:49:00.121 Change in record to another show. Entering device record mode. - LATER
Sat 7/5 20:49:00.131 Change in record to another show. Entering device record mode. - NOW
Sat 7/5 20:49:00.131 Seeker.startRecord(Hauppauge WinTV PVR PCI II Capture A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120], currTime=Sat 7/5 20:49:00.011) currRecord=null switch=false
Sat 7/5 20:49:00.131 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture)
Sat 7/5 20:49:00.131 Setting up MMC video for recording new show & tuning channel conn=Hauppauge WinTV PVR PCI II Capture TV Tuner
Sat 7/5 20:49:00.151 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture)
Sat 7/5 20:49:00.852 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809], Seg1[Sat 7/5 20:49:00.011-Wed 12/31 19:00:00.000]] ifn=null])
Sat 7/5 20:49:00.852 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg]
Sat 7/5 20:49:00.852 VF notified of Inactive File
Sat 7/5 20:49:00.852 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809], Seg1[Sat 7/5 20:49:00.011-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true
Sat 7/5 20:49:00.852 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809], Seg1[Sat 7/5 20:49:00.011-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 20:49:00.862 Added:Watched[id=1181789 Airing=A[1052713,1052282,"The Advocate's Devil",10918@0705.19:00,120], WatchStart=Sat 7/5 20:48:53.332, WatchEnd=Sat 7/5 20:48:59.810, RealStart=Sat 7/5 20:48:53.852, RealEnd=Sat 7/5 20:49:00.852]
Sat 7/5 20:49:00.892 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809], Seg1[Sat 7/5 20:49:00.011-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true
Sat 7/5 20:49:00.892 VF waiting for data to appear in new file...
Sat 7/5 20:49:00.892 VF thread is now waiting for 0:00:00.200
Sat 7/5 20:49:01.093 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809], Seg1[Sat 7/5 20:49:00.011-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true
Sat 7/5 20:49:01.093 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809], Seg1[Sat 7/5 20:49:00.011-Wed 12/31 19:00:00.000]] ifn=null]
Sat 7/5 20:49:01.093 targetTime = Sat 7/5 20:49:00.011
Sat 7/5 20:49:01.093 VideoFrame got an old file currEnc=Hauppauge WinTV PVR PCI II Capture #2 Best - 3GB per hr newEnc=Hauppauge WinTV PVR PCI II Capture Best - 3GB per hr
Sat 7/5 20:49:01.093 VIDEOFRAME Needs to do A FULL SWITCH on the file due to ENCODING CHANGE
Sat 7/5 20:49:01.243 VF processing job null nPlayin=true
Sat 7/5 20:49:01.243 VF thread is now waiting for 1:10:59.739
Sat 7/5 20:49:08.874 UIManager.gotoSleep(true) isTaskbar=false
Sat 7/5 20:49:08.874 Sage is going to SLEEP
Sat 7/5 20:49:08.874 setUI(sage.w@1247c45)
Sat 7/5 20:49:08.874 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null])
Sat 7/5 20:49:08.884 VF.submitJob(VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null])
Sat 7/5 20:49:08.884 VF.submitJob(VFJob[Sleep r=0.0 t=0 file=null ifn=null])
Sat 7/5 20:49:08.894 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=true
Sat 7/5 20:49:08.984 VF processing on UI Thread VFJob[Pause r=0.0 t=0 file=null ifn=null]
Sat 7/5 20:49:09.024 VF processing job VFJob[WatchCompleteCheck r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 20:49:09.024 VF Watch Complete Check currFile=MediaFile[A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120] host=apollo D:\Sage Files\TheWizardofOz-1084572-0.mpg, Seg0[Sat 7/5 20:46:39.018-Sat 7/5 20:48:37.809], Seg1[Sat 7/5 20:49:00.011-Wed 12/31 19:00:00.000]] loggedWatch=false
Sat 7/5 20:49:09.024 VF processing job VFJob[Sleep r=0.0 t=0 file=null ifn=null] nPlayin=false
Sat 7/5 20:49:09.024 VF processing on UI Thread VFJob[Sleep r=0.0 t=0 file=null ifn=null]
Sat 7/5 20:49:09.024 Seeker.finishWatch(SAGETV PROCESS VF)
Sat 7/5 20:49:09.024 Updated:Watched[id=1181787 Airing=A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120], WatchStart=Sat 7/5 20:46:39.018, WatchEnd=Sat 7/5 20:49:07.461, RealStart=Sat 7/5 20:46:39.599, RealEnd=Sat 7/5 20:49:09.024]
Sat 7/5 20:49:09.044 Seeker awoken
Sat 7/5 20:49:09.044 Seeker.endRecord(Sat 7/5 20:49:09.044) currRecord=A[1084572,1083320,"The Wizard of Oz",12852@0705.20:00,120]
Sat 7/5 20:49:09.044 MMC.setCurrEncoder(Hauppauge WinTV PVR PCI II Capture)
Sat 7/5 20:49:09.044 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheWizardofOz-1084572-1.mpg])
Sat 7/5 20:49:09.144 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheWizardofOz-1084572-1.mpg] nPlayin=false
Sat 7/5 20:49:09.144 VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=D:\Sage Files\TheWizardofOz-1084572-1.mpg]
Sat 7/5 20:49:09.154 VF processing job null nPlayin=false
Sat 7/5 20:49:09.154 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:49:09.164 Seeker waiting for 153722849656703 mins.
Sat 7/5 20:49:09.164 Seeker awoken
Sat 7/5 20:49:09.164 VF processing job null nPlayin=false
Sat 7/5 20:49:09.164 VF thread is now waiting for 0:00:00.000
Sat 7/5 20:49:09.174 Seeker waiting for 153722849656703 mins.
Sat 7/5 20:50:21.408 Screen pos= 619, 579
Sat 7/5 20:50:22.750 Sage.exit() called.
Sat 7/5 20:50:22.750 Killed Carny.
Sat 7/5 20:50:22.750 Deleted media segment file D:\Sage Files\TheRealFortKnox-1060433-0.mpg
Sat 7/5 20:50:22.760 Deleted media segment file D:\Sage Files\TheWizardofOz-1084572-0.mpg
Sat 7/5 20:50:22.760 Deleted media segment file D:\Sage Files\TheWizardofOz-1084572-1.mpg
Sat 7/5 20:50:22.760 Deleted media segment file D:\Sage Files\TheAdvocatesDevil-1052713-0.mpg
Sat 7/5 20:50:22.760 Killed Seeker.
Sat 7/5 20:50:22.760 Killed EPG.
Sat 7/5 20:50:22.760 Killed Wizard.
Sat 7/5 20:50:22.970 Killed MMC.
Sat 7/5 20:50:23.591 Killed UIManager.
Sat 7/5 20:50:23.601 Bye-bye.
Sat 7/5 20:50:23.651 SageTV SHUTDOWN is activating!