Tue 4/17 22:09:58.845 user.dir2=C:\Program Files\SageTV\SageTV
Tue 4/17 22:09:58.846 classpath=C:\Program Files\SageTV\SageTV\Sage.jar;C:\Program Files\SageTV\SageTV\xerces.jar;C:\Program Files\SageTV\SageTV\plugin.jar;C:\Program Files\SageTV\SageTV\;;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\sbbi-jmx-1.0.jar;JARs\sbbi-upnplib-1.0.3.jar
Tue 4/17 22:09:58.847 JVM version=1.6.0
Tue 4/17 22:09:58.847 OS=Windows XP 5.1
Tue 4/17 22:09:58.848 Succesfully setup system shell hook
Tue 4/17 22:09:58.848 not specifiy WinkeyboardHook in registery, load default one
Tue 4/17 22:09:59.066 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/splash.gif
Tue 4/17 22:09:59.078 ImageUtils creating BI copy sun.awt.image.ToolkitImage@17e121c w=520 h=350 freeMem=21790864 totalMem=25034752
Tue 4/17 22:09:59.105 SageTV V6.1.8.133
Tue 4/17 22:09:59.110 hostname=htpc
Tue 4/17 22:09:59.178 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon.gif
Tue 4/17 22:09:59.180 ImageUtils creating BI copy sun.awt.image.ToolkitImage@16f25a7 w=32 h=32 freeMem=21362808 totalMem=25034752
Tue 4/17 22:09:59.228 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim0.png
Tue 4/17 22:09:59.241 ImageUtils creating BI copy sun.awt.image.ToolkitImage@8ab708 w=121 h=121 freeMem=20628680 totalMem=25034752
Tue 4/17 22:09:59.242 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim1.png
Tue 4/17 22:09:59.252 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1f3ce5c w=121 h=121 freeMem=20356360 totalMem=25034752
Tue 4/17 22:09:59.253 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim2.png
Tue 4/17 22:09:59.262 ImageUtils creating BI copy sun.awt.image.ToolkitImage@ba5c7a w=121 h=121 freeMem=20100488 totalMem=25034752
Tue 4/17 22:09:59.266 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim3.png
Tue 4/17 22:09:59.270 ImageUtils creating BI copy sun.awt.image.ToolkitImage@340101 w=121 h=121 freeMem=21145640 totalMem=25034752
Tue 4/17 22:09:59.271 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim4.png
Tue 4/17 22:09:59.275 ImageUtils creating BI copy sun.awt.image.ToolkitImage@b7ec5d w=121 h=121 freeMem=20896912 totalMem=25034752
Tue 4/17 22:09:59.275 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim5.png
Tue 4/17 22:09:59.279 ImageUtils creating BI copy sun.awt.image.ToolkitImage@c2b2f6 w=121 h=121 freeMem=20624664 totalMem=25034752
Tue 4/17 22:09:59.280 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim6.png
Tue 4/17 22:09:59.284 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1fe88d w=121 h=121 freeMem=20362168 totalMem=25034752
Tue 4/17 22:09:59.284 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim7.png
Tue 4/17 22:09:59.288 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1b1fbf4 w=121 h=121 freeMem=20103520 totalMem=25034752
Tue 4/17 22:09:59.365 Wizard starting to load database info...
Tue 4/17 22:09:59.366 Wizard DONE loading database info. loadTime=0.0010 sec
Tue 4/17 22:09:59.392 SageTV received connection from:Socket[addr=/127.0.0.1,port=42024,localport=1251]
Tue 4/17 22:09:59.903 Wizard allocating table for Year of size 159
Tue 4/17 22:09:59.904 Wizard loading main index for Year
Tue 4/17 22:09:59.905 Wizard allocating table for Network of size 91
Tue 4/17 22:09:59.906 Wizard loading main index for Network
Tue 4/17 22:09:59.907 Wizard allocating table for Title of size 10830
Tue 4/17 22:09:59.910 Wizard loading main index for Title
Tue 4/17 22:10:00.028 Wizard building alt. index 0 for Title
Tue 4/17 22:10:00.044 Wizard allocating table for Channel of size 279
Tue 4/17 22:10:00.044 Wizard loading main index for Channel
Tue 4/17 22:10:00.050 Wizard allocating table for People of size 44307
Tue 4/17 22:10:00.050 Wizard loading main index for People
Tue 4/17 22:10:00.436 Wizard building alt. index 0 for People
Tue 4/17 22:10:00.503 Wizard allocating table for SubCategory of size 132
Tue 4/17 22:10:00.503 Wizard loading main index for SubCategory
Tue 4/17 22:10:00.504 Wizard allocating table for Rated of size 7
Tue 4/17 22:10:00.504 Wizard loading main index for Rated
Tue 4/17 22:10:00.504 Wizard allocating table for ParentalRating of size 0
Tue 4/17 22:10:00.504 Wizard allocating table for ExtendedRating of size 10
Tue 4/17 22:10:00.504 Wizard loading main index for ExtendedRating
Tue 4/17 22:10:00.505 Wizard allocating table for Category of size 140
Tue 4/17 22:10:00.505 Wizard loading main index for Category
Tue 4/17 22:10:00.506 Wizard allocating table for PrimeTitle of size 0
Tue 4/17 22:10:00.506 Wizard allocating table for Bonus of size 497
Tue 4/17 22:10:00.506 Wizard loading main index for Bonus
Tue 4/17 22:10:00.511 Wizard allocating table for Show of size 33706
Tue 4/17 22:10:00.511 Wizard loading main index for Show
Tue 4/17 22:10:02.506 Sage waiting for GC to free up some memory usage%=0.9636748
Tue 4/17 22:10:02.691 Sage done waiting for GC to free up some memory usage%=0.55870175
Tue 4/17 22:10:03.293 Wizard building alt. index 0 for Show
Tue 4/17 22:10:03.345 Wizard allocating table for Airing of size 77546
Tue 4/17 22:10:03.346 Wizard loading main index for Airing
Tue 4/17 22:10:04.349 Wizard building alt. index 0 for Airing
Tue 4/17 22:10:04.395 Wizard building alt. index 1 for Airing
Tue 4/17 22:10:04.444 Wizard allocating table for ManualRecord of size 1
Tue 4/17 22:10:04.444 Wizard loading main index for ManualRecord
Tue 4/17 22:10:04.447 Wizard allocating table for MediaFile of size 7568
Tue 4/17 22:10:04.447 Wizard loading main index for MediaFile
Tue 4/17 22:10:04.980 Sage waiting for GC to free up some memory usage%=0.9614577
Tue 4/17 22:10:05.268 Sage done waiting for GC to free up some memory usage%=0.55872643
Tue 4/17 22:10:06.668 Wizard building alt. index 0 for MediaFile
Tue 4/17 22:10:06.670 Wizard building alt. index 1 for MediaFile
Tue 4/17 22:10:06.697 Wizard allocating table for Watched of size 1703
Tue 4/17 22:10:06.698 Wizard loading main index for Watched
Tue 4/17 22:10:06.735 Wizard building alt. index 0 for Watched
Tue 4/17 22:10:06.737 Wizard allocating table for Agent of size 5423
Tue 4/17 22:10:06.737 Wizard loading main index for Agent
Tue 4/17 22:10:06.934 Wizard building alt. index 0 for Agent
Tue 4/17 22:10:06.940 Wizard allocating table for Wasted of size 7426
Tue 4/17 22:10:06.941 Wizard loading main index for Wasted
Tue 4/17 22:10:06.982 Wizard allocating table for Playlist of size 1
Tue 4/17 22:10:06.982 Wizard loading main index for Playlist
Tue 4/17 22:10:06.985 Wizard allocating table for TVEditorial of size 67
Tue 4/17 22:10:06.985 Wizard loading main index for TVEditorial
Tue 4/17 22:10:06.997 Wizard allocating table for SeriesInfo of size 1639
Tue 4/17 22:10:06.998 Wizard loading main index for SeriesInfo
Tue 4/17 22:10:07.387 SageTV received connection from:Socket[addr=/127.0.0.1,port=42024,localport=1252]
Tue 4/17 22:10:07.700 MMC calling detectCaptureDevices on sage.p@e8c7db
Tue 4/17 22:10:07.710 devices detected=[Hauppauge WinTV PVR PCI II Capture, Hauppauge WinTV PVR PCI II Capture #2]
Tue 4/17 22:10:07.710 EncoderMap={Hauppauge WinTV PVR PCI II Capture=Hauppauge WinTV PVR PCI II Capture, Hauppauge WinTV PVR PCI II Capture #2=Hauppauge WinTV PVR PCI II Capture #2}
Tue 4/17 22:10:07.755 Checking video directories for new files
Tue 4/17 22:10:07.795 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, CyberLink Audio Decoder (PDVD7 UPnP), CyberLink Audio Decoder (PDVD7), CyberLink Audio Effect (PDVD7), CyberLink Audio Spectrum Analyzer (PDVD7), CyberLink AudioCD Filter (PDVD7), CyberLink Demux (PDVD7 UPnP), CyberLink Demux (PDVD7), CyberLink DVD Navigator (PDVD7), CyberLink H.264/AVC Decoder (PDVD7), CyberLink Line21 Decoder (PDVD7), CyberLink MPEG Splitter(Scramble), CyberLink MPEG-4 Splitter (PDVD7), CyberLink Push-Mode CLStream (PDVD7), CyberLink SAC Video Decoder(PDVD7 HomeNetwork), CyberLink Streamming Filter (PDVD7), Cyberlink SubTitle Importor (PDVD7), CyberLink TimeStretch Filter (PDVD7), CyberLink Video/SP Decoder, CyberLink Video/SP Decoder (PDVD7), Deinterlace Filter, DivX Decoder Filter, DV Muxer, DV Splitter, DV Video Decoder, DVD Navigator, File Source (Async.), File Source (Netshow URL), File Source (URL), File stream renderer, File writer, Frame Eater, Full Screen Renderer, G.711 Codec, Hauppauge WinTV Color Format Converter, Hauppauge WinTV Color Format Converter 2, Hauppauge WinTV File Reader, Hauppauge WinTV File Writer, Hauppauge WinTV MPEG Splitter, Hauppauge WinTV SnapShot, 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, Minimal Null, 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, NSC file Parser, Null Renderer, NVIDIA ATSC File, NVIDIA Audio Decoder, NVIDIA Cd Audio Reader, NVIDIA MultiSource Filter, NVIDIA Navigator, NVIDIA Transport Demux, NVIDIA TS Info Parser, NVIDIA Video Decoder, NVIDIA Video Post Processor, Overlay Mixer, Overlay Mixer2, QT Decompressor, QuickTime Movie Parser, 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, Screen Capture filter, 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, 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, XviD MPEG-4 Video Decoder]
Tue 4/17 22:10:07.818 MsgRecv thread has spawned for /127.0.0.1:42024
Tue 4/17 22:10:07.819 VF thread is now running...
Tue 4/17 22:10:07.819 MsgSend thread spawned for /127.0.0.1:42024
Tue 4/17 22:10:07.820 StorageDeviceDetector started...
Tue 4/17 22:10:07.835 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:10:07.835 UIMgr loading UI from: C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageTV3.xml
Tue 4/17 22:10:07.848 sage.ay@1f9f0f2 needs an update in 23:16:30
Tue 4/17 22:10:07.857 EPG needs an update in 1396 minutes
Tue 4/17 22:10:07.857 EPG's works is done. Waiting...
Tue 4/17 22:10:08.455 locale = en
Tue 4/17 22:10:08.609 UIMgr done loading UI from: C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageTV3.xml
Tue 4/17 22:10:08.648 EVPoolSize=1
Tue 4/17 22:10:08.669 Hidden Extras are DISABLED
Tue 4/17 22:10:08.729 DatePat=EEE, MMM d orgPat=EEEE, MMMM d, yyyy
Tue 4/17 22:10:08.737 setUI(sage.b2@1eae15f[Main Menu]) histIdx=0 uiHistory=[sage.b2@1eae15f[Main Menu]]
Tue 4/17 22:10:08.939 VF processing job null nPlayin=false
Tue 4/17 22:10:08.940 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:10:09.010 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=15]
Tue 4/17 22:10:09.103 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=15] numGlyphs=1674 numCachedGlyphs=282 numImages=4
Tue 4/17 22:10:09.104 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=22]
Tue 4/17 22:10:09.200 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=22] numGlyphs=1674 numCachedGlyphs=241 numImages=7
Tue 4/17 22:10:09.201 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=23]
Tue 4/17 22:10:09.275 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=23] numGlyphs=1674 numCachedGlyphs=264 numImages=8
Tue 4/17 22:10:09.384 Initializing DirectX9
Tue 4/17 22:10:09.387 Creating D3D Device
Tue 4/17 22:10:09.416 Done Initializing DirectX9
Tue 4/17 22:10:09.417 DX9 max texture dimensions is 4096x4096
Tue 4/17 22:10:09.417 Max texture dimension was set as 4096 mine=8192 native=4096 vramlimit=120000000
Tue 4/17 22:10:09.419 Creating DirectX9 Texture from file in memory w=1024 h=576
Tue 4/17 22:10:09.520 Loading 169x59 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageLogo256small.png
Tue 4/17 22:10:09.522 Creating DirectX9 Texture from nio buffer w=169 h=59
Tue 4/17 22:10:09.524 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:10:09.525 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:10:09.526 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:10:09.527 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:10:09.528 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:10:09.529 Loading 586x47 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MenuBarLong.png
Tue 4/17 22:10:09.532 Creating DirectX9 Texture from nio buffer w=586 h=47
Tue 4/17 22:10:09.534 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:10:09.535 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:10:09.536 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.471 SetFullScreen(true)
Tue 4/17 22:11:27.472 FullScreen set to true
Tue 4/17 22:11:27.477 layoutContainer
Tue 4/17 22:11:27.534 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=26]
Tue 4/17 22:11:27.667 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=26] numGlyphs=1674 numCachedGlyphs=259 numImages=9
Tue 4/17 22:11:27.667 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.668 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.669 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.670 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37]
Tue 4/17 22:11:27.756 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37] numGlyphs=1674 numCachedGlyphs=234 numImages=23
Tue 4/17 22:11:27.757 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.758 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.758 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.759 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.760 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.761 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=40]
Tue 4/17 22:11:27.848 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=40] numGlyphs=1674 numCachedGlyphs=236 numImages=24
Tue 4/17 22:11:27.849 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.850 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.851 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.852 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.853 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.854 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.855 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.856 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.862 Switching to DX9 full screen mode...
Tue 4/17 22:11:27.862 MetaImage clearNativeCache nativeImageCacheSize=6094848
Tue 4/17 22:11:27.864 Cleaning up DirectX9
Tue 4/17 22:11:27.865 Doing full DX9 cleanup.
Tue 4/17 22:11:27.868 Done cleaning up DirectX9
Tue 4/17 22:11:27.869 Initializing DirectX9
Tue 4/17 22:11:27.872 Using DX9 Full Screen Exclusive Mode
Tue 4/17 22:11:27.872 Creating D3D Device
Tue 4/17 22:11:27.912 Done Initializing DirectX9
Tue 4/17 22:11:27.912 VF.submitJob(VFJob[ReloadMF r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:27.913 VF processing job VFJob[ReloadMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Tue 4/17 22:11:27.913 VF discarding job VFJob[ReloadMF r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:27.913 VF processing job null nPlayin=false
Tue 4/17 22:11:27.913 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:11:27.916 Creating DirectX9 Texture from file in memory w=1024 h=576
Tue 4/17 22:11:27.973 Loading 169x59 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageLogo256small.png
Tue 4/17 22:11:27.975 Creating DirectX9 Texture from nio buffer w=169 h=59
Tue 4/17 22:11:27.976 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=26]
Tue 4/17 22:11:27.983 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=26] numGlyphs=1674 numCachedGlyphs=259 numImages=9
Tue 4/17 22:11:27.984 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.984 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.985 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.986 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37]
Tue 4/17 22:11:27.994 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37] numGlyphs=1674 numCachedGlyphs=234 numImages=23
Tue 4/17 22:11:27.994 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.995 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.996 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.997 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.997 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:27.998 Loading 586x47 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MenuBarLong.png
Tue 4/17 22:11:28.001 Creating DirectX9 Texture from nio buffer w=586 h=47
Tue 4/17 22:11:28.002 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=40]
Tue 4/17 22:11:28.018 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=40] numGlyphs=1674 numCachedGlyphs=236 numImages=24
Tue 4/17 22:11:28.019 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.020 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.020 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.021 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.022 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.024 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.025 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.026 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.038 Disabling full screen exclusive mode
Tue 4/17 22:11:28.038 MetaImage clearNativeCache nativeImageCacheSize=5570560
Tue 4/17 22:11:28.040 Cleaning up DirectX9
Tue 4/17 22:11:28.041 Resetting D3D device to cleanup FSE properly...
Tue 4/17 22:11:28.068 Doing full DX9 cleanup.
Tue 4/17 22:11:28.070 Done cleaning up DirectX9
Tue 4/17 22:11:28.071 Initializing DirectX9
Tue 4/17 22:11:28.073 Creating D3D Device
Tue 4/17 22:11:28.098 Done Initializing DirectX9
Tue 4/17 22:11:28.098 VF.submitJob(VFJob[ReloadMF r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:28.098 VF processing job VFJob[ReloadMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Tue 4/17 22:11:28.098 VF discarding job VFJob[ReloadMF r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:28.098 VF processing job null nPlayin=false
Tue 4/17 22:11:28.098 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:11:28.099 Creating DirectX9 Texture from file in memory w=1024 h=576
Tue 4/17 22:11:28.158 Loading 169x59 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageLogo256small.png
Tue 4/17 22:11:28.161 Creating DirectX9 Texture from nio buffer w=169 h=59
Tue 4/17 22:11:28.162 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=26]
Tue 4/17 22:11:28.169 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=26] numGlyphs=1674 numCachedGlyphs=259 numImages=9
Tue 4/17 22:11:28.170 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.170 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.171 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.172 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37]
Tue 4/17 22:11:28.180 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37] numGlyphs=1674 numCachedGlyphs=234 numImages=23
Tue 4/17 22:11:28.181 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.182 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.183 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.184 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.185 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.186 Loading 586x47 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MenuBarLong.png
Tue 4/17 22:11:28.189 Creating DirectX9 Texture from nio buffer w=586 h=47
Tue 4/17 22:11:28.191 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=40]
Tue 4/17 22:11:28.200 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=40] numGlyphs=1674 numCachedGlyphs=236 numImages=24
Tue 4/17 22:11:28.217 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.218 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.219 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.220 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.221 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.222 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.226 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:28.228 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:29.337 setUI(sage.b2@a53564[Setup Menu]) histIdx=1 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@a53564[Setup Menu]]
Tue 4/17 22:11:29.381 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:31.072 setUI(sage.b2@1eae15f[Main Menu]) histIdx=0 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@a53564[Setup Menu]]
Tue 4/17 22:11:32.017 setUI(sage.b2@ce623f[SageRecordings SHORTCUTS]) histIdx=1 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@ce623f[SageRecordings SHORTCUTS]]
Tue 4/17 22:11:32.021 setUI(sage.b2@7a140f[SageRecordings STYLE LINKS]) histIdx=1 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@7a140f[SageRecordings STYLE LINKS]]
Tue 4/17 22:11:32.160 setUI(sage.b2@1c7eb33[SageRecordings w/ Optional Preview & AutoCategories]) histIdx=1 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@1c7eb33[SageRecordings w/ Optional Preview & AutoCategories]]
Tue 4/17 22:11:32.404 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=29]
Tue 4/17 22:11:32.472 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=29] numGlyphs=1674 numCachedGlyphs=251 numImages=13
Tue 4/17 22:11:32.472 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:32.473 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:32.474 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:32.475 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:32.475 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:32.476 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:32.477 Premultiplying alpha for BuffImage...
Tue 4/17 22:11:32.478 Creating DirectX9 Texture from memory w=16 h=16
Tue 4/17 22:11:32.480 Loading 27x25 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\up.png
Tue 4/17 22:11:32.480 Creating DirectX9 Texture from nio buffer w=27 h=25
Tue 4/17 22:11:32.481 Loading 26x25 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\down.png
Tue 4/17 22:11:32.481 Creating DirectX9 Texture from nio buffer w=26 h=25
Tue 4/17 22:11:32.661 UI Comp refreshing due to resource load finished of: MediaFile[id=1799406 A[1692266,1683094,"The Rifleman",14765@0414.08:30,30] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] D:\TheRifleman-TheDayaTownSlept-1692266-0.mpg, Seg0[Sat 4/14 8:30:00.018-Sat 4/14 9:00:00.012]]
Tue 4/17 22:11:32.672 Creating DirectX9 Texture from file in memory w=256 h=192
Tue 4/17 22:11:34.068 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerFirstRun.png
Tue 4/17 22:11:34.073 Creating DirectX9 Texture from nio buffer w=121 h=121
Tue 4/17 22:11:34.075 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bolditalic,size=26]
Tue 4/17 22:11:34.184 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bolditalic,size=26] numGlyphs=956 numCachedGlyphs=254 numImages=9
Tue 4/17 22:11:34.184 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:34.185 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:34.186 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:34.187 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:34.187 Loading 96x97 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\RecordFavAll.png
Tue 4/17 22:11:34.189 Creating DirectX9 Texture from nio buffer w=96 h=97
Tue 4/17 22:11:34.190 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerWatched.png
Tue 4/17 22:11:34.192 Creating DirectX9 Texture from nio buffer w=121 h=121
Tue 4/17 22:11:34.318 UI Comp refreshing due to resource load finished of: MediaFile[id=1752795 A[1673801,1673767,"'Til Death",10621@0411.19:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\TilDeath-WebbysNotHappySummerofLove-1673801-0.mpg, Seg0[Wed 4/11 19:00:00.071-Wed 4/11 20:00:00.018]]
Tue 4/17 22:11:34.328 Creating DirectX9 Texture from file in memory w=256 h=192
Tue 4/17 22:11:34.850 MediaFile creating thumbnail to C:\Program Files\SageTV\SageTV\GeneratedThumbnails\DEntourageDogDayAfternoon17061560mpg.jpg for MediaFile[id=1818310 A[1706156,1699661,"Entourage",10240@0417.00:30,30] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] D:\Entourage-DogDayAfternoon-1706156-0.mpg, Seg0[Tue 4/17 0:30:00.018-Tue 4/17 1:00:00.002]]
Tue 4/17 22:11:35.542 UI Comp refreshing due to resource load finished of: MediaFile[id=1818310 A[1706156,1699661,"Entourage",10240@0417.00:30,30] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] D:\Entourage-DogDayAfternoon-1706156-0.mpg, Seg0[Tue 4/17 0:30:00.018-Tue 4/17 1:00:00.002]]
Tue 4/17 22:11:35.560 Creating DirectX9 Texture from file in memory w=256 h=192
Tue 4/17 22:11:36.498 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=19]
Tue 4/17 22:11:36.608 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=19] numGlyphs=1674 numCachedGlyphs=235 numImages=5
Tue 4/17 22:11:36.610 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:36.611 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:36.613 Loading 65x53 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\folder.png
Tue 4/17 22:11:36.615 Creating DirectX9 Texture from nio buffer w=65 h=53
Tue 4/17 22:11:36.771 UI Comp refreshing due to resource load finished of: MediaFile[id=1705407 A[1625990,1625970,"Dark Kingdom: The Dragon King",11097@0404.20:00,120] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture #2 DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\DarkKingdomTheDragonKing-PartTwo-1625990-0.mpg, Seg0[Wed 4/4 20:00:00.081-Wed 4/4 22:00:00.017]]
Tue 4/17 22:11:36.794 Creating DirectX9 Texture from file in memory w=256 h=192
Tue 4/17 22:11:37.049 MediaFile creating thumbnail to C:\Program Files\SageTV\SageTV\GeneratedThumbnails\DBostonPublicChapterThirtyThree17260520mpg.jpg for MediaFile[id=1818336 A[1726052,1725919,"Boston Public",35513@0417.10:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] D:\BostonPublic-ChapterThirtyThree-1726052-0.mpg, Seg0[Tue 4/17 10:00:00.019-Tue 4/17 11:00:00.017]]
Tue 4/17 22:11:37.460 UI Comp refreshing due to resource load finished of: MediaFile[id=1818336 A[1726052,1725919,"Boston Public",35513@0417.10:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] D:\BostonPublic-ChapterThirtyThree-1726052-0.mpg, Seg0[Tue 4/17 10:00:00.019-Tue 4/17 11:00:00.017]]
Tue 4/17 22:11:37.469 Creating DirectX9 Texture from file in memory w=256 h=192
Tue 4/17 22:11:38.983 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=23]
Tue 4/17 22:11:39.108 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=23] numGlyphs=1674 numCachedGlyphs=257 numImages=8
Tue 4/17 22:11:39.109 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:39.110 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:39.111 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:39.318 UI Comp refreshing due to resource load finished of: MediaFile[id=1807145 A[1700484,1700470,"Star Trek: Enterprise",11097@0416.18:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] D:\StarTrekEnterprise-Impulse-1700484-0.mpg, Seg0[Mon 4/16 18:00:00.002-Mon 4/16 19:00:00.002]]
Tue 4/17 22:11:39.326 Creating DirectX9 Texture from file in memory w=256 h=192
Tue 4/17 22:11:39.560 processOptionsMenu optionsMenu=default:null|OptionsMenu:Show Options for a group of airings or imported videos
Tue 4/17 22:11:39.630 BEGIN: Sort video playlists.
Tue 4/17 22:11:39.631 END: Sort video playlists. Sorted 1 playlists. Total Time = '1'
Tue 4/17 22:11:39.658 Loading 146x146 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerInfo.png
Tue 4/17 22:11:39.661 Creating DirectX9 Texture from nio buffer w=146 h=146
Tue 4/17 22:11:39.666 Loading 240x90 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Thumbnailbkgd.png
Tue 4/17 22:11:39.670 Creating DirectX9 Texture from nio buffer w=240 h=90
Tue 4/17 22:11:39.672 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDPlay.png
Tue 4/17 22:11:39.673 Creating DirectX9 Texture from nio buffer w=30 h=26
Tue 4/17 22:11:39.674 Loading 146x146 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerDelete.png
Tue 4/17 22:11:39.680 Creating DirectX9 Texture from nio buffer w=146 h=146
Tue 4/17 22:11:39.682 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerDontLike.png
Tue 4/17 22:11:39.686 Creating DirectX9 Texture from nio buffer w=121 h=121
Tue 4/17 22:11:40.747 EVPoolSize=2
Tue 4/17 22:11:40.764 setUI(sage.b2@12929b2[MediaFileList]) histIdx=2 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@1c7eb33[SageRecordings w/ Optional Preview & AutoCategories], sage.b2@12929b2[MediaFileList]]
Tue 4/17 22:11:41.451 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=24]
Tue 4/17 22:11:41.512 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=24] numGlyphs=1674 numCachedGlyphs=246 numImages=8
Tue 4/17 22:11:41.513 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:41.514 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:41.514 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:41.516 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=22]
Tue 4/17 22:11:41.575 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=22] numGlyphs=1674 numCachedGlyphs=241 numImages=7
Tue 4/17 22:11:41.575 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:41.576 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:41.577 Creating DirectX9 Texture from memory w=128 h=128
Tue 4/17 22:11:42.635 MediaFile creating thumbnail to C:\Program Files\SageTV\SageTV\GeneratedThumbnails\EStarTrekEnterpriseExile17071060mpg.jpg for MediaFile[id=1807149 A[1707106,1707084,"Star Trek: Enterprise",11097@0416.19:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture #2 DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Exile-1707106-0.mpg, Seg0[Mon 4/16 19:00:00.082-Mon 4/16 20:00:00.018]]
Tue 4/17 22:11:43.024 Aborting thumbnail load of MediaFile[id=1807152 A[1707107,1707085,"Star Trek: Enterprise",11097@0416.20:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] D:\StarTrekEnterprise-TheShipment-1707107-0.mpg, Seg0[Mon 4/16 20:00:00.073-Mon 4/16 21:00:00.018]] because nothing is displaying it anymore
Tue 4/17 22:11:43.301 UI Comp refreshing due to resource load finished of: MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:43.321 Creating DirectX9 Texture from file in memory w=256 h=192
Tue 4/17 22:11:43.455 processOptionsMenu optionsMenu=default:null|OptionsMenu:Show Options for an Airing, Recording, File, etc.
Tue 4/17 22:11:43.501 BEGIN: Sort video playlists.
Tue 4/17 22:11:43.502 END: Sort video playlists. Sorted 1 playlists. Total Time = '0'
Tue 4/17 22:11:43.530 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDRecord.png
Tue 4/17 22:11:43.531 Creating DirectX9 Texture from nio buffer w=30 h=26
Tue 4/17 22:11:43.533 Loading 155x138 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerUnChecked.png
Tue 4/17 22:11:43.536 Creating DirectX9 Texture from nio buffer w=155 h=138
Tue 4/17 22:11:44.818 VideoFrame.watch(A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60])
Tue 4/17 22:11:44.819 watchThisFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:44.820 Watch airing is a file & over, do that instead dvd=false
Tue 4/17 22:11:44.822 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] ifn=null])
Tue 4/17 22:11:44.823 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] ifn=null] nPlayin=false
Tue 4/17 22:11:44.829 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] ifn=null]
Tue 4/17 22:11:44.831 VideoFrame creating new media player for file:MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:44.832 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] ifn=null] nPlayin=false
Tue 4/17 22:11:44.833 VF network encoder playback detected: false
Tue 4/17 22:11:44.835 EVPoolSize=3
Tue 4/17 22:11:44.836 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] ifn=null]
Tue 4/17 22:11:44.837 VF file=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] targetTime = Mon 4/16 21:46:54.560
Tue 4/17 22:11:44.837 VideoFrame.timeSelected(Mon 4/16 21:46:54.560, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:44.845 DShowPlayer setVideoRendererFilter0({51B4ABF3-748F-4E3B-A276-C828330E926A}) called
Tue 4/17 22:11:44.884 Setting up VMR9 deinterlacing
Tue 4/17 22:11:44.884 Curr Mix Prefs=0x1015
Tue 4/17 22:11:44.884 Set to 0x2015, hr=0x0, New Mix Prefs=0x2015
Tue 4/17 22:11:44.885 DShowPlayer setAudioRendererFilter0(Default WaveOut Device) called
Tue 4/17 22:11:45.013 DShowPlayer setAudioDecoderFilter0(AC3Filter) called
Tue 4/17 22:11:45.042 setUI(sage.b2@b481ba[MediaPlayer OSD]) histIdx=3 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@1c7eb33[SageRecordings w/ Optional Preview & AutoCategories], sage.b2@12929b2[MediaFileList], sage.b2@b481ba[MediaPlayer OSD]]
Tue 4/17 22:11:45.074 DShowPlayer setTimeshift=0 buffer=0
Tue 4/17 22:11:45.075 Using the default source filter
Tue 4/17 22:11:45.082 ImageUtils loading file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDStatusPause.png
Tue 4/17 22:11:45.085 NATIVE Loading the source file
Tue 4/17 22:11:45.085 NATIVE Setting up the MPEG demux
Tue 4/17 22:11:45.086 ImageUtils creating BI copy sun.awt.image.ToolkitImage@16b7f83 w=6 h=8 freeMem=58803952 totalMem=138354688
Tue 4/17 22:11:45.086 Premultiplying alpha for BuffImage...
Tue 4/17 22:11:45.087 Creating DirectX9 Texture from memory w=6 h=8
Tue 4/17 22:11:45.087 Loading 16x13 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolOn.png
Tue 4/17 22:11:45.088 Creating DirectX9 Texture from nio buffer w=16 h=13
Tue 4/17 22:11:45.088 ImageUtils loading file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolBar.png
Tue 4/17 22:11:45.089 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1a42824 w=64 h=4 freeMem=58766120 totalMem=138354688
Tue 4/17 22:11:45.089 Premultiplying alpha for BuffImage...
Tue 4/17 22:11:45.090 Creating DirectX9 Texture from memory w=64 h=4
Tue 4/17 22:11:45.090 Loading 9x15 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolSlider.png
Tue 4/17 22:11:45.091 Creating DirectX9 Texture from nio buffer w=9 h=15
Tue 4/17 22:11:45.104 NATIVE RenderAudio called stream=1
Tue 4/17 22:11:45.104 NATIVE RenderAudio step 1
Tue 4/17 22:11:45.104 NATIVE RenderAudio step 2
Tue 4/17 22:11:45.104 NATIVE RenderAudio step 3
Tue 4/17 22:11:45.111 NATIVE RenderVideo called
Tue 4/17 22:11:45.112 NATIVE RenderVideo step 1
Tue 4/17 22:11:45.112 NATIVE RenderVideo step 2
Tue 4/17 22:11:45.112 Video source pin information:
Tue 4/17 22:11:45.112 PIN is not connected yet!Tue 4/17 22:11:45.112 M type MEDIATYPE_Video  S type MEDIASUBTYPE_MPEG2_VIDEO F type FORMAT_MPEG2_VIDEO
Tue 4/17 22:11:45.112 NATIVE RenderVideo step 3
Tue 4/17 22:11:45.395 VMR9 alloc 720x540 Offscreen DefaultPool  format=NV12 minBuffs=1 arx=720 ary=540 nativeWidth=720 nativeHeight=540 reqNumBuff=1 allocNumBuff=1 hr=0x0
Tue 4/17 22:11:45.404 Setting up VMR9 deinterlacing
Tue 4/17 22:11:45.404 VM9Deinterlacing Tech: PixelAdaptive
Tue 4/17 22:11:45.404 VM9Deinterlacing Tech: BOBVerticalStretch
Tue 4/17 22:11:45.404 Actual deinterlace: hr=0x0 guid={212DC724-3235-44A4-BD29-E1652BBCC71C}
Tue 4/17 22:11:45.404 deinterlace mode: hr=0x1 guid={00000000-0000-0000-0000-000000000000}
Tue 4/17 22:11:45.404 Setting deinterlace mode to actual mode...
Tue 4/17 22:11:45.404 deinterlace mode: hr=0x0 guid={212DC724-3235-44A4-BD29-E1652BBCC71C}
Tue 4/17 22:11:45.404 Filter Graph Video Renderer Info:
Tue 4/17 22:11:45.404 Video Renderer:CLSID_VideoMixingRenderer9
Tue 4/17 22:11:45.404 M type MEDIATYPE_Video  S type Unknown GUID Name F type FORMAT_VideoInfo2
Tue 4/17 22:11:45.404 Interlaced video
Tue 4/17 22:11:45.405 Both fields per sample
Tue 4/17 22:11:45.405 Field 2 first
Tue 4/17 22:11:45.405 Stream never contains a Field 2
Tue 4/17 22:11:45.405 Either bob or weave mode.
Tue 4/17 22:11:45.405 VMR9 filter detected, using line 21 decoder 2.Tue 4/17 22:11:45.406 DShowGraphFilters=AudRend,VidRend,Line21 Decoder,Audio Decoder,NVIDIA Video Decoder,SageTV MPEG2 Demultiplexer,E:\StarTrekEnterprise-Twilight-1707108-0.mpg,
Tue 4/17 22:11:45.408 VideoFrame.timeSelected2(Mon 4/16 21:46:54.560, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:11:45.408 About to perform media time selection for 2812619
Tue 4/17 22:11:45.408 Running the playback graph.
Tue 4/17 22:11:45.408 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.434 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.444 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.448 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.450 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.451 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.453 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.455 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.457 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.463 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.467 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.471 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.476 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.478 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.480 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:45.543 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.546 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.548 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.548 Event: 0xd l1=0x0 l2=0x0
Tue 4/17 22:11:45.549 Event: 0xe l1=0x0 l2=0x0
Tue 4/17 22:11:45.549 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.549 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.549 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.550 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.550 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.550 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.550 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.550 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.551 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.551 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.551 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.551 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.551 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:45.553 EVPoolSize=4
Tue 4/17 22:11:45.555 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.556 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.559 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.560 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.560 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.560 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.561 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.561 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.561 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.561 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.562 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.562 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.562 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.562 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.562 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.562 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.563 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.563 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.563 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.563 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.563 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.563 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.563 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.564 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.564 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.564 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.564 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.564 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.564 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.564 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.565 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.565 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.565 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.565 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.565 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.565 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.565 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.566 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.566 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.566 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:45.567 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:45.567 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:45.568 VF processing job null nPlayin=true
Tue 4/17 22:11:45.568 isRec=false rd=3597552 base=2812711
Tue 4/17 22:11:45.568 VF thread is now waiting for 0:13:04.591
Tue 4/17 22:11:45.640 Loading 8x8 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDStatusPlay.png
Tue 4/17 22:11:45.640 Creating DirectX9 Texture from nio buffer w=8 h=8
Tue 4/17 22:11:45.952 Created alternate surface
Tue 4/17 22:11:49.980 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Tue 4/17 22:11:49.980 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:49.980 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Tue 4/17 22:11:49.981 VideoFrame.timeSelected(Mon 4/16 21:46:48.983, false) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:49.981 VideoFrame.timeSelected2(Mon 4/16 21:46:48.983, false) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:11:49.981 About to perform media time selection for 2807046
Tue 4/17 22:11:49.991 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:49.993 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:49.996 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:49.998 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:50.000 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:50.004 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:50.004 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:50.004 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:50.006 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:50.006 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:50.006 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:50.006 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:50.006 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:50.021 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:50.022 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:50.024 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:50.024 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:50.025 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:50.025 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:50.025 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:50.025 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:50.025 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:50.026 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:50.026 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:50.026 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:50.028 VF processing job null nPlayin=true
Tue 4/17 22:11:50.028 isRec=false rd=3597552 base=2807055
Tue 4/17 22:11:50.028 VF thread is now waiting for 0:13:10.247
Tue 4/17 22:11:51.921 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Tue 4/17 22:11:51.921 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.921 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Tue 4/17 22:11:51.922 VideoFrame.timeSelected(Mon 4/16 21:46:40.835, false) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:51.922 VideoFrame.timeSelected2(Mon 4/16 21:46:40.835, false) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:11:51.922 About to perform media time selection for 2798903
Tue 4/17 22:11:51.941 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.942 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.943 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.943 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.945 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.952 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.953 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.955 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.957 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.959 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.962 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.962 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.963 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.963 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.963 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.963 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.964 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.965 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.965 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.965 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.965 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.965 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.965 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.965 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:51.966 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.966 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.967 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.968 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:51.968 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.970 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.971 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.971 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.971 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.971 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.971 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.971 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.972 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.972 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.972 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.972 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.972 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.972 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.973 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.973 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.973 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.973 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.973 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.973 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.973 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.974 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.974 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.974 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.974 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:51.975 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:51.976 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:51.977 VF processing job null nPlayin=true
Tue 4/17 22:11:51.978 isRec=false rd=3597552 base=2798915
Tue 4/17 22:11:51.978 VF thread is now waiting for 0:13:18.387
Tue 4/17 22:11:57.470 VF.submitJob(VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null])
Tue 4/17 22:11:57.470 VF processing job VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.470 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null]
Tue 4/17 22:11:57.471 VideoFrame.timeSelected(Mon 4/16 21:49:16.323, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:57.471 VideoFrame.timeSelected2(Mon 4/16 21:49:16.323, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:11:57.471 About to perform media time selection for 2954290
Tue 4/17 22:11:57.581 VF processing job null nPlayin=true
Tue 4/17 22:11:57.581 isRec=false rd=3597552 base=2954290
Tue 4/17 22:11:57.582 VF thread is now waiting for 0:10:43.012
Tue 4/17 22:11:57.624 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.625 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.625 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.626 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.626 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.626 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.627 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.627 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.627 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.627 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.628 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.628 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.628 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.628 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.629 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.629 VF processing job null nPlayin=true
Tue 4/17 22:11:57.629 isRec=false rd=3597552 base=2954304
Tue 4/17 22:11:57.629 VF thread is now waiting for 0:10:42.998
Tue 4/17 22:11:57.630 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.630 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.632 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.633 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.633 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.633 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.633 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.633 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.634 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.634 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.634 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.635 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.635 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.635 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.636 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.636 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.636 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.636 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.637 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.637 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.637 VF processing job null nPlayin=true
Tue 4/17 22:11:57.637 isRec=false rd=3597552 base=2954307
Tue 4/17 22:11:57.638 VF thread is now waiting for 0:10:42.995
Tue 4/17 22:11:57.638 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.638 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.638 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.638 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.638 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.639 VF processing job null nPlayin=true
Tue 4/17 22:11:57.639 isRec=false rd=3597552 base=2954309
Tue 4/17 22:11:57.639 VF thread is now waiting for 0:10:42.993
Tue 4/17 22:11:57.640 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.640 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.640 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.640 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.640 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.641 VF processing job null nPlayin=true
Tue 4/17 22:11:57.641 isRec=false rd=3597552 base=2954311
Tue 4/17 22:11:57.641 VF thread is now waiting for 0:10:42.991
Tue 4/17 22:11:57.641 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.642 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.642 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.642 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.642 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.642 VF processing job null nPlayin=true
Tue 4/17 22:11:57.642 isRec=false rd=3597552 base=2954313
Tue 4/17 22:11:57.643 VF thread is now waiting for 0:10:42.989
Tue 4/17 22:11:57.644 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.644 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.644 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.644 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.645 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.645 VF processing job null nPlayin=true
Tue 4/17 22:11:57.645 isRec=false rd=3597552 base=2954315
Tue 4/17 22:11:57.645 VF thread is now waiting for 0:10:42.987
Tue 4/17 22:11:57.645 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.646 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.646 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.646 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.646 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.646 VF processing job null nPlayin=true
Tue 4/17 22:11:57.646 isRec=false rd=3597552 base=2954317
Tue 4/17 22:11:57.646 VF thread is now waiting for 0:10:42.985
Tue 4/17 22:11:57.647 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.648 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.648 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.648 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.648 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.648 VF processing job null nPlayin=true
Tue 4/17 22:11:57.648 isRec=false rd=3597552 base=2954319
Tue 4/17 22:11:57.648 VF thread is now waiting for 0:10:42.983
Tue 4/17 22:11:57.652 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.652 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.653 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.653 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.653 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.653 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.653 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.654 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.654 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.654 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.654 VF processing job null nPlayin=true
Tue 4/17 22:11:57.654 isRec=false rd=3597552 base=2954324
Tue 4/17 22:11:57.654 VF thread is now waiting for 0:10:42.978
Tue 4/17 22:11:57.655 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:57.655 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:57.656 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:57.656 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:57.656 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:57.656 VF processing job null nPlayin=true
Tue 4/17 22:11:57.656 isRec=false rd=3597552 base=2954326
Tue 4/17 22:11:57.656 VF thread is now waiting for 0:10:42.976
Tue 4/17 22:11:58.552 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:11:58.552 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.552 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:11:58.553 VideoFrame.timeSelected(Mon 4/16 21:49:27.246, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:58.553 VideoFrame.timeSelected2(Mon 4/16 21:49:27.246, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:11:58.553 About to perform media time selection for 2965206
Tue 4/17 22:11:58.563 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.566 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.566 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.566 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.568 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.568 VF processing job null nPlayin=true
Tue 4/17 22:11:58.568 isRec=false rd=3597552 base=2965206
Tue 4/17 22:11:58.568 VF thread is now waiting for 0:10:32.096
Tue 4/17 22:11:58.578 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.578 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.579 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.581 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.581 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.582 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.584 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.584 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.584 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.584 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.584 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.585 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.585 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.587 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.588 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.589 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.589 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.589 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.589 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.589 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.589 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.590 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.590 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.590 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.590 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.590 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.590 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.591 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.591 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.591 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.591 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.591 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.592 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.592 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.592 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.592 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.592 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.592 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.593 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.593 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.594 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.594 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.595 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.595 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.596 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.596 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.596 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.596 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.597 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.597 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.597 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.598 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.598 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.598 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.598 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.598 VF processing job null nPlayin=true
Tue 4/17 22:11:58.599 isRec=false rd=3597552 base=2965215
Tue 4/17 22:11:58.599 VF thread is now waiting for 0:10:32.087
Tue 4/17 22:11:58.599 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.599 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.599 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.599 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.599 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.600 VF processing job null nPlayin=true
Tue 4/17 22:11:58.600 isRec=false rd=3597552 base=2965215
Tue 4/17 22:11:58.600 VF thread is now waiting for 0:10:32.087
Tue 4/17 22:11:58.601 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.601 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.601 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.601 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.602 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.602 VF processing job null nPlayin=true
Tue 4/17 22:11:58.602 isRec=false rd=3597552 base=2965215
Tue 4/17 22:11:58.603 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.603 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.605 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.607 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.607 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.607 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.607 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.608 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.608 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.608 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.609 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.609 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.609 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.609 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.609 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.610 VF processing job null nPlayin=true
Tue 4/17 22:11:58.610 isRec=false rd=3597552 base=2965215
Tue 4/17 22:11:58.610 VF thread is now waiting for 0:10:32.087
Tue 4/17 22:11:58.610 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:58.611 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:58.611 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:58.611 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:58.612 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:58.612 VF processing job null nPlayin=true
Tue 4/17 22:11:58.612 isRec=false rd=3597552 base=2965215
Tue 4/17 22:11:58.612 VF thread is now waiting for 0:10:32.087
Tue 4/17 22:11:59.154 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:11:59.154 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.154 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:11:59.155 VideoFrame.timeSelected(Mon 4/16 21:49:37.778, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:59.156 VideoFrame.timeSelected2(Mon 4/16 21:49:37.778, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:11:59.156 About to perform media time selection for 2975731
Tue 4/17 22:11:59.263 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.265 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.266 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.266 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.269 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.269 VF processing job null nPlayin=true
Tue 4/17 22:11:59.269 isRec=false rd=3597552 base=2975731
Tue 4/17 22:11:59.270 VF thread is now waiting for 0:10:21.571
Tue 4/17 22:11:59.278 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.279 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.280 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.281 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.283 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.285 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.288 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.288 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.288 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.288 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.289 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.289 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.289 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.289 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.290 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.290 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.290 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.291 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.299 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.299 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.300 VF processing job null nPlayin=true
Tue 4/17 22:11:59.300 isRec=false rd=3597552 base=2975754
Tue 4/17 22:11:59.301 VF thread is now waiting for 0:10:21.548
Tue 4/17 22:11:59.319 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.319 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.320 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.320 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.320 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.320 VF processing job null nPlayin=true
Tue 4/17 22:11:59.321 isRec=false rd=3597552 base=2975754
Tue 4/17 22:11:59.321 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.321 VF thread is now waiting for 0:10:21.548
Tue 4/17 22:11:59.321 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.322 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.322 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.322 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.323 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.323 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.323 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.323 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.324 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.324 VF processing job null nPlayin=true
Tue 4/17 22:11:59.326 isRec=false rd=3597552 base=2975754
Tue 4/17 22:11:59.327 VF thread is now waiting for 0:10:21.548
Tue 4/17 22:11:59.730 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:11:59.731 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.735 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:11:59.736 VideoFrame.timeSelected(Mon 4/16 21:49:48.180, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:11:59.736 VideoFrame.timeSelected2(Mon 4/16 21:49:48.180, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:11:59.736 About to perform media time selection for 2986127
Tue 4/17 22:11:59.856 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.858 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.858 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.859 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.862 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.862 VF processing job null nPlayin=true
Tue 4/17 22:11:59.863 isRec=false rd=3597552 base=2986127
Tue 4/17 22:11:59.863 VF thread is now waiting for 0:10:11.175
Tue 4/17 22:11:59.868 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.869 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.870 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.871 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.877 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.877 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.883 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.884 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.890 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.890 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.890 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.903 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:11:59.904 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.904 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.904 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.905 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.905 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:11:59.905 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.905 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.906 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.906 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.906 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.906 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.907 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.907 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.907 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.908 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.911 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.916 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.918 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.919 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.919 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.924 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:11:59.925 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:11:59.925 DShowMediaPlayer is consuming the events...
Tue 4/17 22:11:59.928 VF processing job null nPlayin=true
Tue 4/17 22:11:59.929 isRec=false rd=3597552 base=2986158
Tue 4/17 22:11:59.929 VF thread is now waiting for 0:10:11.144
Tue 4/17 22:12:00.720 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:12:00.721 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.721 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:12:00.722 VideoFrame.timeSelected(Mon 4/16 21:49:58.995, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:12:00.723 VideoFrame.timeSelected2(Mon 4/16 21:49:58.995, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:12:00.723 About to perform media time selection for 2996935
Tue 4/17 22:12:00.834 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.836 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.838 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.838 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.841 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.841 VF processing job null nPlayin=true
Tue 4/17 22:12:00.841 isRec=false rd=3597552 base=2996935
Tue 4/17 22:12:00.842 VF thread is now waiting for 0:10:00.367
Tue 4/17 22:12:00.844 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.844 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.846 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.848 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.849 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.854 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.855 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.855 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.855 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.856 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.856 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.856 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.856 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.856 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.856 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.856 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.857 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.857 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.857 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.858 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.858 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.859 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.859 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.860 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.870 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.870 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.871 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.872 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.874 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.874 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.874 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.875 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.875 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.875 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.875 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.875 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.875 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.876 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.877 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.877 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.878 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.878 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.880 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.882 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.882 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.883 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.883 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.883 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.883 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.884 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.884 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.886 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.886 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.886 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.886 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.887 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.887 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.887 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.887 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.888 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.888 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.889 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.889 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.890 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.890 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.890 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.891 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.892 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.892 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.893 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.893 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.893 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.894 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.894 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.894 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.895 VF processing job null nPlayin=true
Tue 4/17 22:12:00.895 isRec=false rd=3597552 base=2996962
Tue 4/17 22:12:00.895 VF thread is now waiting for 0:10:00.340
Tue 4/17 22:12:00.896 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:00.896 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:00.897 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:00.898 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:00.901 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:00.902 VF processing job null nPlayin=true
Tue 4/17 22:12:00.902 isRec=false rd=3597552 base=2996969
Tue 4/17 22:12:00.904 VF thread is now waiting for 0:10:00.331
Tue 4/17 22:12:01.490 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:12:01.491 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.491 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:12:01.491 VideoFrame.timeSelected(Mon 4/16 21:50:09.609, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:12:01.492 VideoFrame.timeSelected2(Mon 4/16 21:50:09.609, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:12:01.492 About to perform media time selection for 3007542
Tue 4/17 22:12:01.501 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.504 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.504 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.504 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.507 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.507 VF processing job null nPlayin=true
Tue 4/17 22:12:01.508 isRec=false rd=3597552 base=3007542
Tue 4/17 22:12:01.508 VF thread is now waiting for 0:09:49.760
Tue 4/17 22:12:01.520 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.520 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.520 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.524 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.525 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.525 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.525 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.527 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.527 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.531 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.533 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.536 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.537 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.537 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.537 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.538 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.538 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.542 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.543 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.543 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.544 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.544 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.544 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.544 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.545 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.545 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.546 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.546 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.547 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.547 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.547 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.547 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.547 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.548 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.548 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.548 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.550 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:01.551 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.553 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.554 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:01.554 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.555 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.556 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.556 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.558 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.558 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.558 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.559 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.559 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.561 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.563 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.563 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.564 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.564 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.564 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.565 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.565 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.568 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.570 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.571 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.572 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.574 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.575 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.575 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.576 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.576 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.580 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.580 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:01.581 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:01.582 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:01.582 VF processing job null nPlayin=true
Tue 4/17 22:12:01.583 isRec=false rd=3597552 base=3007579
Tue 4/17 22:12:01.583 VF thread is now waiting for 0:09:49.722
Tue 4/17 22:12:02.464 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:12:02.465 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:02.466 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:12:02.466 VideoFrame.timeSelected(Mon 4/16 21:50:20.522, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:12:02.467 VideoFrame.timeSelected2(Mon 4/16 21:50:20.522, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:12:02.467 About to perform media time selection for 3018448
Tue 4/17 22:12:02.472 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:02.473 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:02.474 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:02.474 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:02.477 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:02.477 VF processing job null nPlayin=true
Tue 4/17 22:12:02.477 isRec=false rd=3597552 base=3018448
Tue 4/17 22:12:02.478 VF thread is now waiting for 0:09:38.854
Tue 4/17 22:12:02.484 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:02.485 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:02.485 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:02.486 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:02.486 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:02.487 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:02.487 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:02.487 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:02.487 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:02.492 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:02.505 VF processing job null nPlayin=true
Tue 4/17 22:12:02.509 isRec=false rd=3597552 base=3018466
Tue 4/17 22:12:02.509 VF thread is now waiting for 0:09:38.836
Tue 4/17 22:12:03.078 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:12:03.079 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:03.079 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:12:03.079 VideoFrame.timeSelected(Mon 4/16 21:50:31.080, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:12:03.080 VideoFrame.timeSelected2(Mon 4/16 21:50:31.080, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:12:03.080 About to perform media time selection for 3028999
Tue 4/17 22:12:03.191 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:03.193 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:03.193 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:03.194 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:03.194 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:03.194 VF processing job null nPlayin=true
Tue 4/17 22:12:03.195 isRec=false rd=3597552 base=3028999
Tue 4/17 22:12:03.195 VF thread is now waiting for 0:09:28.303
Tue 4/17 22:12:03.202 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:03.203 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:03.203 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:03.203 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:03.204 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:03.204 VF processing job null nPlayin=true
Tue 4/17 22:12:03.204 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:03.205 isRec=false rd=3597552 base=3029000
Tue 4/17 22:12:03.205 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:03.206 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:03.206 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:03.211 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:03.212 VF processing job null nPlayin=true
Tue 4/17 22:12:03.212 isRec=false rd=3597552 base=3029007
Tue 4/17 22:12:03.212 VF thread is now waiting for 0:09:28.294
Tue 4/17 22:12:03.883 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Tue 4/17 22:12:03.884 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:03.884 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Tue 4/17 22:12:03.885 VideoFrame.timeSelected(Mon 4/16 21:50:41.722, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:12:03.885 VideoFrame.timeSelected2(Mon 4/16 21:50:41.722, true) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:12:03.885 About to perform media time selection for 3039634
Tue 4/17 22:12:03.994 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:03.996 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:03.996 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:03.996 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:03.999 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.000 VF processing job null nPlayin=true
Tue 4/17 22:12:04.000 isRec=false rd=3597552 base=3039634
Tue 4/17 22:12:04.000 VF thread is now waiting for 0:09:17.668
Tue 4/17 22:12:04.002 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.006 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.006 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.007 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.007 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.008 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.008 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.009 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.009 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.009 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.009 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.010 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.010 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.011 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.011 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.011 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.011 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.011 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.012 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.012 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.012 VF processing job null nPlayin=true
Tue 4/17 22:12:04.012 isRec=false rd=3597552 base=3039641
Tue 4/17 22:12:04.013 VF thread is now waiting for 0:09:17.661
Tue 4/17 22:12:04.016 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.016 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.016 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.016 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.018 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.019 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.019 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.019 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.020 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.020 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.020 VF processing job null nPlayin=true
Tue 4/17 22:12:04.021 isRec=false rd=3597552 base=3039650
Tue 4/17 22:12:04.024 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.026 VF thread is now waiting for 0:09:17.650
Tue 4/17 22:12:04.027 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.027 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.035 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.036 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.036 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.036 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.036 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.036 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.036 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.037 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.037 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.037 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.037 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.037 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.038 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.039 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.039 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.039 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.039 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.039 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.040 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.040 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.040 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.040 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.041 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.041 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.041 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.041 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.042 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.042 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.042 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.042 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:04.043 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:04.043 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.044 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.044 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.045 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:04.045 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:04.048 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:04.051 VF processing job null nPlayin=true
Tue 4/17 22:12:04.052 isRec=false rd=3597552 base=3039652
Tue 4/17 22:12:04.055 VF thread is now waiting for 0:09:17.649
Tue 4/17 22:12:05.380 VF.submitJob(VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null])
Tue 4/17 22:12:05.381 VF processing job VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.381 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=-10000 file=null ifn=null]
Tue 4/17 22:12:05.382 VideoFrame.timeSelected(Mon 4/16 21:50:33.061, false) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]]
Tue 4/17 22:12:05.382 VideoFrame.timeSelected2(Mon 4/16 21:50:33.061, false) currFile=MediaFile[id=1807177 A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] host=htpc encodedBy=Hauppauge WinTV PVR PCI II Capture DVD Standard Play format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 6400 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] E:\StarTrekEnterprise-Twilight-1707108-0.mpg, Seg0[Mon 4/16 21:00:00.116-Mon 4/16 22:00:00.002]] realDur=3597552
Tue 4/17 22:12:05.382 About to perform media time selection for 3030979
Tue 4/17 22:12:05.393 VF processing job null nPlayin=true
Tue 4/17 22:12:05.418 isRec=false rd=3597552 base=3030982
Tue 4/17 22:12:05.418 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.418 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.419 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.419 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.419 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.420 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.423 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.423 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.423 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.424 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.424 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.424 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.424 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.425 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.425 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.425 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.425 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.425 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.425 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.426 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.426 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.426 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.426 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.426 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.426 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.427 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.427 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.427 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.427 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.427 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.427 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.428 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.428 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.429 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.429 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.429 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.435 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.435 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.435 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.435 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.436 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.438 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.439 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.439 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:12:05.439 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.439 Event: 0x8065 l1=0x0 l2=0x0
Tue 4/17 22:12:05.439 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.440 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.440 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.443 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.444 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.444 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.446 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.447 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.447 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.447 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.448 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.449 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.451 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.452 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.453 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.454 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.454 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.455 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.455 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.456 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.457 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.457 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.457 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.458 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.458 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.458 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.459 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.459 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.461 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.463 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.463 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.464 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.465 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.465 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.466 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.467 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.468 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:12:05.468 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:12:05.470 DShowMediaPlayer is consuming the events...
Tue 4/17 22:12:05.470 VF processing job null nPlayin=true
Tue 4/17 22:12:05.470 isRec=false rd=3597552 base=3031021
Tue 4/17 22:12:05.470 VF thread is now waiting for 0:09:26.281
Tue 4/17 22:15:07.878 VF processing job null nPlayin=true
Tue 4/17 22:15:07.878 isRec=false rd=3597552 base=3213411
Tue 4/17 22:15:07.878 VF thread is now waiting for 0:06:23.891
Tue 4/17 22:20:08.063 VF processing job null nPlayin=true
Tue 4/17 22:20:08.063 isRec=false rd=3597552 base=3513568
Tue 4/17 22:20:08.065 VF thread is now waiting for 0:01:23.733
Tue 4/17 22:20:55.961 VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:20:55.961 VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Tue 4/17 22:20:55.961 CloseAndWait is executing the close job synchronously now
Tue 4/17 22:20:55.962 VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:20:55.972 VF logFileWatch doneAir=A[1707108,1707087,"Star Trek: Enterprise",11097@0416.21:00,60] theTime=Mon 4/16 21:59:23.889 recTime=Mon 4/16 21:00:00.116
Tue 4/17 22:20:55.974 Stopping the playback graph.
Tue 4/17 22:20:56.033 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:20:56.033 Tearing down playback graph
Tue 4/17 22:20:56.033 NATIVE Deallocating the CPlayerData object
Tue 4/17 22:20:56.144 NATIVE Finished destroying the playback graph
Tue 4/17 22:20:56.145 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=false
Tue 4/17 22:20:56.146 VF discarding job VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:20:56.146 VF processing job null nPlayin=false
Tue 4/17 22:20:56.146 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:20:56.148 setUI(sage.b2@12929b2[MediaFileList]) histIdx=2 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@1c7eb33[SageRecordings w/ Optional Preview & AutoCategories], sage.b2@12929b2[MediaFileList], sage.b2@b481ba[MediaPlayer OSD]]
Tue 4/17 22:20:56.309 VF processing job null nPlayin=false
Tue 4/17 22:20:56.309 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:20:56.362 VF processing job null nPlayin=false
Tue 4/17 22:20:56.363 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:21:07.737 setUI(sage.b2@1299938[Main Menu]) histIdx=3 uiHistory=[sage.b2@1eae15f[Main Menu], sage.b2@1c7eb33[SageRecordings w/ Optional Preview & AutoCategories], sage.b2@12929b2[MediaFileList], sage.b2@1299938[Main Menu]]
Tue 4/17 22:21:49.588 UIManager.gotoSleep(true) isTaskbar=false
Tue 4/17 22:21:49.588 Sage is going to SLEEP
Tue 4/17 22:21:49.589 VF.submitJob(VFJob[Sleep r=0.0 t=0 file=null ifn=null])
Tue 4/17 22:21:49.589 VF processing job VFJob[Sleep r=0.0 t=0 file=null ifn=null] nPlayin=false
Tue 4/17 22:21:49.589 setUI(sage.b2@1299938[Main Menu]) histIdx=0 uiHistory=[sage.b2@1299938[Main Menu]]
Tue 4/17 22:21:49.937 Removed system shell hook
Tue 4/17 22:21:49.940 VF processing on UI Thread VFJob[Sleep r=0.0 t=0 file=null ifn=null]
Tue 4/17 22:21:49.943 VF processing job null nPlayin=false
Tue 4/17 22:21:49.943 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:21:49.960 MetaImage clearNativeCache nativeImageCacheSize=10080768
Tue 4/17 22:21:49.964 Cleaning up DirectX9
Tue 4/17 22:21:49.966 Doing full DX9 cleanup.
Tue 4/17 22:21:49.973 Done cleaning up DirectX9
Tue 4/17 22:25:00.069 VF processing job null nPlayin=false
Tue 4/17 22:25:00.070 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:25:07.851 Saving properties file to C:\Program Files\SageTV\SageTV\SageClient.properties
Tue 4/17 22:30:01.357 VF processing job null nPlayin=false
Tue 4/17 22:30:01.357 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:32:20.892 VF processing job null nPlayin=false
Tue 4/17 22:32:20.892 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:37:21.016 VF processing job null nPlayin=false
Tue 4/17 22:37:21.017 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:40:07.918 Saving properties file to C:\Program Files\SageTV\SageTV\SageClient.properties
Tue 4/17 22:41:57.364 Screen Saver Timeout expired....tv=false playin=false fs=true sleepy=true currSS=false
Tue 4/17 22:42:21.214 VF processing job null nPlayin=false
Tue 4/17 22:42:21.215 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:46:23.426 VF processing job null nPlayin=false
Tue 4/17 22:46:23.426 VF thread is now waiting for 0:00:00.000
Tue 4/17 22:51:23.544 VF processing job null nPlayin=false
Tue 4/17 22:51:23.544 VF thread is now waiting for 0:00:00.000