Wed 7/11 18:55:42.743 user.dir2=C:\Program Files\SageTV\SageTV Wed 7/11 18:55:42.744 classpath=C:\Program Files\SageTV\SageTV\Sage.jar;C:\Program Files\SageTV\SageTV\plugin.jar;C:\Program Files\SageTV\SageTV\;.;C:\Program Files\Java\jre1.6.0_01\lib\ext\QTJava.zip;JARs\commons-jxpath-1.1.jar;JARs\commons-logging-api.jar;JARs\commons-logging.jar;JARs\jcifs-1.1.6.jar;JARs\jdic.jar;JARs\mx4j-impl.jar;JARs\mx4j-jmx.jar;JARs\mx4j-remote.jar;JARs\mx4j-tools.jar;JARs\nielm_sageutls.jar;JARs\nielm_sagewebserver.jar;JARs\nielm_sagexmlinfo.jar;JARs\sbbi-jmx-1.0.jar;JARs\sbbi-upnplib-1.0.3.jar;JARs\servlet.jar Wed 7/11 18:55:42.745 JVM version=1.6.0_01 Wed 7/11 18:55:42.745 OS=Windows XP 5.1 Wed 7/11 18:55:42.747 Succesfully setup system shell hook Wed 7/11 18:55:42.747 not specifiy WinkeyboardHook in registery, load default one Wed 7/11 18:55:43.037 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/splashclient.gif Wed 7/11 18:55:43.052 ImageUtils creating BI copy sun.awt.image.ToolkitImage@7ced01 w=520 h=350 freeMem=21867936 totalMem=25034752 Wed 7/11 18:55:43.090 SageTV V6.1.9.146 Wed 7/11 18:55:43.097 hostname=office Wed 7/11 18:55:43.196 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon.gif Wed 7/11 18:55:43.198 ImageUtils creating BI copy sun.awt.image.ToolkitImage@6eb38a w=32 h=32 freeMem=21372592 totalMem=25034752 Wed 7/11 18:55:43.274 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim0.png Wed 7/11 18:55:43.293 ImageUtils creating BI copy sun.awt.image.ToolkitImage@13c5982 w=121 h=121 freeMem=20663776 totalMem=25034752 Wed 7/11 18:55:43.295 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim1.png Wed 7/11 18:55:43.311 ImageUtils creating BI copy sun.awt.image.ToolkitImage@c21495 w=121 h=121 freeMem=20384128 totalMem=25034752 Wed 7/11 18:55:43.313 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim2.png Wed 7/11 18:55:43.327 ImageUtils creating BI copy sun.awt.image.ToolkitImage@a0dcd9 w=121 h=121 freeMem=20135232 totalMem=25034752 Wed 7/11 18:55:43.333 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim3.png Wed 7/11 18:55:43.339 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1cfb549 w=121 h=121 freeMem=21194624 totalMem=25034752 Wed 7/11 18:55:43.340 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim4.png Wed 7/11 18:55:43.345 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1820dda w=121 h=121 freeMem=20928864 totalMem=25034752 Wed 7/11 18:55:43.346 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim5.png Wed 7/11 18:55:43.352 ImageUtils creating BI copy sun.awt.image.ToolkitImage@422ede w=121 h=121 freeMem=20659824 totalMem=25034752 Wed 7/11 18:55:43.353 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim6.png Wed 7/11 18:55:43.359 ImageUtils creating BI copy sun.awt.image.ToolkitImage@93dcd w=121 h=121 freeMem=20398728 totalMem=25034752 Wed 7/11 18:55:43.360 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim7.png Wed 7/11 18:55:43.366 ImageUtils creating BI copy sun.awt.image.ToolkitImage@110b053 w=121 h=121 freeMem=20143200 totalMem=25034752 Wed 7/11 18:55:43.479 Wizard starting to load database info... Wed 7/11 18:55:43.490 Wizard DONE loading database info. loadTime=0.0010 sec Wed 7/11 18:55:43.526 SageTV received connection from:Socket[addr=/192.168.1.101,port=42024,localport=1185] Wed 7/11 18:55:44.171 Wizard allocating table for Year of size 25 Wed 7/11 18:55:44.172 Wizard loading main index for Year Wed 7/11 18:55:44.172 Wizard allocating table for Network of size 87 Wed 7/11 18:55:44.173 Wizard loading main index for Network Wed 7/11 18:55:44.175 Wizard allocating table for Title of size 2319 Wed 7/11 18:55:44.175 Wizard loading main index for Title Wed 7/11 18:55:44.217 Wizard building alt. index 0 for Title Wed 7/11 18:55:44.221 Wizard allocating table for Channel of size 40 Wed 7/11 18:55:44.222 Wizard loading main index for Channel Wed 7/11 18:55:44.224 Wizard allocating table for People of size 6358 Wed 7/11 18:55:44.225 Wizard loading main index for People Wed 7/11 18:55:44.297 Wizard building alt. index 0 for People Wed 7/11 18:55:44.305 Wizard allocating table for SubCategory of size 66 Wed 7/11 18:55:44.306 Wizard loading main index for SubCategory Wed 7/11 18:55:44.307 Wizard allocating table for Rated of size 5 Wed 7/11 18:55:44.307 Wizard loading main index for Rated Wed 7/11 18:55:44.307 Wizard allocating table for ParentalRating of size 0 Wed 7/11 18:55:44.308 Wizard allocating table for ExtendedRating of size 9 Wed 7/11 18:55:44.308 Wizard loading main index for ExtendedRating Wed 7/11 18:55:44.308 Wizard allocating table for Category of size 50 Wed 7/11 18:55:44.309 Wizard loading main index for Category Wed 7/11 18:55:44.309 Wizard allocating table for PrimeTitle of size 0 Wed 7/11 18:55:44.309 Wizard allocating table for Bonus of size 7 Wed 7/11 18:55:44.310 Wizard loading main index for Bonus Wed 7/11 18:55:44.311 Wizard allocating table for Show of size 1749 Wed 7/11 18:55:44.319 Wizard loading main index for Show Wed 7/11 18:55:44.496 Wizard building alt. index 0 for Show Wed 7/11 18:55:44.499 Wizard allocating table for Airing of size 3954 Wed 7/11 18:55:44.500 Wizard loading main index for Airing Wed 7/11 18:55:44.563 Wizard building alt. index 0 for Airing Wed 7/11 18:55:44.566 Wizard building alt. index 1 for Airing Wed 7/11 18:55:44.569 Wizard allocating table for ManualRecord of size 3 Wed 7/11 18:55:44.570 Wizard loading main index for ManualRecord Wed 7/11 18:55:44.574 Wizard allocating table for MediaFile of size 23 Wed 7/11 18:55:44.574 Wizard loading main index for MediaFile Wed 7/11 18:55:44.597 Wizard building alt. index 0 for MediaFile Wed 7/11 18:55:44.597 Wizard building alt. index 1 for MediaFile Wed 7/11 18:55:44.598 Wizard allocating table for Watched of size 36 Wed 7/11 18:55:44.599 Wizard loading main index for Watched Wed 7/11 18:55:44.601 Wizard building alt. index 0 for Watched Wed 7/11 18:55:44.602 Wizard allocating table for Agent of size 3 Wed 7/11 18:55:44.602 Wizard loading main index for Agent Wed 7/11 18:55:44.608 Wizard building alt. index 0 for Agent Wed 7/11 18:55:44.608 Wizard allocating table for Wasted of size 0 Wed 7/11 18:55:44.608 Wizard allocating table for Playlist of size 0 Wed 7/11 18:55:44.608 Wizard allocating table for TVEditorial of size 30 Wed 7/11 18:55:44.609 Wizard loading main index for TVEditorial Wed 7/11 18:55:44.616 Wizard allocating table for SeriesInfo of size 1908 Wed 7/11 18:55:44.616 Wizard loading main index for SeriesInfo Wed 7/11 18:55:45.136 SageTV received connection from:Socket[addr=/192.168.1.101,port=42024,localport=1186] Wed 7/11 18:55:45.224 MMC calling detectCaptureDevices on sage.p@1434234 Wed 7/11 18:55:45.237 devices detected=[FusionHDTV, WDM Video Capture] Wed 7/11 18:55:45.238 EncoderMap={FusionHDTV, WDM Video Capture=FusionHDTV, WDM Video Capture} Wed 7/11 18:55:45.292 Checking video directories for new files Wed 7/11 18:55:45.360 DShowFilters=[.RAM file Parser, 9x8Resize, AC3 Parser Filter, AC3Filter, 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, Bitmap Generate, Color Space Converter, Deinterlace Filter, DScaler Renderer, DV Muxer, DV Splitter, DV Video Decoder, DVD Navigator, ffdshow Audio Decoder, ffdshow Audio Processor, ffdshow MPEG-4 Video Decoder, ffdshow raw video filter, File Source (Async.), File Source (Netshow URL), File Source (URL), File stream renderer, File writer, Frame Eater, Full Screen Renderer, G.711 Codec, Haali Matroska Muxer, Haali Media Splitter, Haali Media Splitter (AR), Haali Simple Media Splitter, Haali Video Renderer, Haali Video Sink, HighMAT and MPV Navigator Filter, HighMAT/MPV Navigator Client Filter, Indeo® audio softwar, Indeo® video 4.4 Compression Filte, Indeo® video 4.4 Decompression Filte, Indeo® video 5.10 Compression Filte, Indeo® video 5.10 Decompression Filte, Infinite Pin Tee Filter, Internal Script Command Renderer, Line 21 Decoder, Line 21 Decoder 2, 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 Splitter, MPEG-2 Video Stream Analyzer, MPEG-I Stream Splitter, MPEG2Dump, Mpeg4 Decoder DMO, Mpeg43 Decoder DMO, Mpeg4s Decoder DMO, Multi-file Parser, Nero Audible Decoder, Nero Audio CD Filter, Nero Audio CD Navigator, Nero Audio Decoder 2, Nero Audio Sample Renderer, Nero Audio Stream Renderer, Nero AV Synchronizer, Nero Deinterlace, Nero Digital AVC Audio Encoder, Nero Digital AVC File Writer, Nero Digital AVC Muxer, Nero Digital AVC Null Renderer, Nero Digital AVC Subpicture Enc, Nero Digital AVC Video Enc, Nero Digital Parser, Nero DV Splitter, Nero DVD Decoder, Nero DVD Navigator, Nero ES Video Reader, Nero File Source, Nero File Source (Async.), Nero File Source / Splitter, Nero Format Converter, Nero Frame Capture, Nero InteractiveGraphics Decoder, Nero MP3 Encoder, Nero MP4 Splitter, Nero Mpeg2 Encoder, Nero Ogg Splitter, Nero Overlay Mixer, Nero Photo Source, Nero PresentationGraphics Decoder, Nero PS Muxer, Nero QuickTime(tm) Audio Decoder, Nero QuickTime(tm) Video Decoder, Nero Resize, Nero Scene Detector, Nero Scene Detector 2, Nero Sound Processor, Nero Sound Switcher, Nero Splitter, Nero Stream Buffer Sink, Nero Stream Buffer Source, Nero Subpicture Decoder, Nero Subtitle, Nero Thumbnail Decoder, Nero Vcd Navigator, Nero Video Analyzer, Nero Video Decoder, Nero Video Decoder HD, Nero Video Processor, Nero Video Renderer, Nero Video Sample Renderer, Nero Video Stream Renderer, NSC file Parser, Null Renderer, NVIDIA ATSC File, NVIDIA Audio Decoder, NVIDIA MultiSource Filter, NVIDIA Transport Demux, NVIDIA TS Info Parser, NVIDIA Video Decoder, NVIDIA Video Post Processor, Overlay Mixer, Overlay Mixer2, QT Decompressor, QuickTime Movie Parser, RealPlayer Audio Filter, Record Queue, SageTV MPEG Audio Decoder, SageTV MPEG Audio Encoder, SageTV MPEG Encoder, SageTV MPEG Layer II Audio Encoder, SageTV MPEG Multiplexer, SageTV MPEG Splitter, SageTV MPEG Video Decoder, SageTV MPEG Video Encoder, SageTV MpegDeMux, SageTV MpegMux, SageTV Music Visualization, SageTV TS Splitter 1.0, SAMI (CC) Parser, SampleGrabber, Shared File Source (Async.), Smart Tee, StreamBufferSink, StreamBufferSource, Stretch Video, 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, Winamp(tm) Visualization Host Filter, Windows Media Audio Decoder, Windows Media Multiplexer, Windows Media source filter, Windows Media Update Filter, Windows Media Video Decoder, Windows Media Video Decoder, WM ASF Reader, WM ASF Writer, WMA Voice Decoder DMO, WMAPro over S/PDIF DMO, WMAudio 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 Advanced Decoder DMO, WMVideo Decoder DMO, WST Decoder, XML Playlist] Wed 7/11 18:55:45.393 sage.ay@bfc8e0 needs an update in 23:50:33 Wed 7/11 18:55:45.394 EPG needs an update in 110 minutes Wed 7/11 18:55:45.394 EPG's works is done. Waiting... Wed 7/11 18:55:45.403 MsgSend thread spawned for /192.168.1.101:42024 Wed 7/11 18:55:45.408 MsgRecv thread has spawned for /192.168.1.101:42024 Wed 7/11 18:55:45.409 VF thread is now running... Wed 7/11 18:55:45.415 VF thread is now waiting for 0:00:00.000 Wed 7/11 18:55:45.443 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon.gif Wed 7/11 18:55:45.447 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1bfc93a w=32 h=32 freeMem=13073520 totalMem=25034752 Wed 7/11 18:55:45.449 StorageDeviceDetector started... Wed 7/11 18:55:45.490 SetFullScreen(true) Wed 7/11 18:55:45.490 FullScreen set to true Wed 7/11 18:55:45.494 UIMgr loading UI from: C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageTV3.xml Wed 7/11 18:55:46.381 AWTThread Hang Detected - hang time = 768 Wed 7/11 18:55:46.958 locale = en Wed 7/11 18:55:47.127 AWTThread Hang Detected - hang time = 1514 Wed 7/11 18:55:47.272 UIMgr done loading UI from: C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageTV3.xml Wed 7/11 18:55:47.334 EVPoolSize=1 Wed 7/11 18:55:47.355 Hidden Extras are DISABLED Wed 7/11 18:55:47.411 DatePat=EEE, MMM d orgPat=EEEE, MMMM d, yyyy Wed 7/11 18:55:47.425 setUI(sage.b2@1a19458[Main Menu]) histIdx=0 uiHistory=[sage.b2@1a19458[Main Menu]] Wed 7/11 18:55:47.488 layoutContainer Wed 7/11 18:55:47.501 layoutContainer Wed 7/11 18:55:47.507 VF processing job null nPlayin=false Wed 7/11 18:55:47.510 VF thread is now waiting for 0:00:00.000 Wed 7/11 18:55:47.575 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=38] Wed 7/11 18:55:47.733 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=38] numGlyphs=1674 numCachedGlyphs=242 numImages=24 Wed 7/11 18:55:47.735 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=55] Wed 7/11 18:55:48.146 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=55] numGlyphs=1674 numCachedGlyphs=236 numImages=69 Wed 7/11 18:55:48.148 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=60] Wed 7/11 18:55:48.310 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=60] numGlyphs=1674 numCachedGlyphs=235 numImages=76 Wed 7/11 18:55:48.318 Initializing DirectX9 Wed 7/11 18:55:48.330 Using DX9 Full Screen Exclusive Mode Wed 7/11 18:55:48.330 Creating D3D Device Wed 7/11 18:55:48.393 Done Initializing DirectX9 Wed 7/11 18:55:48.394 DX9 max texture dimensions is 4096x4096 Wed 7/11 18:55:48.395 Max texture dimension was set as 4096 mine=4096 native=4096 vramlimit=120000000 Wed 7/11 18:55:48.399 Creating DirectX9 Texture from file in memory w=1024 h=576 Wed 7/11 18:55:48.526 Loading 169x59 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageLogo256small.png Wed 7/11 18:55:48.530 Creating DirectX9 Texture from nio buffer w=169 h=59 Wed 7/11 18:55:48.532 Loading 117x102 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\GoWindowed.png Wed 7/11 18:55:48.535 Creating DirectX9 Texture from nio buffer w=117 h=102 Wed 7/11 18:55:48.536 Loading 97x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\GoSleep.png Wed 7/11 18:55:48.541 Creating DirectX9 Texture from nio buffer w=97 h=121 Wed 7/11 18:55:48.542 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.543 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.545 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.546 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.548 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.549 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.550 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.552 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.553 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.554 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.555 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.556 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.557 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.559 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.560 Loading 586x47 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MenuBarLong.png Wed 7/11 18:55:48.561 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.562 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.563 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.565 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.566 Creating DirectX9 Texture from nio buffer w=586 h=47 Wed 7/11 18:55:48.568 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.569 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.570 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.571 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.574 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.575 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.577 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.578 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.582 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.583 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:48.586 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:50.042 EVPoolSize=2 Wed 7/11 18:55:50.042 Disabled SS Wed 7/11 18:55:50.043 VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:50.044 VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/11 18:55:50.044 CloseAndWait is executing the close job synchronously now Wed 7/11 18:55:50.044 VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:50.044 VF processing job null nPlayin=false Wed 7/11 18:55:50.045 VF thread is now waiting for 0:00:00.000 Wed 7/11 18:55:50.352 setUI(sage.b2@1955970[MediaPlayer OSD]) histIdx=1 uiHistory=[sage.b2@1a19458[Main Menu], sage.b2@1955970[MediaPlayer OSD]] Wed 7/11 18:55:50.501 Loading 380x380 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\VideoArt.png Wed 7/11 18:55:50.503 VideoFrame.watch(A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30]) Wed 7/11 18:55:50.503 watchThisFile=null Wed 7/11 18:55:50.504 Sending requestWatch to server for A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] Wed 7/11 18:55:50.528 Creating DirectX9 Texture from nio buffer w=380 h=380 Wed 7/11 18:55:50.532 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=43] Wed 7/11 18:55:50.671 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=43] numGlyphs=1674 numCachedGlyphs=241 numImages=28 Wed 7/11 18:55:50.672 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:50.760 VF.submitJob(VFJob[Play r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:50.761 VF processing job VFJob[Play r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/11 18:55:50.762 VF discarding job VFJob[Play r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:50.762 VF processing job null nPlayin=false Wed 7/11 18:55:50.762 VF.submitJob(VFJob[TimeSet r=0.0 t=1184194550761 file=null ifn=null]) Wed 7/11 18:55:50.763 VF thread is now waiting for 0:00:00.000 Wed 7/11 18:55:50.764 VF processing job VFJob[TimeSet r=0.0 t=1184194550761 file=null ifn=null] nPlayin=false Wed 7/11 18:55:50.764 VF discarding job VFJob[TimeSet r=0.0 t=1184194550761 file=null ifn=null] Wed 7/11 18:55:50.764 VF processing job null nPlayin=false Wed 7/11 18:55:50.765 VF thread is now waiting for 0:00:00.000 Wed 7/11 18:55:52.509 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null]) Wed 7/11 18:55:52.510 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 18:55:52.512 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] Wed 7/11 18:55:52.514 VideoFrame creating new media player for file:MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] Wed 7/11 18:55:52.518 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 18:55:52.519 VF network encoder playback detected: false Wed 7/11 18:55:52.533 VF waiting for data to appear in new file...liveWait=-1040 Wed 7/11 18:55:52.536 VF thread is now waiting for 0:00:00.200 Wed 7/11 18:55:52.622 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.624 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.626 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.627 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.628 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.629 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.630 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.631 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.632 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.633 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.635 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 18:55:52.636 Loading 29x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDChUp.png Wed 7/11 18:55:52.637 Creating DirectX9 Texture from nio buffer w=29 h=26 Wed 7/11 18:55:52.638 Loading 35x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDChDisplay.png Wed 7/11 18:55:52.638 Creating DirectX9 Texture from nio buffer w=35 h=26 Wed 7/11 18:55:52.639 Loading 29x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDChDown.png Wed 7/11 18:55:52.640 Creating DirectX9 Texture from nio buffer w=29 h=26 Wed 7/11 18:55:52.641 ImageUtils loading file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDStatusPause.png Wed 7/11 18:55:52.654 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1784427 w=6 h=8 freeMem=12678800 totalMem=51523584 Wed 7/11 18:55:52.655 Premultiplying alpha for BuffImage... Wed 7/11 18:55:52.655 Creating DirectX9 Texture from memory w=6 h=8 Wed 7/11 18:55:52.656 Loading 16x13 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolOn.png Wed 7/11 18:55:52.657 Creating DirectX9 Texture from nio buffer w=16 h=13 Wed 7/11 18:55:52.657 ImageUtils loading file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolBar.png Wed 7/11 18:55:52.659 ImageUtils creating BI copy sun.awt.image.ToolkitImage@6d1750 w=64 h=4 freeMem=12639416 totalMem=51523584 Wed 7/11 18:55:52.659 Premultiplying alpha for BuffImage... Wed 7/11 18:55:52.661 Creating DirectX9 Texture from memory w=64 h=4 Wed 7/11 18:55:52.661 Loading 9x15 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolSlider.png Wed 7/11 18:55:52.662 Creating DirectX9 Texture from nio buffer w=9 h=15 Wed 7/11 18:55:52.663 Loading 29x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDReplay.png Wed 7/11 18:55:52.664 Creating DirectX9 Texture from nio buffer w=29 h=26 Wed 7/11 18:55:52.664 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDRew.png Wed 7/11 18:55:52.665 Creating DirectX9 Texture from nio buffer w=30 h=26 Wed 7/11 18:55:52.666 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDStop.png Wed 7/11 18:55:52.667 Creating DirectX9 Texture from nio buffer w=30 h=26 Wed 7/11 18:55:52.667 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDPlay.png Wed 7/11 18:55:52.668 Creating DirectX9 Texture from nio buffer w=30 h=26 Wed 7/11 18:55:52.668 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDFwd.png Wed 7/11 18:55:52.669 Creating DirectX9 Texture from nio buffer w=30 h=26 Wed 7/11 18:55:52.670 Loading 29x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDSkip.png Wed 7/11 18:55:52.671 Creating DirectX9 Texture from nio buffer w=29 h=26 Wed 7/11 18:55:52.736 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 18:55:52.736 VF network encoder playback detected: false Wed 7/11 18:55:52.746 VF waiting for data to appear in new file...liveWait=-1256 Wed 7/11 18:55:52.746 VF thread is now waiting for 0:00:00.200 Wed 7/11 18:55:52.939 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 18:55:52.939 VF network encoder playback detected: false Wed 7/11 18:55:52.947 VF waiting for data to appear in new file...liveWait=-1458 Wed 7/11 18:55:52.947 VF thread is now waiting for 0:00:00.200 Wed 7/11 18:55:53.142 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 6 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 18:55:53.143 VF network encoder playback detected: false Wed 7/11 18:55:53.151 VF waiting for data to appear in new file...liveWait=-1662 Wed 7/11 18:55:53.151 VF thread is now waiting for 0:00:00.200 Wed 7/11 18:55:53.345 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 6 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 18:55:53.346 VF network encoder playback detected: false Wed 7/11 18:55:53.354 VF waiting for data to appear in new file...liveWait=-1865 Wed 7/11 18:55:53.355 VF thread is now waiting for 0:00:00.200 Wed 7/11 18:55:53.548 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 6 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 18:55:53.549 VF network encoder playback detected: false Wed 7/11 18:55:53.555 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 6 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] ifn=null] Wed 7/11 18:55:53.557 VF file=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 6 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] targetTime = Wed 7/11 18:55:51.481 Wed 7/11 18:55:53.558 VideoFrame.timeSelected(Wed 7/11 18:55:51.481, true) currFile=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 6 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] Wed 7/11 18:55:53.568 DShowPlayer setVideoRendererFilter0({51B4ABF3-748F-4E3B-A276-C828330E926A}) called Wed 7/11 18:55:53.607 Setting up VMR9 deinterlacing Wed 7/11 18:55:53.608 Curr Mix Prefs=0x1015 Wed 7/11 18:55:53.608 Set to 0x2015, hr=0x0, New Mix Prefs=0x2015 Wed 7/11 18:55:53.609 DShowPlayer setAudioRendererFilter0(SB Live! Wave Device) called Wed 7/11 18:55:53.661 DShowPlayer setVideoDecoderFilter0(NVIDIA Video Decoder) called Wed 7/11 18:55:53.943 DShowPlayer setAudioDecoderFilter0(NVIDIA Audio Decoder) called Wed 7/11 18:55:54.163 DShowPlayer setTimeshift=1 buffer=0 Wed 7/11 18:55:54.182 NATIVE Loading the source file Wed 7/11 18:55:54.198 NATIVE Setting up the MPEG demux Wed 7/11 18:55:54.222 NATIVE RenderAudio called stream=1 Wed 7/11 18:55:54.223 NATIVE RenderAudio step 1 Wed 7/11 18:55:54.229 NATIVE RenderAudio step 2 Wed 7/11 18:55:54.229 NATIVE RenderAudio step 3 Wed 7/11 18:55:54.232 NATIVE RenderVideo called Wed 7/11 18:55:54.232 NATIVE RenderVideo step 1 Wed 7/11 18:55:54.234 NATIVE RenderVideo step 2 Wed 7/11 18:55:54.234 Video source pin information: Wed 7/11 18:55:54.234 PIN is not connected yet!Wed 7/11 18:55:54.243 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.243 Interlaced video Wed 7/11 18:55:54.243 Both fields per sample Wed 7/11 18:55:54.243 Field 2 first Wed 7/11 18:55:54.243 Stream never contains a Field 2 Wed 7/11 18:55:54.243 Either bob or weave mode. Wed 7/11 18:55:54.243 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.243 Interlaced video Wed 7/11 18:55:54.243 Both fields per sample Wed 7/11 18:55:54.244 Field 2 first Wed 7/11 18:55:54.244 Stream never contains a Field 2 Wed 7/11 18:55:54.244 Either bob or weave mode. Wed 7/11 18:55:54.244 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.244 Interlaced video Wed 7/11 18:55:54.244 Both fields per sample Wed 7/11 18:55:54.244 Field 2 first Wed 7/11 18:55:54.244 Stream never contains a Field 2 Wed 7/11 18:55:54.244 Either bob or weave mode. Wed 7/11 18:55:54.244 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.245 Interlaced video Wed 7/11 18:55:54.245 Both fields per sample Wed 7/11 18:55:54.245 Field 2 first Wed 7/11 18:55:54.245 Stream never contains a Field 2 Wed 7/11 18:55:54.245 Either bob or weave mode. Wed 7/11 18:55:54.245 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.245 Interlaced video Wed 7/11 18:55:54.245 Both fields per sample Wed 7/11 18:55:54.245 Field 2 first Wed 7/11 18:55:54.245 Stream never contains a Field 2 Wed 7/11 18:55:54.246 Either bob or weave mode. Wed 7/11 18:55:54.246 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.246 Interlaced video Wed 7/11 18:55:54.246 Both fields per sample Wed 7/11 18:55:54.246 Field 2 first Wed 7/11 18:55:54.246 Stream never contains a Field 2 Wed 7/11 18:55:54.246 Either bob or weave mode. Wed 7/11 18:55:54.246 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.246 Interlaced video Wed 7/11 18:55:54.246 Both fields per sample Wed 7/11 18:55:54.246 Field 2 first Wed 7/11 18:55:54.247 Stream never contains a Field 2 Wed 7/11 18:55:54.247 Either bob or weave mode. Wed 7/11 18:55:54.247 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.247 Interlaced video Wed 7/11 18:55:54.247 Both fields per sample Wed 7/11 18:55:54.247 Field 2 first Wed 7/11 18:55:54.247 Stream never contains a Field 2 Wed 7/11 18:55:54.247 Either bob or weave mode. Wed 7/11 18:55:54.247 M type MEDIATYPE_Video S type MEDIASUBTYPE_YV12 F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.247 Interlaced video Wed 7/11 18:55:54.247 Both fields per sample Wed 7/11 18:55:54.248 Field 2 first Wed 7/11 18:55:54.248 Stream never contains a Field 2 Wed 7/11 18:55:54.248 Either bob or weave mode. Wed 7/11 18:55:54.248 M type MEDIATYPE_Video S type MEDIASUBTYPE_YUY2 F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.248 Interlaced video Wed 7/11 18:55:54.248 Both fields per sample Wed 7/11 18:55:54.248 Field 2 first Wed 7/11 18:55:54.248 Stream never contains a Field 2 Wed 7/11 18:55:54.248 Either bob or weave mode. Wed 7/11 18:55:54.248 M type MEDIATYPE_Video S type MEDIASUBTYPE_UYVY F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.248 Interlaced video Wed 7/11 18:55:54.249 Both fields per sample Wed 7/11 18:55:54.249 Field 2 first Wed 7/11 18:55:54.249 Stream never contains a Field 2 Wed 7/11 18:55:54.249 Either bob or weave mode. Wed 7/11 18:55:54.249 NATIVE RenderVideo step 3 Wed 7/11 18:55:54.408 AWTThread Hang Detected - hang time = 735 Wed 7/11 18:55:54.439 VMR9 alloc 1920x1080 Offscreen DefaultPool format=NV12 minBuffs=1 arx=1920 ary=1080 nativeWidth=1920 nativeHeight=1080 reqNumBuff=1 allocNumBuff=1 hr=0x0 Wed 7/11 18:55:54.447 Setting up VMR9 deinterlacing Wed 7/11 18:55:54.447 VM9Deinterlacing Tech: PixelAdaptive Wed 7/11 18:55:54.447 VM9Deinterlacing Tech: BOBVerticalStretch Wed 7/11 18:55:54.447 Actual deinterlace: hr=0x0 guid={212DC724-3235-44A4-BD29-E1652BBCC71C} Wed 7/11 18:55:54.448 deinterlace mode: hr=0x1 guid={00000000-0000-0000-0000-000000000000} Wed 7/11 18:55:54.448 Setting deinterlace mode to actual mode... Wed 7/11 18:55:54.448 deinterlace mode: hr=0x0 guid={212DC724-3235-44A4-BD29-E1652BBCC71C} Wed 7/11 18:55:54.448 Filter Graph Video Renderer Info: Wed 7/11 18:55:54.448 Video Renderer:CLSID_VideoMixingRenderer9 Wed 7/11 18:55:54.449 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 18:55:54.449 Interlaced video Wed 7/11 18:55:54.449 Both fields per sample Wed 7/11 18:55:54.449 Field 2 first Wed 7/11 18:55:54.449 Stream never contains a Field 2 Wed 7/11 18:55:54.449 Either bob or weave mode. Wed 7/11 18:55:54.449 VMR9 filter detected, using line 21 decoder 2.Wed 7/11 18:55:54.453 DShowGraphFilters=AudRend,VidRend,Line21 Decoder,Audio Decoder,Video Decoder,SageTV MPEG2 Demultiplexer,E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Wed 7/11 18:55:54.455 VideoFrame.timeSelected2(Wed 7/11 18:55:51.481, true) currFile=MediaFile[id=31143 A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 6 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg, Seg0[Wed 7/11 18:55:51.481-Wed 12/31 19:00:00.000]] realDur=0 Wed 7/11 18:55:54.455 About to perform media time selection for 0 Wed 7/11 18:55:54.455 Running the playback graph. Wed 7/11 18:55:54.456 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.472 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.477 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.482 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.483 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.484 Event: 0xd l1=0x0 l2=0x0 Wed 7/11 18:55:54.484 Event: 0xe l1=0x0 l2=0x0 Wed 7/11 18:55:54.496 EVPoolSize=3 Wed 7/11 18:55:54.498 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.522 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.522 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.523 VF processing job null nPlayin=true Wed 7/11 18:55:54.523 isRec=true rd=3042 base=43 Wed 7/11 18:55:54.524 VF thread is now waiting for 0:04:08.226 Wed 7/11 18:55:54.610 Loading 8x8 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDStatusPlay.png Wed 7/11 18:55:54.611 Creating DirectX9 Texture from nio buffer w=8 h=8 Wed 7/11 18:55:54.612 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDPause.png Wed 7/11 18:55:54.616 Creating DirectX9 Texture from nio buffer w=30 h=26 Wed 7/11 18:55:54.666 VMR9 alloc 1934x1088 Offscreen DefaultPool format=NV12 minBuffs=1 arx=1934 ary=1088 nativeWidth=1934 nativeHeight=1088 reqNumBuff=1 allocNumBuff=1 hr=0x0 Wed 7/11 18:55:54.667 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52215.img Wed 7/11 18:55:54.682 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:55:54.712 Created alternate surface Wed 7/11 18:55:54.714 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.714 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.715 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.715 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.715 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.716 VF processing job null nPlayin=true Wed 7/11 18:55:54.717 isRec=true rd=3236 base=142 Wed 7/11 18:55:54.717 VF thread is now waiting for 0:04:08.127 Wed 7/11 18:55:54.719 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.721 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.721 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.721 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.722 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.722 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.723 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.723 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.724 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.725 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.725 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.725 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.726 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.726 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.730 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52216.img Wed 7/11 18:55:54.730 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.730 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.731 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.732 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.733 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.733 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.733 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.734 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.735 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.736 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.736 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.736 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.736 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.737 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.738 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.738 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.739 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.740 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.740 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:55:54.742 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.743 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.744 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.744 VF processing job null nPlayin=true Wed 7/11 18:55:54.744 isRec=true rd=3263 base=171 Wed 7/11 18:55:54.745 VF thread is now waiting for 0:04:08.098 Wed 7/11 18:55:54.750 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.750 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.750 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.750 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.751 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.751 VF processing job null nPlayin=true Wed 7/11 18:55:54.751 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.751 isRec=true rd=3270 base=178 Wed 7/11 18:55:54.752 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.752 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.752 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.752 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.753 VF processing job null nPlayin=true Wed 7/11 18:55:54.753 isRec=true rd=3272 base=179 Wed 7/11 18:55:54.753 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:55:54.754 VF thread is now waiting for 0:04:08.090 Wed 7/11 18:55:54.754 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:55:54.754 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:55:54.755 DShowMediaPlayer is consuming the events... Wed 7/11 18:55:54.755 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:55:54.755 VF processing job null nPlayin=true Wed 7/11 18:55:54.756 isRec=true rd=3275 base=181 Wed 7/11 18:55:54.756 VF thread is now waiting for 0:04:08.087 Wed 7/11 18:55:54.794 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52217.img Wed 7/11 18:55:54.800 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:55:55.128 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52218.img Wed 7/11 18:55:55.133 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:55:55.147 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52219.img Wed 7/11 18:55:55.153 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:55:55.168 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52220.img Wed 7/11 18:55:55.174 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:55:55.189 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52221.img Wed 7/11 18:55:55.196 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:55:55.209 Loading 82x82 image from file C:\DOCUME~1\FAMILY~1.OFF\LOCALS~1\Temp\stv52222.img Wed 7/11 18:55:55.215 Creating DirectX9 Texture from nio buffer w=82 h=82 Wed 7/11 18:56:44.920 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.920 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.920 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.921 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.921 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.921 VF processing job null nPlayin=true Wed 7/11 18:56:44.921 isRec=true rd=53440 base=49626 Wed 7/11 18:56:44.922 VF thread is now waiting for 0:03:18.643 Wed 7/11 18:56:44.922 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.923 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.923 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.923 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.923 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.923 VF processing job null nPlayin=true Wed 7/11 18:56:44.924 isRec=true rd=53443 base=49629 Wed 7/11 18:56:44.924 VF thread is now waiting for 0:03:18.640 Wed 7/11 18:56:44.924 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.925 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.925 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.926 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.926 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.926 VF processing job null nPlayin=true Wed 7/11 18:56:44.927 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.927 isRec=true rd=53446 base=49632 Wed 7/11 18:56:44.930 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.931 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.931 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.932 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.932 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.932 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.932 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.933 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.933 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.933 VF processing job null nPlayin=true Wed 7/11 18:56:44.934 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.934 isRec=true rd=53453 base=49639 Wed 7/11 18:56:44.934 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.934 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.935 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.935 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.935 VF processing job null nPlayin=true Wed 7/11 18:56:44.935 isRec=true rd=53454 base=49640 Wed 7/11 18:56:44.936 VF thread is now waiting for 0:03:18.628 Wed 7/11 18:56:44.936 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.936 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.936 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.937 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.937 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.937 VF processing job null nPlayin=true Wed 7/11 18:56:44.938 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.938 isRec=true rd=53457 base=49643 Wed 7/11 18:56:44.938 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.939 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.939 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.940 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.941 VF processing job null nPlayin=true Wed 7/11 18:56:44.941 isRec=true rd=53460 base=49646 Wed 7/11 18:56:44.941 VF thread is now waiting for 0:03:18.622 Wed 7/11 18:56:44.949 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.949 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.950 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.950 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.950 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.950 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.951 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.951 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.951 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.952 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.952 VF processing job null nPlayin=true Wed 7/11 18:56:44.952 isRec=true rd=53471 base=49658 Wed 7/11 18:56:44.953 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:56:44.953 VF thread is now waiting for 0:03:18.611 Wed 7/11 18:56:44.954 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:56:44.955 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:56:44.955 DShowMediaPlayer is consuming the events... Wed 7/11 18:56:44.955 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:56:44.956 VF processing job null nPlayin=true Wed 7/11 18:56:44.956 isRec=true rd=53475 base=49662 Wed 7/11 18:56:44.956 VF thread is now waiting for 0:03:18.607 Wed 7/11 18:57:49.774 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 18:57:49.775 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 18:57:49.775 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 18:57:49.775 DShowMediaPlayer is consuming the events... Wed 7/11 18:57:49.775 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 18:57:49.776 VF processing job null nPlayin=true Wed 7/11 18:57:49.776 isRec=true rd=118295 base=114492 Wed 7/11 18:57:49.776 VF thread is now waiting for 0:02:13.776 Wed 7/11 18:57:54.496 Setting the system clock to be Wed 7/11 18:57:55.477 Wed 7/11 18:59:59.996 VF processing job null nPlayin=true Wed 7/11 18:59:59.997 isRec=true rd=248516 base=243538 Wed 7/11 18:59:59.997 VF thread is now waiting for 0:00:04.730 Wed 7/11 19:00:00.004 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg]) Wed 7/11 19:00:00.005 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg] nPlayin=true Wed 7/11 19:00:00.006 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=E:\Temp Recordings\CBSEveningNewsWithKatieCouric-1959-0.mpg] Wed 7/11 19:00:00.006 VF notified of Inactive File Wed 7/11 19:00:00.007 VF processing job null nPlayin=true Wed 7/11 19:00:00.007 isRec=true rd=248526 base=243549 Wed 7/11 19:00:00.008 VF thread is now waiting for 0:00:04.727 Wed 7/11 19:00:01.593 VF processing job null nPlayin=true Wed 7/11 19:00:01.594 isRec=false rd=248520 base=245135 Wed 7/11 19:00:01.594 VF thread is now waiting for 0:00:03.134 Wed 7/11 19:00:01.932 VF processing job null nPlayin=true Wed 7/11 19:00:01.933 isRec=false rd=248520 base=245474 Wed 7/11 19:00:01.933 VF thread is now waiting for 0:00:02.796 Wed 7/11 19:00:02.380 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:02.381 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:02.381 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:02.381 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:02.381 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:02.382 VF processing job null nPlayin=true Wed 7/11 19:00:02.382 isRec=false rd=248520 base=245924 Wed 7/11 19:00:02.382 VF thread is now waiting for 0:00:02.346 Wed 7/11 19:00:04.728 VF processing job null nPlayin=true Wed 7/11 19:00:04.729 isRec=false rd=248520 base=248270 Wed 7/11 19:00:04.729 VideoFrame watching a live old airing, fixing it. Wed 7/11 19:00:04.731 watchMe=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] Wed 7/11 19:00:04.731 Sending requestWatch to server for A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] Wed 7/11 19:00:04.733 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true Wed 7/11 19:00:04.734 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] ifn=null] Wed 7/11 19:00:04.738 VF logFileWatch doneAir=A[1959,1533,"CBS Evening News With Katie Couric",20495@0711.18:30,30] theTime=Wed 7/11 18:59:59.757 recTime=Wed 7/11 18:55:51.481 Wed 7/11 19:00:04.739 Stopping the playback graph. Wed 7/11 19:00:04.744 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 19:00:04.744 VF network encoder playback detected: false Wed 7/11 19:00:04.752 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] ifn=null] Wed 7/11 19:00:04.752 VF file=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] targetTime = Wed 7/11 19:00:00.018 Wed 7/11 19:00:04.753 VideoFrame.timeSelected(Wed 7/11 19:00:00.018, true) currFile=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] Wed 7/11 19:00:04.753 DShowPlayer setTimeshift=1 buffer=0 Wed 7/11 19:00:04.753 NATIVE Loading the source file Wed 7/11 19:00:04.865 About to perform media time selection for 0 Wed 7/11 19:00:04.865 Pausing the playback graph. Wed 7/11 19:00:04.897 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:04.897 VideoFrame.timeSelected2(Wed 7/11 19:00:00.018, true) currFile=MediaFile[id=31144 A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WTSPDT format=MPEG2-PS 0:00:00 80384 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg, Seg0[Wed 7/11 19:00:00.018-Wed 12/31 19:00:00.000]] realDur=0 Wed 7/11 19:00:04.897 About to perform media time selection for 0 Wed 7/11 19:00:04.903 Running the playback graph. Wed 7/11 19:00:04.906 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:04.907 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:04.908 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:04.908 Event: 0xe l1=0x0 l2=0x0 Wed 7/11 19:00:04.908 VF processing job null nPlayin=true Wed 7/11 19:00:04.909 isRec=true rd=4891 base=0 Wed 7/11 19:00:04.909 VF thread is now waiting for 0:29:59.732 Wed 7/11 19:00:04.934 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:04.934 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:04.935 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:04.935 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:04.936 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:04.936 VF processing job null nPlayin=true Wed 7/11 19:00:04.936 isRec=true rd=4918 base=22 Wed 7/11 19:00:04.936 VF thread is now waiting for 0:29:59.709 Wed 7/11 19:00:04.939 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:04.939 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:04.939 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:04.940 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:04.940 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:04.940 VF processing job null nPlayin=true Wed 7/11 19:00:04.941 isRec=true rd=4923 base=27 Wed 7/11 19:00:04.941 VF thread is now waiting for 0:29:59.705 Wed 7/11 19:00:05.050 VF processing job null nPlayin=true Wed 7/11 19:00:05.051 isRec=true rd=5033 base=68 Wed 7/11 19:00:05.051 VF thread is now waiting for 0:29:59.664 Wed 7/11 19:00:10.182 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:10.182 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:10.183 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:10.183 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:10.183 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:10.183 VF processing job null nPlayin=true Wed 7/11 19:00:10.184 isRec=true rd=10166 base=5787 Wed 7/11 19:00:10.184 VF thread is now waiting for 0:29:53.944 Wed 7/11 19:00:36.400 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.400 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.400 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.401 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.401 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.401 VF processing job null nPlayin=true Wed 7/11 19:00:36.401 isRec=true rd=36383 base=32009 Wed 7/11 19:00:36.401 VF thread is now waiting for 0:29:27.723 Wed 7/11 19:00:36.402 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.402 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.403 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.403 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.403 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.403 VF processing job null nPlayin=true Wed 7/11 19:00:36.403 isRec=true rd=36385 base=32011 Wed 7/11 19:00:36.404 VF thread is now waiting for 0:29:27.720 Wed 7/11 19:00:36.404 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.404 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.405 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.405 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.405 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.405 VF processing job null nPlayin=true Wed 7/11 19:00:36.406 isRec=true rd=36388 base=32014 Wed 7/11 19:00:36.406 VF thread is now waiting for 0:29:27.718 Wed 7/11 19:00:36.406 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.407 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.407 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.407 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.407 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.407 VF processing job null nPlayin=true Wed 7/11 19:00:36.408 isRec=true rd=36390 base=32016 Wed 7/11 19:00:36.408 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.410 VF thread is now waiting for 0:29:27.716 Wed 7/11 19:00:36.410 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.410 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.411 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.411 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.411 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.411 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.411 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.412 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.412 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.413 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.413 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.413 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.413 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.414 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.414 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.414 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.414 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.415 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.415 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.415 VF processing job null nPlayin=true Wed 7/11 19:00:36.416 isRec=true rd=36398 base=32023 Wed 7/11 19:00:36.417 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.417 VF thread is now waiting for 0:29:27.708 Wed 7/11 19:00:36.418 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.418 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.419 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.419 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.419 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.419 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.420 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.420 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.422 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.422 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.423 VF processing job null nPlayin=true Wed 7/11 19:00:36.423 isRec=true rd=36405 base=32030 Wed 7/11 19:00:36.423 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.424 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.424 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.425 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.425 VF processing job null nPlayin=true Wed 7/11 19:00:36.425 isRec=true rd=36407 base=32032 Wed 7/11 19:00:36.425 VF thread is now waiting for 0:29:27.700 Wed 7/11 19:00:36.440 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.440 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.440 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.441 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.441 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.441 VF processing job null nPlayin=true Wed 7/11 19:00:36.441 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.442 isRec=true rd=36424 base=32049 Wed 7/11 19:00:36.442 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.442 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.443 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.443 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:36.443 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.444 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:36.444 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:36.445 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:36.446 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:36.447 VF processing job null nPlayin=true Wed 7/11 19:00:36.447 isRec=true rd=36429 base=32055 Wed 7/11 19:00:36.447 VF thread is now waiting for 0:29:27.676 Wed 7/11 19:00:40.991 processOptionsMenu optionsMenu=default:null|OptionsMenu:Wide OSD Options Wed 7/11 19:00:41.135 Loading 240x90 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Thumbnailbkgd.png Wed 7/11 19:00:41.142 Creating DirectX9 Texture from nio buffer w=240 h=90 Wed 7/11 19:00:41.146 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:41.147 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDRecord.png Wed 7/11 19:00:41.148 Creating DirectX9 Texture from nio buffer w=30 h=26 Wed 7/11 19:00:44.173 setUI(sage.b2@18e2a42[LiveTVGuide w/ Optional Preview]) histIdx=2 uiHistory=[sage.b2@1a19458[Main Menu], sage.b2@1955970[MediaPlayer OSD], sage.b2@18e2a42[LiveTVGuide w/ Optional Preview]] Wed 7/11 19:00:44.222 setUI(sage.b2@395626[LiveTVGuide w/ Optional Preview]) histIdx=2 uiHistory=[sage.b2@1a19458[Main Menu], sage.b2@1955970[MediaPlayer OSD], sage.b2@395626[LiveTVGuide w/ Optional Preview]] Wed 7/11 19:00:44.843 Loading 190x116 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\RecordingNow.png Wed 7/11 19:00:44.848 Creating DirectX9 Texture from nio buffer w=190 h=116 Wed 7/11 19:00:44.850 Loading 14x71 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\guideLeft.png Wed 7/11 19:00:44.854 Creating DirectX9 Texture from nio buffer w=14 h=71 Wed 7/11 19:00:44.862 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerHDTV.png Wed 7/11 19:00:44.867 Creating DirectX9 Texture from nio buffer w=121 h=121 Wed 7/11 19:00:44.869 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:44.871 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:44.872 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:44.875 Loading 24x29 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\left.png Wed 7/11 19:00:44.876 Creating DirectX9 Texture from nio buffer w=24 h=29 Wed 7/11 19:00:44.879 Loading 25x27 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\right.png Wed 7/11 19:00:44.881 Creating DirectX9 Texture from nio buffer w=25 h=27 Wed 7/11 19:00:44.882 Loading 15x71 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\guideRight.png Wed 7/11 19:00:44.883 Creating DirectX9 Texture from nio buffer w=15 h=71 Wed 7/11 19:00:44.886 Loading 96x97 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\RecordAny.png Wed 7/11 19:00:44.891 Creating DirectX9 Texture from nio buffer w=96 h=97 Wed 7/11 19:00:44.898 Loading 27x25 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\up.png Wed 7/11 19:00:44.899 Creating DirectX9 Texture from nio buffer w=27 h=25 Wed 7/11 19:00:44.901 Loading 26x25 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\down.png Wed 7/11 19:00:44.902 Creating DirectX9 Texture from nio buffer w=26 h=25 Wed 7/11 19:00:44.903 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=35] Wed 7/11 19:00:45.113 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=35] numGlyphs=1674 numCachedGlyphs=249 numImages=15 Wed 7/11 19:00:45.114 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.116 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.118 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.120 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.235 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=28] Wed 7/11 19:00:45.496 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=28] numGlyphs=1674 numCachedGlyphs=242 numImages=12 Wed 7/11 19:00:45.498 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.512 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.513 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.515 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.516 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:45.518 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerFirstRun.png Wed 7/11 19:00:45.523 Creating DirectX9 Texture from nio buffer w=121 h=121 Wed 7/11 19:00:46.816 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37] Wed 7/11 19:00:47.139 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37] numGlyphs=1674 numCachedGlyphs=234 numImages=23 Wed 7/11 19:00:47.141 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.143 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.144 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.145 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.147 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.149 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.150 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.151 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.153 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.156 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.262 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=33] Wed 7/11 19:00:47.538 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=33] numGlyphs=1674 numCachedGlyphs=242 numImages=14 Wed 7/11 19:00:47.540 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.541 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.542 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.544 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:47.547 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:00:48.343 processOptionsMenu optionsMenu=default:null|OptionsMenu:Show Options for an Airing, Recording, File, etc. Wed 7/11 19:00:48.451 BEGIN: Sort video playlists. Wed 7/11 19:00:48.453 END: Sort video playlists. Sorted 0 playlists. Total Time = '1' Wed 7/11 19:00:48.545 Loading 146x146 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerInfo.png Wed 7/11 19:00:48.573 Creating DirectX9 Texture from nio buffer w=146 h=146 Wed 7/11 19:00:49.834 processOptionsMenu optionsMenu=default:null|OptionsMenu:Preparing for video playback notice Wed 7/11 19:00:49.905 VideoFrame.watch(A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30]) Wed 7/11 19:00:49.906 watchThisFile=null Wed 7/11 19:00:49.910 Sending requestWatch to server for A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] Wed 7/11 19:00:49.914 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg]) Wed 7/11 19:00:49.915 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg] nPlayin=true Wed 7/11 19:00:49.916 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=E:\Temp Recordings\WheelofFortune-TeenBestFriends-1600-0.mpg] Wed 7/11 19:00:49.920 VF notified of Inactive File Wed 7/11 19:00:49.920 VF processing job null nPlayin=true Wed 7/11 19:00:49.921 isRec=true rd=49903 base=45532 Wed 7/11 19:00:49.922 VF thread is now waiting for 0:29:14.200 Wed 7/11 19:00:50.152 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim2.png Wed 7/11 19:00:50.154 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:50.209 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim3.png Wed 7/11 19:00:50.216 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:50.360 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim4.png Wed 7/11 19:00:50.362 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:50.511 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim5.png Wed 7/11 19:00:50.514 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:50.602 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim6.png Wed 7/11 19:00:50.605 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:50.696 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim7.png Wed 7/11 19:00:50.697 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:50.779 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim0.png Wed 7/11 19:00:50.781 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:50.868 Loading 32x32 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Images_Default\tvicon_anim1.png Wed 7/11 19:00:50.869 Creating DirectX9 Texture from nio buffer w=32 h=32 Wed 7/11 19:00:51.849 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:51.849 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:51.850 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:51.850 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:51.850 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:51.850 VF processing job null nPlayin=true Wed 7/11 19:00:51.851 isRec=false rd=49897 base=47461 Wed 7/11 19:00:51.851 VF thread is now waiting for 0:00:02.186 Wed 7/11 19:00:52.650 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null]) Wed 7/11 19:00:52.651 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=true Wed 7/11 19:00:52.655 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null] Wed 7/11 19:00:52.660 VF logFileWatch doneAir=A[1600,733,"Wheel of Fortune",20495@0711.19:00,30] theTime=Wed 7/11 19:00:48.283 recTime=Wed 7/11 19:00:00.018 Wed 7/11 19:00:52.662 Stopping the playback graph. Wed 7/11 19:00:52.674 VIDEOFRAME Needs to do A FULL SWITCH on the file due to ENCODING CHANGE Wed 7/11 19:00:52.675 Tearing down playback graph Wed 7/11 19:00:52.675 NATIVE Deallocating the CPlayerData object Wed 7/11 19:00:52.696 setUI(sage.b2@140eacd[MediaPlayer OSD]) histIdx=3 uiHistory=[sage.b2@1a19458[Main Menu], sage.b2@1955970[MediaPlayer OSD], sage.b2@395626[LiveTVGuide w/ Optional Preview], sage.b2@140eacd[MediaPlayer OSD]] Wed 7/11 19:00:52.838 NATIVE Finished destroying the playback graph Wed 7/11 19:00:52.840 VideoFrame creating new media player for file:MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] Wed 7/11 19:00:52.876 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 19:00:52.876 VF network encoder playback detected: false Wed 7/11 19:00:52.918 VF waiting for data to appear in new file...liveWait=-2947 Wed 7/11 19:00:52.919 VF thread is now waiting for 0:00:00.200 Wed 7/11 19:00:53.120 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 0 kbps [] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 19:00:53.120 VF network encoder playback detected: false Wed 7/11 19:00:53.132 VF waiting for data to appear in new file...liveWait=-3191 Wed 7/11 19:00:53.132 VF thread is now waiting for 0:00:00.200 Wed 7/11 19:00:53.323 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 19776 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 19:00:53.323 VF network encoder playback detected: false Wed 7/11 19:00:53.333 VF waiting for data to appear in new file...liveWait=-3395 Wed 7/11 19:00:53.334 VF thread is now waiting for 0:00:00.200 Wed 7/11 19:00:53.526 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 19776 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Wed 7/11 19:00:53.526 VF network encoder playback detected: false Wed 7/11 19:00:53.532 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 19776 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] ifn=null] Wed 7/11 19:00:53.532 VF file=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 19776 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] targetTime = Wed 7/11 19:00:49.929 Wed 7/11 19:00:53.533 VideoFrame.timeSelected(Wed 7/11 19:00:49.929, true) currFile=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 19776 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] Wed 7/11 19:00:53.536 DShowPlayer setVideoRendererFilter0({51B4ABF3-748F-4E3B-A276-C828330E926A}) called Wed 7/11 19:00:53.567 Setting up VMR9 deinterlacing Wed 7/11 19:00:53.568 Curr Mix Prefs=0x1015 Wed 7/11 19:00:53.568 Set to 0x2015, hr=0x0, New Mix Prefs=0x2015 Wed 7/11 19:00:53.568 DShowPlayer setAudioRendererFilter0(SB Live! Wave Device) called Wed 7/11 19:00:53.574 DShowPlayer setVideoDecoderFilter0(NVIDIA Video Decoder) called Wed 7/11 19:00:53.711 DShowPlayer setAudioDecoderFilter0(NVIDIA Audio Decoder) called Wed 7/11 19:00:53.851 DShowPlayer setTimeshift=1 buffer=0 Wed 7/11 19:00:53.851 NATIVE Loading the source file Wed 7/11 19:00:53.855 NATIVE Setting up the MPEG demux Wed 7/11 19:00:53.861 NATIVE RenderAudio called stream=1 Wed 7/11 19:00:53.861 NATIVE RenderAudio step 1 Wed 7/11 19:00:53.863 NATIVE RenderAudio step 2 Wed 7/11 19:00:53.863 NATIVE RenderAudio step 3 Wed 7/11 19:00:53.865 NATIVE RenderVideo called Wed 7/11 19:00:53.866 NATIVE RenderVideo step 1 Wed 7/11 19:00:53.867 NATIVE RenderVideo step 2 Wed 7/11 19:00:53.867 Video source pin information: Wed 7/11 19:00:53.867 PIN is not connected yet!Wed 7/11 19:00:53.867 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.868 Interlaced video Wed 7/11 19:00:53.868 Both fields per sample Wed 7/11 19:00:53.868 Field 2 first Wed 7/11 19:00:53.868 Stream never contains a Field 2 Wed 7/11 19:00:53.868 Either bob or weave mode. Wed 7/11 19:00:53.868 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.868 Interlaced video Wed 7/11 19:00:53.869 Both fields per sample Wed 7/11 19:00:53.869 Field 2 first Wed 7/11 19:00:53.869 Stream never contains a Field 2 Wed 7/11 19:00:53.869 Either bob or weave mode. Wed 7/11 19:00:53.869 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.869 Interlaced video Wed 7/11 19:00:53.869 Both fields per sample Wed 7/11 19:00:53.869 Field 2 first Wed 7/11 19:00:53.870 Stream never contains a Field 2 Wed 7/11 19:00:53.870 Either bob or weave mode. Wed 7/11 19:00:53.870 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.870 Interlaced video Wed 7/11 19:00:53.870 Both fields per sample Wed 7/11 19:00:53.870 Field 2 first Wed 7/11 19:00:53.870 Stream never contains a Field 2 Wed 7/11 19:00:53.871 Either bob or weave mode. Wed 7/11 19:00:53.871 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.871 Interlaced video Wed 7/11 19:00:53.871 Both fields per sample Wed 7/11 19:00:53.871 Field 2 first Wed 7/11 19:00:53.871 Stream never contains a Field 2 Wed 7/11 19:00:53.871 Either bob or weave mode. Wed 7/11 19:00:53.872 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.872 Interlaced video Wed 7/11 19:00:53.872 Both fields per sample Wed 7/11 19:00:53.872 Field 2 first Wed 7/11 19:00:53.872 Stream never contains a Field 2 Wed 7/11 19:00:53.872 Either bob or weave mode. Wed 7/11 19:00:53.873 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.873 Interlaced video Wed 7/11 19:00:53.873 Both fields per sample Wed 7/11 19:00:53.873 Field 2 first Wed 7/11 19:00:53.873 Stream never contains a Field 2 Wed 7/11 19:00:53.873 Either bob or weave mode. Wed 7/11 19:00:53.873 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.873 Interlaced video Wed 7/11 19:00:53.874 Both fields per sample Wed 7/11 19:00:53.874 Field 2 first Wed 7/11 19:00:53.874 Stream never contains a Field 2 Wed 7/11 19:00:53.874 Either bob or weave mode. Wed 7/11 19:00:53.874 M type MEDIATYPE_Video S type MEDIASUBTYPE_YV12 F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.874 Interlaced video Wed 7/11 19:00:53.874 Both fields per sample Wed 7/11 19:00:53.885 Field 2 first Wed 7/11 19:00:53.886 Stream never contains a Field 2 Wed 7/11 19:00:53.886 Either bob or weave mode. Wed 7/11 19:00:53.886 M type MEDIATYPE_Video S type MEDIASUBTYPE_YUY2 F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.886 Interlaced video Wed 7/11 19:00:53.886 Both fields per sample Wed 7/11 19:00:53.890 Field 2 first Wed 7/11 19:00:53.890 Stream never contains a Field 2 Wed 7/11 19:00:53.890 Either bob or weave mode. Wed 7/11 19:00:53.891 M type MEDIATYPE_Video S type MEDIASUBTYPE_UYVY F type FORMAT_VideoInfo2 Wed 7/11 19:00:53.891 Interlaced video Wed 7/11 19:00:53.891 Both fields per sample Wed 7/11 19:00:53.891 Field 2 first Wed 7/11 19:00:53.891 Stream never contains a Field 2 Wed 7/11 19:00:53.891 Either bob or weave mode. Wed 7/11 19:00:53.891 NATIVE RenderVideo step 3 Wed 7/11 19:00:54.017 VMR9 alloc 1920x1080 Offscreen DefaultPool format=NV12 minBuffs=1 arx=1920 ary=1080 nativeWidth=1920 nativeHeight=1080 reqNumBuff=1 allocNumBuff=1 hr=0x0 Wed 7/11 19:00:54.024 Setting up VMR9 deinterlacing Wed 7/11 19:00:54.025 VM9Deinterlacing Tech: PixelAdaptive Wed 7/11 19:00:54.025 VM9Deinterlacing Tech: BOBVerticalStretch Wed 7/11 19:00:54.025 Actual deinterlace: hr=0x0 guid={212DC724-3235-44A4-BD29-E1652BBCC71C} Wed 7/11 19:00:54.025 deinterlace mode: hr=0x1 guid={00000000-0000-0000-0000-000000000000} Wed 7/11 19:00:54.025 Setting deinterlace mode to actual mode... Wed 7/11 19:00:54.025 deinterlace mode: hr=0x0 guid={212DC724-3235-44A4-BD29-E1652BBCC71C} Wed 7/11 19:00:54.026 Filter Graph Video Renderer Info: Wed 7/11 19:00:54.026 Video Renderer:CLSID_VideoMixingRenderer9 Wed 7/11 19:00:54.026 M type MEDIATYPE_Video S type Unknown GUID Name F type FORMAT_VideoInfo2 Wed 7/11 19:00:54.026 Interlaced video Wed 7/11 19:00:54.026 Both fields per sample Wed 7/11 19:00:54.026 Field 2 first Wed 7/11 19:00:54.027 Stream never contains a Field 2 Wed 7/11 19:00:54.027 Either bob or weave mode. Wed 7/11 19:00:54.027 VMR9 filter detected, using line 21 decoder 2.Wed 7/11 19:00:54.029 DShowGraphFilters=AudRend,VidRend,Line21 Decoder,Audio Decoder,Video Decoder,SageTV MPEG2 Demultiplexer,E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Wed 7/11 19:00:54.030 VideoFrame.timeSelected2(Wed 7/11 19:00:49.929, true) currFile=MediaFile[id=31146 A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] host=htpc encodedBy=FusionHDTV, WDM Video Capture WFLADT format=MPEG2-PS 0:00:00 19776 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 2 channels MAIN idx=1 id=bd-81010002]] E:\Temp Recordings\EntertainmentTonight-2519-0.mpg, Seg0[Wed 7/11 19:00:49.929-Wed 12/31 19:00:00.000]] realDur=0 Wed 7/11 19:00:54.031 About to perform media time selection for 0 Wed 7/11 19:00:54.031 Running the playback graph. Wed 7/11 19:00:54.031 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.046 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.051 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.052 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.052 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.053 Event: 0xd l1=0x0 l2=0x0 Wed 7/11 19:00:54.053 Event: 0xe l1=0x0 l2=0x0 Wed 7/11 19:00:54.056 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.057 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.057 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.058 VF processing job null nPlayin=true Wed 7/11 19:00:54.058 isRec=true rd=4129 base=4 Wed 7/11 19:00:54.059 VF thread is now waiting for 0:29:09.817 Wed 7/11 19:00:54.220 VMR9 alloc 1934x1088 Offscreen DefaultPool format=NV12 minBuffs=1 arx=1934 ary=1088 nativeWidth=1934 nativeHeight=1088 reqNumBuff=1 allocNumBuff=1 hr=0x0 Wed 7/11 19:00:54.263 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.263 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.264 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.265 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.265 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.265 VF processing job null nPlayin=true Wed 7/11 19:00:54.265 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.266 isRec=true rd=4337 base=123 Wed 7/11 19:00:54.266 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.268 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.269 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.269 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.270 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.270 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.272 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.272 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.273 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.273 VF processing job null nPlayin=true Wed 7/11 19:00:54.274 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.276 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.278 isRec=true rd=4346 base=123 Wed 7/11 19:00:54.281 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.282 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.284 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.286 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.288 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.288 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.290 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.291 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.291 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.291 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.292 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.292 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.292 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.293 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.303 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.303 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.304 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.304 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.304 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.305 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.305 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.306 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.306 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.306 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.307 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.309 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.309 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.310 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.310 VF processing job null nPlayin=true Wed 7/11 19:00:54.310 isRec=true rd=4381 base=123 Wed 7/11 19:00:54.311 VF thread is now waiting for 0:29:09.698 Wed 7/11 19:00:54.318 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.318 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.319 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.319 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.319 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.320 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.320 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.320 VF processing job null nPlayin=true Wed 7/11 19:00:54.320 isRec=true rd=4391 base=123 Wed 7/11 19:00:54.321 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.322 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:00:54.323 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.323 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.324 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:00:54.324 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:00:54.324 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:00:54.325 DShowMediaPlayer is consuming the events... Wed 7/11 19:00:54.325 VF processing job null nPlayin=true Wed 7/11 19:00:54.325 isRec=true rd=4396 base=123 Wed 7/11 19:00:54.326 VF thread is now waiting for 0:29:09.698 Wed 7/11 19:02:31.871 Connection lost from: Socket[addr=/192.168.1.101,port=42024,localport=1185] by:java.io.EOFException Wed 7/11 19:02:31.872 Cleaning up c/s connection Wed 7/11 19:02:31.872 NetworkManager CommunicationFailure : /192.168.1.101:42024 type=2 Wed 7/11 19:02:31.882 setUI(sage.b2@166ca4c[Server Connection Lost]) histIdx=4 uiHistory=[sage.b2@1a19458[Main Menu], sage.b2@1955970[MediaPlayer OSD], sage.b2@395626[LiveTVGuide w/ Optional Preview], sage.b2@140eacd[MediaPlayer OSD], sage.b2@166ca4c[Server Connection Lost]] Wed 7/11 19:02:41.623 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:02:41.624 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:02:41.624 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:02:41.625 DShowMediaPlayer is consuming the events... Wed 7/11 19:02:41.625 Event: 0x3 l1=0x80004005 l2=0x0 Wed 7/11 19:02:41.625 VF processing job null nPlayin=true Wed 7/11 19:02:41.626 isRec=true rd=111697 base=105788 Wed 7/11 19:02:41.626 VF thread is now waiting for 0:27:24.032 Wed 7/11 19:02:41.635 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:02:41.635 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:02:41.636 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null] Wed 7/11 19:02:41.636 DShowMediaPlayer is consuming the events... Wed 7/11 19:02:41.636 Event: 0x8065 l1=0x0 l2=0x0 Wed 7/11 19:02:41.636 VF processing job null nPlayin=true Wed 7/11 19:02:41.636 isRec=true rd=111707 base=105799 Wed 7/11 19:02:41.637 VF thread is now waiting for 0:27:24.022 Wed 7/11 19:02:44.440 Error communicating with server:java.net.SocketException: Connection reset Wed 7/11 19:02:44.440 Cleaning up c/s connection Wed 7/11 19:02:44.441 NetworkManager CommunicationFailure : /192.168.1.101:42024 type=1 Wed 7/11 19:02:44.441 MsgRecv thread is terminating for /192.168.1.101:42024 Wed 7/11 19:02:44.883 Error with c/s comm:java.lang.NullPointerException Wed 7/11 19:02:44.883 java.lang.NullPointerException Wed 7/11 19:02:44.884 at sage.u.for(Unknown Source) Wed 7/11 19:02:44.884 at sage.av.a(Unknown Source) Wed 7/11 19:02:44.884 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.884 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.884 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.884 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.885 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.885 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.885 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.885 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.885 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.885 at sage.d.m.a(Unknown Source) Wed 7/11 19:02:44.885 at sage.e.a(Unknown Source) Wed 7/11 19:02:44.886 at sage.a8.a(Unknown Source) Wed 7/11 19:02:44.886 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.886 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.886 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.886 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.887 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.887 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.887 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.887 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.887 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.887 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.887 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.888 at sage.b2.I(Unknown Source) Wed 7/11 19:02:44.888 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.888 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.888 at sage.UIManager.f(Unknown Source) Wed 7/11 19:02:44.888 at sage.bq.gp(Unknown Source) Wed 7/11 19:02:44.889 at sage.bq.if(Unknown Source) Wed 7/11 19:02:44.889 at sage.u.run(Unknown Source) Wed 7/11 19:02:44.889 at java.lang.Thread.run(Unknown Source) Wed 7/11 19:02:44.889 Error with c/s comm:java.lang.NullPointerException Wed 7/11 19:02:44.889 java.lang.NullPointerException Wed 7/11 19:02:44.890 at sage.u.for(Unknown Source) Wed 7/11 19:02:44.890 at sage.av.a(Unknown Source) Wed 7/11 19:02:44.890 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.890 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.890 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.891 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.891 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.891 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.891 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.891 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.891 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.892 at sage.d.m.a(Unknown Source) Wed 7/11 19:02:44.892 at sage.e.a(Unknown Source) Wed 7/11 19:02:44.892 at sage.a8.a(Unknown Source) Wed 7/11 19:02:44.892 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.892 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.892 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.893 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.893 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.893 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.893 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.894 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.894 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.894 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.894 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.894 at sage.b2.I(Unknown Source) Wed 7/11 19:02:44.894 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.895 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.895 at sage.UIManager.f(Unknown Source) Wed 7/11 19:02:44.895 at sage.bq.gp(Unknown Source) Wed 7/11 19:02:44.895 at sage.bq.if(Unknown Source) Wed 7/11 19:02:44.895 at sage.u.run(Unknown Source) Wed 7/11 19:02:44.895 at java.lang.Thread.run(Unknown Source) Wed 7/11 19:02:44.896 Error communicating with server:java.lang.NullPointerException Wed 7/11 19:02:44.896 Error with c/s comm:java.lang.NullPointerException Wed 7/11 19:02:44.896 java.lang.NullPointerException Wed 7/11 19:02:44.896 at sage.u.for(Unknown Source) Wed 7/11 19:02:44.897 at sage.av.a(Unknown Source) Wed 7/11 19:02:44.897 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.897 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.897 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.897 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.897 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.898 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.898 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.898 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.898 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.898 at sage.d.m.a(Unknown Source) Wed 7/11 19:02:44.898 at sage.e.a(Unknown Source) Wed 7/11 19:02:44.898 at sage.a8.a(Unknown Source) Wed 7/11 19:02:44.899 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.899 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.899 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.899 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.899 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.900 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.900 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.900 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.900 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.900 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.900 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.901 at sage.b2.I(Unknown Source) Wed 7/11 19:02:44.901 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.901 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.901 at sage.UIManager.f(Unknown Source) Wed 7/11 19:02:44.901 at sage.bq.gp(Unknown Source) Wed 7/11 19:02:44.901 at sage.bq.if(Unknown Source) Wed 7/11 19:02:44.902 at sage.u.run(Unknown Source) Wed 7/11 19:02:44.902 at java.lang.Thread.run(Unknown Source) Wed 7/11 19:02:44.902 Cleaning up c/s connection Wed 7/11 19:02:44.902 NetworkManager CommunicationFailure : /192.168.1.101:42024 type=1 Wed 7/11 19:02:44.902 MsgSend thread terminating for /192.168.1.101:42024 Wed 7/11 19:02:44.903 Error with c/s comm:java.lang.NullPointerException Wed 7/11 19:02:44.903 java.lang.NullPointerException Wed 7/11 19:02:44.903 at sage.u.for(Unknown Source) Wed 7/11 19:02:44.903 at sage.av.a(Unknown Source) Wed 7/11 19:02:44.904 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.904 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.904 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.904 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.904 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.904 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.905 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.905 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.905 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.905 at sage.d.m.a(Unknown Source) Wed 7/11 19:02:44.905 at sage.e.a(Unknown Source) Wed 7/11 19:02:44.905 at sage.a8.a(Unknown Source) Wed 7/11 19:02:44.905 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.906 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.906 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.906 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.906 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.906 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.907 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.907 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.907 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.907 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.907 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.908 at sage.b2.I(Unknown Source) Wed 7/11 19:02:44.908 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.908 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.908 at sage.UIManager.f(Unknown Source) Wed 7/11 19:02:44.908 at sage.bq.gp(Unknown Source) Wed 7/11 19:02:44.908 at sage.bq.if(Unknown Source) Wed 7/11 19:02:44.908 at sage.u.run(Unknown Source) Wed 7/11 19:02:44.909 at java.lang.Thread.run(Unknown Source) Wed 7/11 19:02:44.909 Error with c/s comm:java.lang.NullPointerException Wed 7/11 19:02:44.909 java.lang.NullPointerException Wed 7/11 19:02:44.909 at sage.u.for(Unknown Source) Wed 7/11 19:02:44.909 at sage.av.a(Unknown Source) Wed 7/11 19:02:44.910 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.910 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.910 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.910 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.910 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.910 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.911 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.911 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.911 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.911 at sage.d.m.a(Unknown Source) Wed 7/11 19:02:44.911 at sage.e.a(Unknown Source) Wed 7/11 19:02:44.911 at sage.a8.a(Unknown Source) Wed 7/11 19:02:44.912 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.912 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.912 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.912 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.912 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.912 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.913 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.913 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.913 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.913 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.913 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.913 at sage.b2.I(Unknown Source) Wed 7/11 19:02:44.914 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.914 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.914 at sage.UIManager.f(Unknown Source) Wed 7/11 19:02:44.914 at sage.bq.gp(Unknown Source) Wed 7/11 19:02:44.914 at sage.bq.if(Unknown Source) Wed 7/11 19:02:44.914 at sage.u.run(Unknown Source) Wed 7/11 19:02:44.915 at java.lang.Thread.run(Unknown Source) Wed 7/11 19:02:44.915 Error with c/s comm:java.lang.NullPointerException Wed 7/11 19:02:44.915 java.lang.NullPointerException Wed 7/11 19:02:44.915 at sage.u.for(Unknown Source) Wed 7/11 19:02:44.916 at sage.av.a(Unknown Source) Wed 7/11 19:02:44.916 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.916 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.916 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.916 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.917 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.917 at sage.d.k.if(Unknown Source) Wed 7/11 19:02:44.917 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.917 at sage.d.c.a(Unknown Source) Wed 7/11 19:02:44.917 at sage.d.d.a(Unknown Source) Wed 7/11 19:02:44.917 at sage.d.m.a(Unknown Source) Wed 7/11 19:02:44.918 at sage.e.a(Unknown Source) Wed 7/11 19:02:44.918 at sage.a8.a(Unknown Source) Wed 7/11 19:02:44.918 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.918 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.918 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.918 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.918 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.919 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.919 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.919 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.920 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.920 at sage.a8.for(Unknown Source) Wed 7/11 19:02:44.920 at sage.a8.do(Unknown Source) Wed 7/11 19:02:44.920 at sage.b2.I(Unknown Source) Wed 7/11 19:02:44.920 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.921 at sage.UIManager.a(Unknown Source) Wed 7/11 19:02:44.921 at sage.UIManager.f(Unknown Source) Wed 7/11 19:02:44.921 at sage.bq.gp(Unknown Source) Wed 7/11 19:02:44.921 at sage.bq.if(Unknown Source) Wed 7/11 19:02:44.921 at sage.u.run(Unknown Source) Wed 7/11 19:02:44.922 at java.lang.Thread.run(Unknown Source) Wed 7/11 19:02:44.923 Client/Server connection has been lost. Cleaning up and then attempting to restore... Wed 7/11 19:02:44.923 Cleaning up c/s connection Wed 7/11 19:02:44.923 VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:02:44.924 VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true Wed 7/11 19:02:44.924 VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null] Wed 7/11 19:02:44.925 VF logFileWatch doneAir=A[2519,2023,"Entertainment Tonight",21222@0711.19:00,30] theTime=Wed 7/11 19:02:39.017 recTime=Wed 7/11 19:00:49.929 Wed 7/11 19:02:44.927 Stopping the playback graph. Wed 7/11 19:02:44.953 VF.submitJob(VFJob[Pause r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:02:44.954 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:02:44.955 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:02:45.795 AWTThread Hang Detected - hang time = 750 Wed 7/11 19:02:46.545 AWTThread Hang Detected - hang time = 1500 Wed 7/11 19:02:47.295 AWTThread Hang Detected - hang time = 2250 Wed 7/11 19:02:48.045 AWTThread Hang Detected - hang time = 3000 Wed 7/11 19:02:48.795 AWTThread Hang Detected - hang time = 3750 Wed 7/11 19:02:49.544 AWTThread Hang Detected - hang time = 4499 Wed 7/11 19:02:50.294 AWTThread Hang Detected - hang time = 5249 Wed 7/11 19:02:51.044 AWTThread Hang Detected - hang time = 5999 Wed 7/11 19:02:51.794 AWTThread Hang Detected - hang time = 6749 Wed 7/11 19:02:52.544 AWTThread Hang Detected - hang time = 7499 Wed 7/11 19:02:53.294 AWTThread Hang Detected - hang time = 8249 Wed 7/11 19:02:54.044 AWTThread Hang Detected - hang time = 8999 Wed 7/11 19:02:54.794 AWTThread Hang Detected - hang time = 9749 Wed 7/11 19:02:55.544 AWTThread Hang Detected - hang time = 10499 Wed 7/11 19:02:56.294 AWTThread Hang Detected - hang time = 11249 Wed 7/11 19:02:57.044 AWTThread Hang Detected - hang time = 11999 Wed 7/11 19:02:57.794 AWTThread Hang Detected - hang time = 12749 Wed 7/11 19:02:58.544 AWTThread Hang Detected - hang time = 13499 Wed 7/11 19:02:59.294 AWTThread Hang Detected - hang time = 14249 Wed 7/11 19:03:00.044 AWTThread Hang Detected - hang time = 14999 Wed 7/11 19:03:00.794 AWTThread Hang Detected - hang time = 15749 Wed 7/11 19:03:01.544 AWTThread Hang Detected - hang time = 16499 Wed 7/11 19:03:02.293 AWTThread Hang Detected - hang time = 17248 Wed 7/11 19:03:03.043 AWTThread Hang Detected - hang time = 17998 Wed 7/11 19:03:03.793 AWTThread Hang Detected - hang time = 18748 Wed 7/11 19:03:04.543 AWTThread Hang Detected - hang time = 19498 Wed 7/11 19:03:05.293 AWTThread Hang Detected - hang time = 20248 Wed 7/11 19:03:05.964 Tearing down playback graph Wed 7/11 19:03:05.964 NATIVE Deallocating the CPlayerData object Wed 7/11 19:03:06.002 NATIVE Finished destroying the playback graph Wed 7/11 19:03:06.004 Cleaning up c/s connection Wed 7/11 19:03:06.005 VF processing job VFJob[Pause r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/11 19:03:06.006 Saving properties file to C:\Program Files\SageTV\SageTV\SageClient.properties Wed 7/11 19:03:06.006 VF discarding job VFJob[Pause r=0.0 t=0 file=null ifn=null] Wed 7/11 19:03:06.007 VF processing job null nPlayin=false Wed 7/11 19:03:06.008 VF thread is now waiting for 0:00:00.000 Wed 7/11 19:03:06.067 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.110 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=17] Wed 7/11 19:03:06.209 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=17] numGlyphs=1674 numCachedGlyphs=259 numImages=4 Wed 7/11 19:03:06.210 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:06.211 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:06.212 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=26] Wed 7/11 19:03:06.296 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=26] numGlyphs=1674 numCachedGlyphs=242 numImages=8 Wed 7/11 19:03:06.297 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:06.298 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:06.299 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:06.345 SetFullScreen(true) Wed 7/11 19:03:06.345 FullScreen set to true Wed 7/11 19:03:06.345 Disabling full screen exclusive mode Wed 7/11 19:03:06.348 MetaImage clearNativeCache nativeImageCacheSize=12045824 Wed 7/11 19:03:06.352 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.361 layoutContainer Wed 7/11 19:03:06.368 SetFullScreen(true) Wed 7/11 19:03:06.376 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.378 Cleaning up DirectX9 Wed 7/11 19:03:06.381 Resetting D3D device to cleanup FSE properly... Wed 7/11 19:03:06.432 layoutContainer Wed 7/11 19:03:06.433 SetFullScreen(true) Wed 7/11 19:03:06.443 layoutContainer Wed 7/11 19:03:06.447 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.473 layoutContainer Wed 7/11 19:03:06.496 SetFullScreen(true) Wed 7/11 19:03:06.514 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.522 layoutContainer Wed 7/11 19:03:06.522 SetFullScreen(true) Wed 7/11 19:03:06.531 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.540 Doing full DX9 cleanup. Wed 7/11 19:03:06.544 layoutContainer Wed 7/11 19:03:06.546 SetFullScreen(true) Wed 7/11 19:03:06.550 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.556 layoutContainer Wed 7/11 19:03:06.557 SetFullScreen(true) Wed 7/11 19:03:06.560 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.566 layoutContainer Wed 7/11 19:03:06.571 SetFullScreen(true) Wed 7/11 19:03:06.573 Done cleaning up DirectX9 Wed 7/11 19:03:06.583 Initializing DirectX9 Wed 7/11 19:03:06.588 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.600 layoutContainer Wed 7/11 19:03:06.601 SetFullScreen(true) Wed 7/11 19:03:06.603 Switching back to non-FSE window due to focus loss... Wed 7/11 19:03:06.608 layoutContainer Wed 7/11 19:03:06.609 SetFullScreen(true) Wed 7/11 19:03:06.675 Creating D3D Device Wed 7/11 19:03:06.691 DX9Renderer NATIVE WARNING (non-FAILURE) line 450 hr=0x8876086c Wed 7/11 19:03:06.691 DX9Renderer creation failed. Wait and then try again, it worked before... Wed 7/11 19:03:07.187 MetaImage clearNativeCache nativeImageCacheSize=0 Wed 7/11 19:03:07.188 Cleaning up DirectX9 Wed 7/11 19:03:07.188 Doing full DX9 cleanup. Wed 7/11 19:03:07.188 Done cleaning up DirectX9 Wed 7/11 19:03:07.188 Initializing DirectX9 Wed 7/11 19:03:07.202 Creating D3D Device Wed 7/11 19:03:07.238 Done Initializing DirectX9 Wed 7/11 19:03:07.239 VF.submitJob(VFJob[ReloadMF r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:03:07.239 VF processing job VFJob[ReloadMF r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/11 19:03:07.240 VF discarding job VFJob[ReloadMF r=0.0 t=0 file=null ifn=null] Wed 7/11 19:03:07.240 VF processing job null nPlayin=false Wed 7/11 19:03:07.240 VF thread is now waiting for 0:00:00.000 Wed 7/11 19:03:07.254 Creating DirectX9 Texture from file in memory w=1024 h=576 Wed 7/11 19:03:07.334 Loading 169x59 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageLogo256small.png Wed 7/11 19:03:07.337 Creating DirectX9 Texture from nio buffer w=169 h=59 Wed 7/11 19:03:07.338 Loading 117x102 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\GoWindowed.png Wed 7/11 19:03:07.341 Creating DirectX9 Texture from nio buffer w=117 h=102 Wed 7/11 19:03:07.341 Loading 97x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\GoSleep.png Wed 7/11 19:03:07.344 Creating DirectX9 Texture from nio buffer w=97 h=121 Wed 7/11 19:03:07.345 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=38] Wed 7/11 19:03:07.367 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=38] numGlyphs=1674 numCachedGlyphs=242 numImages=24 Wed 7/11 19:03:07.368 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.369 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.370 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.371 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.372 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.373 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.374 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=60] Wed 7/11 19:03:07.661 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=60] numGlyphs=1674 numCachedGlyphs=235 numImages=76 Wed 7/11 19:03:07.662 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.664 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.664 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.666 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.666 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.668 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.668 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.670 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.671 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.672 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:07.673 Creating DirectX9 Texture from memory w=128 h=128 Wed 7/11 19:03:10.507 UIManager.gotoSleep(true) isTaskbar=false Wed 7/11 19:03:10.507 Sage is going to SLEEP Wed 7/11 19:03:10.507 VF.submitJob(VFJob[Sleep r=0.0 t=0 file=null ifn=null]) Wed 7/11 19:03:10.508 VF processing job VFJob[Sleep r=0.0 t=0 file=null ifn=null] nPlayin=false Wed 7/11 19:03:10.516 setUI(sage.b2@10d2cf0[Main Menu]) histIdx=0 uiHistory=[sage.b2@10d2cf0[Main Menu]] Wed 7/11 19:03:10.535 Removed system shell hook Wed 7/11 19:03:10.537 VF processing on UI Thread VFJob[Sleep r=0.0 t=0 file=null ifn=null] Wed 7/11 19:03:10.537 VF processing job null nPlayin=false Wed 7/11 19:03:10.538 VF thread is now waiting for 0:00:00.000 Wed 7/11 19:03:10.702 MetaImage clearNativeCache nativeImageCacheSize=5505024 Wed 7/11 19:03:10.703 Cleaning up DirectX9 Wed 7/11 19:03:10.705 Doing full DX9 cleanup. Wed 7/11 19:03:10.708 Done cleaning up DirectX9 Wed 7/11 19:03:12.137 Screen pos= 1173, 1012 Wed 7/11 19:03:13.077 Sage.exit() called. Wed 7/11 19:03:13.077 Cleaning up servers Wed 7/11 19:03:13.077 Cleaning up PM Wed 7/11 19:03:13.077 Enabled SS Wed 7/11 19:03:13.078 Killed Carny. Wed 7/11 19:03:13.078 Killed Scheduler. Wed 7/11 19:03:13.078 VF processing job null nPlayin=false Wed 7/11 19:03:13.079 Killed Seeker. Wed 7/11 19:03:13.079 VideoFrame thread is now exiting Wed 7/11 19:03:13.079 Killed EPG. Wed 7/11 19:03:13.081 Killed MMC. Wed 7/11 19:03:13.081 Killing UIMgr UIManager:localhost@@SAGETV_PROCESS_LOCAL_UI-1729854 Wed 7/11 19:03:13.082 Killed UI Timers Wed 7/11 19:03:13.082 Killed VideoFrame Wed 7/11 19:03:13.187 Killed EventRouter Wed 7/11 19:03:14.390 AWTThread Hang Detected - hang time = 750 Wed 7/11 19:03:15.140 AWTThread Hang Detected - hang time = 1500 Wed 7/11 19:03:15.187 Killed RootPanel Wed 7/11 19:03:15.203 Disposed Window Wed 7/11 19:03:15.203 Killed UIManager. Wed 7/11 19:03:15.203 Killed Wizard. Wed 7/11 19:03:15.203 Saving properties file to C:\Program Files\SageTV\SageTV\SageClient.properties Wed 7/11 19:03:15.245 Bye-bye. Wed 7/11 19:03:15.246 SageTV SHUTDOWN is activating!