Sun 4/29 15:20:40.307 user.dir2=C:\Program Files\SageTV\SageTV
Sun 4/29 15:20:40.312 classpath=C:\Program Files\SageTV\SageTV\Sage.jar;C:\Program Files\SageTV\SageTV\plugin.jar;C:\Program Files\SageTV\SageTV\;;JARs\commons-codec-1.3.jar;JARs\commons-collections-3.2.jar;JARs\commons-httpclient-3.0.1.jar;JARs\commons-jxpath-1.1.jar;JARs\commons-logging-api.jar;JARs\commons-logging.jar;JARs\df_sageutils.jar;JARs\jcifs-1.1.6.jar;JARs\jdic.jar;JARs\jflac-1.0.jar;JARs\jl1.0.jar;JARs\jnlp.jar;JARs\jogg-0.0.7.jar;JARs\jorbis-0.0.15.jar;JARs\jsch-20040429.jar;JARs\log4j.jar;JARs\mp3spi1.9.2.jar;JARs\mx4j-impl.jar;JARs\mx4j-jmx.jar;JARs\mx4j-remote.jar;JARs\mx4j-tools.jar;JARs\nielm_getrssnews.jar;JARs\nielm_sageimdb.jar;JARs\nielm_sagemenureader.jar;JARs\nielm_sageutls.jar;JARs\sagemc.jar;JARs\sbbi-jmx-1.0.jar;JARs\sbbi-upnplib-1.0.3.jar;JARs\slimplayer.jar;JARs\SoftSqueeze.jar;JARs\tritonus_share.jar;JARs\vorbisspi1.0.2.jar
Sun 4/29 15:20:40.312 JVM version=1.6.0
Sun 4/29 15:20:40.312 OS=Windows 2003 5.2
Sun 4/29 15:20:40.324 Succesfully setup system shell hook
Sun 4/29 15:20:40.324 not specifiy WinkeyboardHook in registery, load default one
Sun 4/29 15:20:40.504 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/splashclient.gif
Sun 4/29 15:20:40.520 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1d6f122 w=520 h=350 freeMem=22259216 totalMem=25034752
Sun 4/29 15:20:40.538 SageTV V6.1.8.133
Sun 4/29 15:20:40.548 hostname=livingroom
Sun 4/29 15:20:40.611 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon.gif
Sun 4/29 15:20:40.613 ImageUtils creating BI copy sun.awt.image.ToolkitImage@491c4c w=32 h=32 freeMem=20779512 totalMem=25034752
Sun 4/29 15:20:40.650 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim0.png
Sun 4/29 15:20:40.669 ImageUtils creating BI copy sun.awt.image.ToolkitImage@82d37 w=121 h=121 freeMem=20026360 totalMem=25034752
Sun 4/29 15:20:40.670 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim1.png
Sun 4/29 15:20:40.688 ImageUtils creating BI copy sun.awt.image.ToolkitImage@609959 w=121 h=121 freeMem=21004336 totalMem=25034752
Sun 4/29 15:20:40.689 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim2.png
Sun 4/29 15:20:40.693 ImageUtils creating BI copy sun.awt.image.ToolkitImage@ba5c7a w=121 h=121 freeMem=20755688 totalMem=25034752
Sun 4/29 15:20:40.693 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim3.png
Sun 4/29 15:20:40.696 ImageUtils creating BI copy sun.awt.image.ToolkitImage@7cbde6 w=121 h=121 freeMem=20483256 totalMem=25034752
Sun 4/29 15:20:40.697 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim4.png
Sun 4/29 15:20:40.700 ImageUtils creating BI copy sun.awt.image.ToolkitImage@2e1f1f w=121 h=121 freeMem=20218968 totalMem=25034752
Sun 4/29 15:20:40.701 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim5.png
Sun 4/29 15:20:40.704 ImageUtils creating BI copy sun.awt.image.ToolkitImage@fefe3f w=121 h=121 freeMem=19947240 totalMem=25034752
Sun 4/29 15:20:40.705 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim6.png
Sun 4/29 15:20:40.709 ImageUtils creating BI copy sun.awt.image.ToolkitImage@149d886 w=121 h=121 freeMem=19698912 totalMem=25034752
Sun 4/29 15:20:40.710 ImageUtils loading URL jar:file:/C:/Program%20Files/SageTV/SageTV/Sage.jar!/images/tvicon_anim7.png
Sun 4/29 15:20:40.716 ImageUtils creating BI copy sun.awt.image.ToolkitImage@b60b93 w=121 h=121 freeMem=20618776 totalMem=25034752
Sun 4/29 15:20:40.776 Wizard starting to load database info...
Sun 4/29 15:20:40.777 Wizard DONE loading database info. loadTime=0.0010 sec
Sun 4/29 15:20:40.800 SageTV received connection from:Socket[addr=/10.0.1.100,port=42024,localport=4097]
Sun 4/29 15:20:41.277 Wizard allocating table for Year of size 90
Sun 4/29 15:20:41.277 Wizard loading main index for Year
Sun 4/29 15:20:41.278 Wizard allocating table for Network of size 134
Sun 4/29 15:20:41.279 Wizard loading main index for Network
Sun 4/29 15:20:41.280 Wizard allocating table for Title of size 8580
Sun 4/29 15:20:41.280 Wizard loading main index for Title
Sun 4/29 15:20:41.427 Wizard building alt. index 0 for Title
Sun 4/29 15:20:41.438 Wizard allocating table for Channel of size 574
Sun 4/29 15:20:41.438 Wizard loading main index for Channel
Sun 4/29 15:20:41.453 Wizard allocating table for People of size 24939
Sun 4/29 15:20:41.453 Wizard loading main index for People
Sun 4/29 15:20:41.643 Wizard building alt. index 0 for People
Sun 4/29 15:20:41.667 Wizard allocating table for SubCategory of size 134
Sun 4/29 15:20:41.668 Wizard loading main index for SubCategory
Sun 4/29 15:20:41.668 Wizard allocating table for Rated of size 7
Sun 4/29 15:20:41.668 Wizard loading main index for Rated
Sun 4/29 15:20:41.669 Wizard allocating table for ParentalRating of size 0
Sun 4/29 15:20:41.669 Wizard allocating table for ExtendedRating of size 10
Sun 4/29 15:20:41.669 Wizard loading main index for ExtendedRating
Sun 4/29 15:20:41.669 Wizard allocating table for Category of size 160
Sun 4/29 15:20:41.669 Wizard loading main index for Category
Sun 4/29 15:20:41.670 Wizard allocating table for PrimeTitle of size 0
Sun 4/29 15:20:41.670 Wizard allocating table for Bonus of size 222
Sun 4/29 15:20:41.670 Wizard loading main index for Bonus
Sun 4/29 15:20:41.673 Wizard allocating table for Show of size 24705
Sun 4/29 15:20:41.673 Wizard loading main index for Show
Sun 4/29 15:20:43.410 Wizard building alt. index 0 for Show
Sun 4/29 15:20:43.440 Wizard allocating table for Airing of size 92195
Sun 4/29 15:20:43.445 Wizard loading main index for Airing
Sun 4/29 15:20:43.666 Sage waiting for GC to free up some memory usage%=0.96404904
Sun 4/29 15:20:43.822 Sage done waiting for GC to free up some memory usage%=0.5585093
Sun 4/29 15:20:44.777 Wizard building alt. index 0 for Airing
Sun 4/29 15:20:44.830 Wizard building alt. index 1 for Airing
Sun 4/29 15:20:44.880 Wizard allocating table for ManualRecord of size 0
Sun 4/29 15:20:44.880 Wizard allocating table for MediaFile of size 2641
Sun 4/29 15:20:44.880 Wizard loading main index for MediaFile
Sun 4/29 15:20:45.362 Wizard building alt. index 0 for MediaFile
Sun 4/29 15:20:45.364 Wizard building alt. index 1 for MediaFile
Sun 4/29 15:20:45.373 Wizard allocating table for Watched of size 3082
Sun 4/29 15:20:45.373 Wizard loading main index for Watched
Sun 4/29 15:20:45.433 Wizard building alt. index 0 for Watched
Sun 4/29 15:20:45.435 Wizard allocating table for Agent of size 542
Sun 4/29 15:20:45.436 Wizard loading main index for Agent
Sun 4/29 15:20:45.454 Wizard building alt. index 0 for Agent
Sun 4/29 15:20:45.457 Wizard allocating table for Wasted of size 4310
Sun 4/29 15:20:45.457 Wizard loading main index for Wasted
Sun 4/29 15:20:45.470 Wizard allocating table for Playlist of size 108
Sun 4/29 15:20:45.471 Wizard loading main index for Playlist
Sun 4/29 15:20:45.480 Wizard allocating table for TVEditorial of size 65
Sun 4/29 15:20:45.480 Wizard loading main index for TVEditorial
Sun 4/29 15:20:45.489 Wizard allocating table for SeriesInfo of size 1702
Sun 4/29 15:20:45.490 Wizard loading main index for SeriesInfo
Sun 4/29 15:20:45.943 SageTV received connection from:Socket[addr=/10.0.1.100,port=42024,localport=4098]
Sun 4/29 15:20:46.189 MMC calling detectCaptureDevices on sage.p@b40443
Sun 4/29 15:20:46.201 devices detected=[Hauppauge WinTV PVR PCI II Capture #3, Hauppauge WinTV PVR PCI II Capture, EVO DEVICE - 00045A786C56 on localhost:6200, Hauppauge WinTV PVR PCI II Capture #2]
Sun 4/29 15:20:46.202 EncoderMap={Hauppauge WinTV PVR PCI II Capture #3=Hauppauge WinTV PVR PCI II Capture #3, 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, EVO DEVICE - 00045A786C56 on localhost:6200=EVO DEVICE - 00045A786C56 on localhost:6200}
Sun 4/29 15:20:46.227 Checking video directories for new files
Sun 4/29 15:20:46.253 DShowFilters=[.RAM file Parser, AC3 Parser Filter, AC3Filter, ACELP.net Sipro Lab Audio Decoder, ACM Wrapper, ASF ACM Handler, ASF DIB Handler, ASF DJPEG Handler, ASF embedded stuff Handler, ASF ICM Handler, ASF JPEG Handler, ASF URL Handler, ASX file Parser, ASX v.2 file Parser, AVI Decompressor, AVI Draw, AVI Mux, AVI Splitter, AVI/WAV File Source, Color Space Converter, Deinterlace Filter, DV Muxer, DV Splitter, DV Video Decoder, DVD Navigator, File Source (Async.), File Source (Netshow URL), File Source (URL), File stream renderer, File writer, Full Screen Renderer, G.711 Codec, Infinite Pin Tee Filter, Internal Script Command Renderer, Line 21 Decoder, Line 21 Decoder 2, Microsoft MPEG-4 Video Decompressor, Microsoft Screen Video Decompressor, MIDI Parser, MJPEG Decompressor, MPEG Audio Decoder, MPEG Layer-3 Decoder, MPEG Video Decoder, MPEG-2 Demultiplexer, MPEG-2 Sections and Tables, MPEG-2 Splitter, 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, 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, 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, WMAPro over S/PDIF DMO, WMAudio Decoder DMO, WMSpeech Decoder DMO, WMV Screen decoder DMO, WMVideo Advanced Decoder DMO, WMVideo Decoder DMO, WST Decoder, XML Playlist]
Sun 4/29 15:20:46.266 UIMgr loading UI from: C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageTV3.xml
Sun 4/29 15:20:46.891 VF thread is now running...
Sun 4/29 15:20:46.891 MsgSend thread spawned for /10.0.1.100:42024
Sun 4/29 15:20:46.892 MsgRecv thread has spawned for /10.0.1.100:42024
Sun 4/29 15:20:46.893 VF thread is now waiting for 0:00:00.000
Sun 4/29 15:20:46.893 Setting the system clock to be Sun 4/29 15:20:48.046
Sun 4/29 15:20:48.126 locale = en
Sun 4/29 15:20:48.710 AWTThread Hang Detected - hang time = 908
Sun 4/29 15:20:48.862 StorageDeviceDetector started...
Sun 4/29 15:20:48.863 sage.a5@b8f952 needs an update in 8:04:40
Sun 4/29 15:20:48.867 UIMgr done loading UI from: C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageTV3.xml
Sun 4/29 15:20:48.897 EVPoolSize=1
Sun 4/29 15:20:48.987 Hidden Extras are DISABLED
Sun 4/29 15:20:49.020 DatePat=EEE, MMM d orgPat=EEEE, MMMM d, yyyy
Sun 4/29 15:20:49.028 setUI(sage.b2@1da59fa[Main Menu]) histIdx=0 uiHistory=[sage.b2@1da59fa[Main Menu]]
Sun 4/29 15:20:49.034 sage.ay@ced6e2 needs an update in 1:02:17
Sun 4/29 15:20:49.034 sage.ay@17da562 needs an update in 9:30:01
Sun 4/29 15:20:49.034 EPG needs an update in 62 minutes
Sun 4/29 15:20:49.035 EPG's works is done. Waiting...
Sun 4/29 15:20:49.196 VF processing job null nPlayin=false
Sun 4/29 15:20:49.197 VF thread is now waiting for 0:00:00.000
Sun 4/29 15:20:49.229 EVPoolSize=2
Sun 4/29 15:20:49.256 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=36]
Sun 4/29 15:20:49.345 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=36] numGlyphs=1674 numCachedGlyphs=241 numImages=23
Sun 4/29 15:20:49.354 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=52]
Sun 4/29 15:20:49.447 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=52] numGlyphs=1674 numCachedGlyphs=236 numImages=32
Sun 4/29 15:20:49.448 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=56]
Sun 4/29 15:20:49.554 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=plain,size=56] numGlyphs=1674 numCachedGlyphs=235 numImages=69
Sun 4/29 15:20:49.559 Initializing DirectX9
Sun 4/29 15:20:49.593 Creating D3D Device
Sun 4/29 15:20:49.643 Done Initializing DirectX9
Sun 4/29 15:20:49.643 DX9 max texture dimensions is 4096x4096
Sun 4/29 15:20:49.647 Max texture dimension was set as 4096 mine=8192 native=4096 vramlimit=120000000
Sun 4/29 15:20:49.649 Creating DirectX9 Texture from file in memory w=1024 h=576
Sun 4/29 15:20:49.701 Loading 169x59 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\SageLogo256small.png
Sun 4/29 15:20:49.703 Creating DirectX9 Texture from nio buffer w=169 h=59
Sun 4/29 15:20:49.705 Loading 61x55 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\ConflictUnresolved.png
Sun 4/29 15:20:49.706 Creating DirectX9 Texture from nio buffer w=61 h=55
Sun 4/29 15:20:49.706 Loading 118x103 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\GoFullscreen.png
Sun 4/29 15:20:49.708 Creating DirectX9 Texture from nio buffer w=118 h=103
Sun 4/29 15:20:49.708 Loading 97x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\GoSleep.png
Sun 4/29 15:20:49.709 Creating DirectX9 Texture from nio buffer w=97 h=121
Sun 4/29 15:20:49.710 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.711 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.711 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.712 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.713 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.713 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.714 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.716 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.717 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.717 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.718 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.718 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.719 Loading 586x47 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MenuBarLong.png
Sun 4/29 15:20:49.721 Creating DirectX9 Texture from nio buffer w=586 h=47
Sun 4/29 15:20:49.722 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.723 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.724 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.724 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.725 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.725 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.726 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.726 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.727 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.728 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.728 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.729 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.730 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.731 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.733 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:49.735 Loading 82x82 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23302.img
Sun 4/29 15:20:49.738 Creating DirectX9 Texture from nio buffer w=82 h=82
Sun 4/29 15:20:49.800 Loading 82x82 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23303.img
Sun 4/29 15:20:49.807 Creating DirectX9 Texture from nio buffer w=82 h=82
Sun 4/29 15:20:49.833 Loading 82x82 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23304.img
Sun 4/29 15:20:49.837 Creating DirectX9 Texture from nio buffer w=82 h=82
Sun 4/29 15:20:49.853 Loading 82x82 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23305.img
Sun 4/29 15:20:49.860 Creating DirectX9 Texture from nio buffer w=82 h=82
Sun 4/29 15:20:49.897 Loading 82x82 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23306.img
Sun 4/29 15:20:49.904 Creating DirectX9 Texture from nio buffer w=82 h=82
Sun 4/29 15:20:49.940 Loading 82x82 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23307.img
Sun 4/29 15:20:49.944 Creating DirectX9 Texture from nio buffer w=82 h=82
Sun 4/29 15:20:56.155 setUI(sage.b2@1dd66fd[Library]) histIdx=1 uiHistory=[sage.b2@1da59fa[Main Menu], sage.b2@1dd66fd[Library]]
Sun 4/29 15:20:56.175 Disabled SS
Sun 4/29 15:20:56.188 Loading 14x71 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\guideLeft.png
Sun 4/29 15:20:56.189 Creating DirectX9 Texture from nio buffer w=14 h=71
Sun 4/29 15:20:56.190 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:56.190 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:56.192 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:58.326 setUI(sage.b2@6bf80b[Browser - Music]) histIdx=2 uiHistory=[sage.b2@1da59fa[Main Menu], sage.b2@1dd66fd[Library], sage.b2@6bf80b[Browser - Music]]
Sun 4/29 15:20:58.375 setUI(sage.b2@920ee7[Browser - Music]) histIdx=2 uiHistory=[sage.b2@1da59fa[Main Menu], sage.b2@1dd66fd[Library], sage.b2@920ee7[Browser - Music]]
Sun 4/29 15:20:58.385 BEGIN: Get all music files
Sun 4/29 15:20:58.386  ==> Get all music files. GetMediaFiles() Time = '1'
Sun 4/29 15:20:58.390  ==> Get all music files. Filter by IsMusicFile Time = '4'
Sun 4/29 15:20:58.395  ==> Get all music files. Filter by IsLibraryFile Time = '5'
Sun 4/29 15:20:58.396 END: Get all music files. Found 2221 songs. Total Time = '11'
Sun 4/29 15:20:58.413 BEGIN: Sort playlists.
Sun 4/29 15:20:58.427 END: Sort playlists. Sorted 108 playlists. Total Time = '13'
Sun 4/29 15:20:58.428 BEGIN: Group songs by genre.
Sun 4/29 15:20:58.436  ==> Group by genre via GetShowCategory: Time = '8'
Sun 4/29 15:20:58.442  ==> Sort genre group: Time = '6'
Sun 4/29 15:20:58.445 END: Group songs by genre. Found 63 genres. Total Time = '17'
Sun 4/29 15:20:58.457 Starting to wait for user input timeout
Sun 4/29 15:20:58.473 Generating album cache...
Sun 4/29 15:20:58.480 Done generating album cache.
Sun 4/29 15:20:58.525 EVPoolSize=3
Sun 4/29 15:20:58.556 ImageUtils loading binary data length=4176
Sun 4/29 15:20:58.559 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1bee6f w=130 h=130 freeMem=33490616 totalMem=108527616
Sun 4/29 15:20:58.587 ImageUtils loading binary data length=58934
Sun 4/29 15:20:58.595 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1b0a038 w=366 h=324 freeMem=32404288 totalMem=108527616
Sun 4/29 15:20:58.618 ImageUtils loading binary data length=12858
Sun 4/29 15:20:58.621 ImageUtils creating BI copy sun.awt.image.ToolkitImage@fc7ceb w=170 h=170 freeMem=31538224 totalMem=108527616
Sun 4/29 15:20:58.656 ImageUtils loading binary data length=78171
Sun 4/29 15:20:58.682 ImageUtils creating BI copy sun.awt.image.ToolkitImage@c9c2d7 w=500 h=500 freeMem=33864312 totalMem=108527616
Sun 4/29 15:20:58.702 ImageUtils loading binary data length=28132
Sun 4/29 15:20:58.708 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1c69f7b w=301 h=300 freeMem=32098688 totalMem=108527616
Sun 4/29 15:20:58.734 ImageUtils loading binary data length=39871
Sun 4/29 15:20:58.740 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1120eed w=301 h=300 freeMem=30898512 totalMem=108527616
Sun 4/29 15:20:58.886 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:58.887 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:58.892 BEGIN: Group songs by year.
Sun 4/29 15:20:58.894  ==> Group by year via GetShowYear: Time = '2'
Sun 4/29 15:20:58.895  ==> Sort year group: Time = '1'
Sun 4/29 15:20:58.897 END: Group songs by year. Found 42 years. Total Time = '5'
Sun 4/29 15:20:58.898 BEGIN: Get all filenames
Sun 4/29 15:20:58.916  ==> Get all filenames. GroupByArrayMethod() Time = '18'
Sun 4/29 15:20:58.918  ==> Get all filenames.  get keySet. Time = '2'
Sun 4/29 15:20:59.010  ==> Get all filenames.  Convert to Strings. Time = '92'
Sun 4/29 15:20:59.014  ==> Get all filenames. Sort Time = '4'
Sun 4/29 15:20:59.015 END: Get all filenames. Found 2221 filenames. Total Time = '117'
Sun 4/29 15:20:59.015  ++++++++ Music folder browser:  LastCurFolder = null; CurFolder = null; LastMusicFolder = null
Sun 4/29 15:20:59.079  ++++++++ Music folder browser:  Reloaded folder info
Sun 4/29 15:20:59.080 BEGIN: Sort songs by title.
Sun 4/29 15:20:59.124  ==> Sort all songs by GetMediaTitle: Time = '43'
Sun 4/29 15:20:59.125 END: Sort songs by title. Found 2221 songs. Total Time = '45'
Sun 4/29 15:20:59.125 BEGIN: Group songs by album.
Sun 4/29 15:20:59.133 User input timeout
Sun 4/29 15:20:59.169 END: Group songs by album. Found 345 albums. Total Time = '44'
Sun 4/29 15:20:59.169 BEGIN: Group songs by artist.
Sun 4/29 15:20:59.193  ==> Group by artist via GetPeopleListInShowInRoles (Artist;Album Artist): Time = '23'
Sun 4/29 15:20:59.198  ==> Sort artist group: Time = '5'
Sun 4/29 15:20:59.225 END: Group songs by artist. Found 590 artists. Total Time = '55'
Sun 4/29 15:20:59.231 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.232 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.232 Loading 240x90 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\Thumbnailbkgd.png
Sun 4/29 15:20:59.234 Creating DirectX9 Texture from nio buffer w=240 h=90
Sun 4/29 15:20:59.235 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicStyleNext.png
Sun 4/29 15:20:59.237 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:20:59.237 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicStylePrev.png
Sun 4/29 15:20:59.239 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:20:59.240 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicOptions.png
Sun 4/29 15:20:59.241 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:20:59.242 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicBack.png
Sun 4/29 15:20:59.243 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:20:59.244 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicRandOnRptOff.png
Sun 4/29 15:20:59.245 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:20:59.245 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=44]
Sun 4/29 15:20:59.318 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=44] numGlyphs=1674 numCachedGlyphs=235 numImages=28
Sun 4/29 15:20:59.319 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.320 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.320 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.321 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=27]
Sun 4/29 15:20:59.393 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=27] numGlyphs=1674 numCachedGlyphs=248 numImages=12
Sun 4/29 15:20:59.394 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.394 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.395 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.395 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.396 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.396 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.397 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.397 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.399 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.400 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:20:59.401 Loading 27x25 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\up.png
Sun 4/29 15:20:59.401 Creating DirectX9 Texture from nio buffer w=27 h=25
Sun 4/29 15:20:59.402 Loading 26x25 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\down.png
Sun 4/29 15:20:59.403 Creating DirectX9 Texture from nio buffer w=26 h=25
Sun 4/29 15:20:59.466 Loading 130x130 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23308.img
Sun 4/29 15:20:59.478 Creating DirectX9 Texture from nio buffer w=130 h=130
Sun 4/29 15:20:59.503 Loading 366x324 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23309.img
Sun 4/29 15:20:59.514 Creating DirectX9 Texture from nio buffer w=366 h=324
Sun 4/29 15:20:59.537 Loading 170x170 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23310.img
Sun 4/29 15:20:59.539 Creating DirectX9 Texture from nio buffer w=170 h=170
Sun 4/29 15:20:59.572 Loading 500x500 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23311.img
Sun 4/29 15:20:59.589 Creating DirectX9 Texture from nio buffer w=500 h=500
Sun 4/29 15:20:59.613 Loading 301x300 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23312.img
Sun 4/29 15:20:59.620 Creating DirectX9 Texture from nio buffer w=301 h=300
Sun 4/29 15:20:59.643 Loading 301x300 image from file C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\stv23313.img
Sun 4/29 15:20:59.651 Creating DirectX9 Texture from nio buffer w=301 h=300
Sun 4/29 15:21:01.075 processOptionsMenu optionsMenu=default:null|OptionsMenu:Show options for song or group of songs
Sun 4/29 15:21:01.148 Loading 146x146 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MarkerInfo.png
Sun 4/29 15:21:01.153 Creating DirectX9 Texture from nio buffer w=146 h=146
Sun 4/29 15:21:01.154 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDPlay.png
Sun 4/29 15:21:01.155 Creating DirectX9 Texture from nio buffer w=30 h=26
Sun 4/29 15:21:02.671 VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Sun 4/29 15:21:02.671 VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Sun 4/29 15:21:02.671 CloseAndWait is executing the close job synchronously now
Sun 4/29 15:21:02.672 VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Sun 4/29 15:21:02.672 VF processing job null nPlayin=false
Sun 4/29 15:21:02.672 VF thread is now waiting for 0:00:00.000
Sun 4/29 15:21:02.676 VF.startPlaylist Playlist[Now Playing Stupid Girl by Garbage, ] startIdx=0
Sun 4/29 15:21:02.676 VF.submitJob(VFJob[StartPlaylist r=0.0 t=0 file=null ifn=null])
Sun 4/29 15:21:02.677 VF processing job VFJob[StartPlaylist r=0.0 t=0 file=null ifn=null] nPlayin=false
Sun 4/29 15:21:02.694 VF processing on UI Thread VFJob[StartPlaylist r=0.0 t=0 file=null ifn=null]
Sun 4/29 15:21:02.695 VF.playbackNextRandomPlaylist playlistChain=[Playlist[Now Playing Stupid Girl by Garbage, Friday I'm In Love by The Cure, ]] playindices=[-1] currFile=null
Sun 4/29 15:21:02.695 VideoFrame.watch(A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4])
Sun 4/29 15:21:02.695 watchThisFile=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]]
Sun 4/29 15:21:02.697 Watch airing is a file & over, do that instead dvd=false
Sun 4/29 15:21:02.699 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]] ifn=null])
Sun 4/29 15:21:02.700 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]] ifn=null] nPlayin=false
Sun 4/29 15:21:02.702 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]] ifn=null]
Sun 4/29 15:21:02.703 VideoFrame creating new media player for file:MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]]
Sun 4/29 15:21:02.704 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]] ifn=null] nPlayin=false
Sun 4/29 15:21:02.705 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]] ifn=null]
Sun 4/29 15:21:02.707 VF file=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]] targetTime = Sun 1/7 8:32:00.669
Sun 4/29 15:21:02.707 VideoFrame.timeSelected(Sun 1/7 8:32:00.669, true) currFile=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]]
Sun 4/29 15:21:02.717 DShowPlayer setAudioRendererFilter0(Default WaveOut Device) called
Sun 4/29 15:21:02.778 Using the default source filter
Sun 4/29 15:21:02.780 NATIVE Loading the source file
Sun 4/29 15:21:02.798 NATIVE RenderSource called
Sun 4/29 15:21:02.807 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=48]
Sun 4/29 15:21:02.817 DShowGraphFilters=AudRend,MPEG-1 Stream Splitter,\\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3,
Sun 4/29 15:21:02.821 WIN32 FAILURE HR=0x80004002 FILE=.\CPlayerData.cpp LINE=1521
Sun 4/29 15:21:02.821 WIN32 FAILURE HR=0x80004002 FILE=.\CPlayerData.cpp LINE=1540
Sun 4/29 15:21:02.822 VideoFrame.timeSelected2(Sun 1/7 8:32:00.669, true) currFile=MediaFile[id=2773667 A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] host=pvr encodedBy= format=MP3 0:04:21 192 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Friday I'm In Love, Year=1992, Duration=261240, ThumbnailOffset=173, Genre=Alt. Rock, Artist=The Cure, AlbumArtist=The Cure, Title=Scared As You, ThumbnailSize=58934, Track=03}] \\pvr\CD Library\The Cure - Friday I'm In Love\The Cure - Scared As You.mp3, Seg0[Sun 1/7 8:32:00.669-Sun 1/7 8:36:21.909]] realDur=258852
Sun 4/29 15:21:02.822 About to perform media time selection for 0
Sun 4/29 15:21:02.822 Running the playback graph.
Sun 4/29 15:21:02.822 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 4/29 15:21:02.847 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 4/29 15:21:02.848 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 4/29 15:21:02.857 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 4/29 15:21:02.868 DShowMediaPlayer is consuming the events...
Sun 4/29 15:21:02.868 Event: 0xd l1=0x0 l2=0x0
Sun 4/29 15:21:02.868 Event: 0xe l1=0x0 l2=0x0
Sun 4/29 15:21:02.892 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 4/29 15:21:02.893 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 4/29 15:21:02.893 DShowMediaPlayer is consuming the events...
Sun 4/29 15:21:02.893 VF processing job null nPlayin=true
Sun 4/29 15:21:02.893 isRec=false rd=258852 base=43
Sun 4/29 15:21:02.893 VF thread is now waiting for 0:04:18.559
Sun 4/29 15:21:03.278 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=48] numGlyphs=1674 numCachedGlyphs=237 numImages=30
Sun 4/29 15:21:03.278 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:21:03.279 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:21:03.279 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDRew.png
Sun 4/29 15:21:03.280 Creating DirectX9 Texture from nio buffer w=30 h=26
Sun 4/29 15:21:03.280 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDStop.png
Sun 4/29 15:21:03.281 Creating DirectX9 Texture from nio buffer w=30 h=26
Sun 4/29 15:21:03.281 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDFwd.png
Sun 4/29 15:21:03.281 Creating DirectX9 Texture from nio buffer w=30 h=26
Sun 4/29 15:21:03.281 Loading 16x13 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolOn.png
Sun 4/29 15:21:03.282 Creating DirectX9 Texture from nio buffer w=16 h=13
Sun 4/29 15:21:03.282 ImageUtils loading file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolBar.png
Sun 4/29 15:21:03.295 ImageUtils creating BI copy sun.awt.image.ToolkitImage@aa780b w=64 h=4 freeMem=25753288 totalMem=108527616
Sun 4/29 15:21:03.296 Premultiplying alpha for BuffImage...
Sun 4/29 15:21:03.297 Creating DirectX9 Texture from memory w=64 h=4
Sun 4/29 15:21:03.297 Loading 9x15 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDVolSlider.png
Sun 4/29 15:21:03.298 Creating DirectX9 Texture from nio buffer w=9 h=15
Sun 4/29 15:21:03.298 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicTrackPrev.png
Sun 4/29 15:21:03.349 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:21:03.350 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicTrackNext.png
Sun 4/29 15:21:03.353 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:21:03.353 Loading 121x121 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\MusicVisuals.png
Sun 4/29 15:21:03.371 Creating DirectX9 Texture from nio buffer w=121 h=121
Sun 4/29 15:21:03.401 Loading 30x26 image from file C:\Program Files\SageTV\SageTV\STVs\SageTV3\OSDPause.png
Sun 4/29 15:21:03.448 Creating DirectX9 Texture from nio buffer w=30 h=26
Sun 4/29 15:21:04.817 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:21:16.274 VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Sun 4/29 15:21:16.274 VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 4/29 15:21:16.274 CloseAndWait is executing the close job synchronously now
Sun 4/29 15:21:16.274 VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Sun 4/29 15:21:16.274 VF logFileWatch doneAir=A[2773669,2773668,"Friday I'm In Love",0@0107.08:32,4] theTime=Sun 1/7 8:32:14.093 recTime=Sun 1/7 8:32:00.669
Sun 4/29 15:21:16.275 Stopping the playback graph.
Sun 4/29 15:21:16.281 Tearing down playback graph
Sun 4/29 15:21:16.282 NATIVE Deallocating the CPlayerData object
Sun 4/29 15:21:16.392 NATIVE Finished destroying the playback graph
Sun 4/29 15:21:16.392 VF processing job null nPlayin=false
Sun 4/29 15:21:16.392 VF thread is now waiting for 0:00:00.000
Sun 4/29 15:21:16.442 Loading new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37]
Sun 4/29 15:21:16.513 Rendered new font to cache font=java.awt.Font[family=Arial,name=Arial,style=bold,size=37] numGlyphs=1674 numCachedGlyphs=234 numImages=23
Sun 4/29 15:21:16.514 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:21:16.515 Creating DirectX9 Texture from memory w=128 h=128
Sun 4/29 15:21:16.553 VF processing job null nPlayin=false
Sun 4/29 15:21:16.553 VF thread is now waiting for 0:00:00.000
Sun 4/29 15:21:17.204 VF processing job null nPlayin=false
Sun 4/29 15:21:17.204 VF thread is now waiting for 0:00:00.000
Sun 4/29 15:21:46.431 Enabled SS
Sun 4/29 15:23:02.223 Setting the system clock to be Sun 4/29 15:23:02.898