Sun 3/5 11:34:42.175 user.dir2=C:\Program Files\Frey Technologies\SageTV
Sun 3/5 11:34:42.179 classpath=C:\Program Files\Frey Technologies\SageTV\Sage.jar;C:\Program Files\Frey Technologies\SageTV\xerces.jar;C:\Program Files\Frey Technologies\SageTV\plugin.jar;C:\Program Files\Frey Technologies\SageTV\;;JARs\jcifs-1.1.6.jar;JARs\jdic.jar;JARs\nielm_sageutls.jar;JARs\nielm_sagewebserver.jar;JARs\servlet.jar
Sun 3/5 11:34:42.180 JVM version=1.5.0_06
Sun 3/5 11:34:42.180 OS=Windows XP 5.1
Sun 3/5 11:34:42.195 Succesfully setup system shell hook
Sun 3/5 11:34:42.377 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/CloseWindow.gif
Sun 3/5 11:34:42.484 ImageUtils creating BI copy sun.awt.image.ToolkitImage@10d448
Sun 3/5 11:34:42.606 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/CloseWindowPressed.gif
Sun 3/5 11:34:42.607 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1bf216a
Sun 3/5 11:34:42.608 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/MinWindow.gif
Sun 3/5 11:34:42.610 ImageUtils creating BI copy sun.awt.image.ToolkitImage@c20e24
Sun 3/5 11:34:42.611 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/MinWindowPressed.gif
Sun 3/5 11:34:42.613 ImageUtils creating BI copy sun.awt.image.ToolkitImage@863399
Sun 3/5 11:34:42.867 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/splash.gif
Sun 3/5 11:34:42.880 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1cd8669
Sun 3/5 11:34:43.492 hostname=a2000
Sun 3/5 11:34:43.595 SageTV V4.1.3.67
Sun 3/5 11:34:43.596 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/tvicon.gif
Sun 3/5 11:34:43.599 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1100d7a
Sun 3/5 11:34:43.708 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/tvicon_anim0.gif
Sun 3/5 11:34:43.710 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1bf6770
Sun 3/5 11:34:43.712 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/tvicon_anim1.gif
Sun 3/5 11:34:43.716 ImageUtils creating BI copy sun.awt.image.ToolkitImage@a401c2
Sun 3/5 11:34:43.718 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/tvicon_anim2.gif
Sun 3/5 11:34:43.719 ImageUtils creating BI copy sun.awt.image.ToolkitImage@c51355
Sun 3/5 11:34:43.721 ImageUtils loading URL jar:file:/C:/Program%20Files/Frey%20Technologies/SageTV/Sage.jar!/images/tvicon_anim3.gif
Sun 3/5 11:34:43.723 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1457cb
Sun 3/5 11:34:43.862 Wizard starting to load database info...
Sun 3/5 11:34:43.864 Wizard DONE loading database info. loadTime=0.0010 sec
Sun 3/5 11:34:44.959 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:34:44.963 NetworkClient fullCleanup null
Sun 3/5 11:34:48.459 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:34:48.464 NetworkClient fullCleanup null
Sun 3/5 11:34:51.959 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:34:51.963 NetworkClient fullCleanup null
Sun 3/5 11:34:55.459 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:34:55.463 NetworkClient fullCleanup null
Sun 3/5 11:34:58.959 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:34:58.963 NetworkClient fullCleanup null
Sun 3/5 11:35:02.460 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:02.463 NetworkClient fullCleanup null
Sun 3/5 11:35:05.960 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:05.963 NetworkClient fullCleanup null
Sun 3/5 11:35:09.460 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:09.463 NetworkClient fullCleanup null
Sun 3/5 11:35:12.961 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:12.969 NetworkClient fullCleanup null
Sun 3/5 11:35:16.460 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:16.464 NetworkClient fullCleanup null
Sun 3/5 11:35:19.961 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:19.964 NetworkClient fullCleanup null
Sun 3/5 11:35:23.461 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:23.464 NetworkClient fullCleanup null
Sun 3/5 11:35:26.962 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:26.968 NetworkClient fullCleanup null
Sun 3/5 11:35:30.461 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:30.464 NetworkClient fullCleanup null
Sun 3/5 11:35:33.961 Error establishing server connection of:java.net.ConnectException: Connection refused: connect
Sun 3/5 11:35:33.965 NetworkClient fullCleanup null
Sun 3/5 11:35:36.511 SageTV received connection from:Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:35:38.248 Received command of:PROPERTY_SYNC 105 from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:35:38.383 Received command of:WIZARD_SYNC from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:35:38.385 Wizard allocating table for Year of size 123
Sun 3/5 11:35:38.386 Wizard loading main index for Year
Sun 3/5 11:35:38.388 Wizard allocating table for Network of size 34
Sun 3/5 11:35:38.388 Wizard loading main index for Network
Sun 3/5 11:35:38.389 Wizard allocating table for Title of size 10202
Sun 3/5 11:35:38.390 Wizard loading main index for Title
Sun 3/5 11:35:38.906 Wizard building alt. index 0 for Title
Sun 3/5 11:35:38.931 Wizard allocating table for Channel of size 467
Sun 3/5 11:35:38.932 Wizard loading main index for Channel
Sun 3/5 11:35:38.961 Wizard allocating table for People of size 20896
Sun 3/5 11:35:38.962 Wizard loading main index for People
Sun 3/5 11:35:39.446 Wizard building alt. index 0 for People
Sun 3/5 11:35:39.492 Wizard allocating table for SubCategory of size 130
Sun 3/5 11:35:39.493 Wizard loading main index for SubCategory
Sun 3/5 11:35:39.495 Wizard allocating table for Rated of size 15
Sun 3/5 11:35:39.495 Wizard loading main index for Rated
Sun 3/5 11:35:39.496 Wizard allocating table for ParentalRating of size 0
Sun 3/5 11:35:39.496 Wizard allocating table for ExtendedRating of size 10
Sun 3/5 11:35:39.496 Wizard loading main index for ExtendedRating
Sun 3/5 11:35:39.497 Wizard allocating table for Category of size 227
Sun 3/5 11:35:39.497 Wizard loading main index for Category
Sun 3/5 11:35:39.536 Wizard allocating table for PrimeTitle of size 0
Sun 3/5 11:35:39.536 Wizard allocating table for Bonus of size 273
Sun 3/5 11:35:39.537 Wizard loading main index for Bonus
Sun 3/5 11:35:39.540 Wizard allocating table for Show of size 37103
Sun 3/5 11:35:39.558 Wizard loading main index for Show
Sun 3/5 11:35:46.176 Wizard building alt. index 0 for Show
Sun 3/5 11:35:46.293 Wizard allocating table for Airing of size 92829
Sun 3/5 11:35:46.306 Wizard loading main index for Airing
Sun 3/5 11:35:48.024 Sage waiting for GC to free up some memory usage%=0.9601648
Sun 3/5 11:35:48.416 Sage done waiting for GC to free up some memory usage%=0.5603206
Sun 3/5 11:35:49.909 Wizard building alt. index 0 for Airing
Sun 3/5 11:35:50.015 Wizard building alt. index 1 for Airing
Sun 3/5 11:35:50.123 Wizard allocating table for ManualRecord of size 0
Sun 3/5 11:35:50.123 Wizard allocating table for MediaFile of size 16125
Sun 3/5 11:35:50.124 Wizard loading main index for MediaFile
Sun 3/5 11:35:54.649 Sage waiting for GC to free up some memory usage%=0.9917063
Sun 3/5 11:35:55.224 Sage done waiting for GC to free up some memory usage%=0.5592672
Sun 3/5 11:35:56.672 Wizard building alt. index 0 for MediaFile
Sun 3/5 11:35:56.683 Wizard allocating table for Watched of size 4440
Sun 3/5 11:35:56.684 Wizard loading main index for Watched
Sun 3/5 11:35:56.925 Wizard building alt. index 0 for Watched
Sun 3/5 11:35:56.930 Wizard allocating table for Agent of size 2650
Sun 3/5 11:35:56.931 Wizard loading main index for Agent
Sun 3/5 11:35:57.255 Wizard building alt. index 0 for Agent
Sun 3/5 11:35:57.263 Wizard allocating table for Wasted of size 8388
Sun 3/5 11:35:57.263 Wizard loading main index for Wasted
Sun 3/5 11:35:57.345 Wizard allocating table for Playlist of size 157
Sun 3/5 11:35:57.346 Wizard loading main index for Playlist
Sun 3/5 11:35:57.458 Received command of:CARNY_SYNC_CAUSEMAP 1796 from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:35:57.494 Received command of:CARNY_SYNC_WPMAP 1589 from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:35:57.514 Received command of:CARNY_SYNC_MUSTSEE 275 from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:35:57.527 Received command of:CARNY_SYNC_LOVE 4580 from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:35:57.563 SageTV received connection from:Socket[addr=/127.0.0.1,port=42024,localport=1059]
Sun 3/5 11:35:58.776 MMC calling detectCaptureDevices on sage.n@165cbd2
Sun 3/5 11:35:58.796 devices detected=[Hauppauge WinTV PVR PCI II Capture]
Sun 3/5 11:35:58.796 EncoderMap={Hauppauge WinTV PVR PCI II Capture=Hauppauge WinTV PVR PCI II Capture}
Sun 3/5 11:35:59.947 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, Audio Source, AVI Decompressor, AVI Draw, AVI Mux, AVI Splitter, AVI Writer, AVI/WAV File Source, Bitmap Generate, Color Converter, Color Space Converter, CyberLink Audio Decoder, CyberLink Audio Effect (PDVD6), CyberLink Audio Spectrum Analyzer (PDVD6), CyberLink AudioCD Filter (PDVD6), CyberLink Demux (PDVD6), CyberLink DVD Navigator (PDVD6), CyberLink Line21 Decoder (PDVD6), Cyberlink SubTitle Importor (PDVD6), CyberLink TimeStretch Filter (PDVD6), CyberLink Video/SP Decoder, Deinterlace Filter, Dib Output, Dib Receive, Disk Record Queue, DivX Decoder Filter, DivX Decoder Filter, DivX Demux, DivX Mux, DivX Subtitle Decoder, DivX Subtitle Encoder, DivX Subtitle Source, DScaler Audio Decoder, DScaler Mpeg2 Video Decoder, DScaler Renderer, DV Muxer, DV Scenes, DV Source Filter, DV Splitter, DV V/A ACM Source Filter, DV V/A Source Filter, DV Video Decoder, DV Video Source Filter, DVD Navigator, ffdshow Audio Decoder, ffdshow Audio Processor, ffdshow MPEG-4 Video Decoder, ffdshow raw video filter, ffdshow VFW decoder helper, 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 2, Hauppauge WinTV File Reader, Hauppauge WinTV File Writer, Hauppauge WinTV MPEG Splitter, Hauppauge WinTV SnapShot, Indeo® audio software, Indeo® video 4.4 Compression Filter, Indeo® video 4.4 Decompression Filter, Indeo® video 5.10 Compression Filter, Indeo® video 5.10 Decompression Filter, Infinite Pin Tee Filter, Internal Script Command Renderer, Intervideo 3gFileSource, Intervideo 3gFileWrite, InterVideo AAC (XForm) Decoder, Intervideo AAC Encoder, InterVideo AAC Parser, Intervideo AMR Decoder, Intervideo AMR Encoder, InterVideo Audio Decoder, InterVideo Audio Encoder, InterVideo Audio Padding, InterVideo Audio Processor Fx, InterVideo Demultiplexer, Intervideo Disc Read2 Filter, InterVideo Disc Write2 Filter, InterVideo File Writer, InterVideo G726Dec DMO, Intervideo H.264 Decoder, InterVideo MPEG4 Video Decoder, InterVideo MPEG4 Video Encoder, InterVideo Multiplexer, InterVideo Navigator, InterVideo NonCSS Audio Decoder for Hauppauge, InterVideo NonCSS Video Decoder for Hauppauge, InterVideo Null-Audio Source, InterVideo PSIP/SI Filter, Intervideo QT source, Intervideo QT Writer, Intervideo ScaleX, InterVideo Subtitle, Intervideo Trim Pack, InterVideo Video Decoder, InterVideo Video Encoder, InterVideo WaveDump, IVF source filter, Line 21 Decoder, Line 21 Decoder 2, Lyric Parser, Microsoft MPEG-4 Video Decompressor, 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 Sections and Tables, MPEG-2 Splitter, MPEG-2 Video Stream Analyzer, MPEG-I Stream Splitter, MPEG2 TS Source, MPEG2Dump, Mpeg4 Decoder DMO, Mpeg43 Decoder DMO, Mpeg4s Decoder DMO, Multi-file Parser, Nero Audio CD Filter, Nero Audio CD Navigator, Nero Audio Processor, Nero Audio Sample Renderer, Nero Audio Stream Renderer, Nero Digital Audio Decoder, Nero Digital AVC Audio Encoder, Nero Digital AVC File Writer, Nero Digital AVC Muxer, Nero Digital AVC Null Renderer, Nero Digital AVC Subpicture Enc, Nero Digital AVC Video Enc, Nero Digital Parser, Nero DV Splitter, Nero DVD Decoder, Nero DVD Navigator, Nero ES Video Reader, Nero File Source, Nero File Source (Async.), Nero File Source / Splitter, Nero Format Converter, Nero Frame Capture, Nero Mpeg2 Encoder, Nero Photo Source, Nero PS Muxer, Nero QuickTime(tm) Audio Decoder, Nero QuickTime(tm) Video Decoder, Nero Resize, Nero Scene Detector, Nero Scene Detector 2, Nero Splitter, Nero Vcd Navigator, Nero Video Analyzer, Nero Video Decoder, Nero Video Processor, Nero Video Sample Renderer, Nero Video Stream Renderer, 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, ReClock Audio Renderer, 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.), ShotBoundaryDet, Smart Tee, StreamBufferSink, StreamBufferSource, Stretch Video, Ulead AC3 Audio Decoder 2, ULead File Source (Async.), ULead Infinite Pin Tee, Ulead MPEG Audio Decoder, Ulead MPEG Encoder, Ulead MPEG Muxer, Ulead MPEG Splitter, Ulead MPEG Video Decoder, Ulead Video Deinterlace Filter, Uncompressed Domain Shot Detection Filter, VBI Surface Allocator, VGA 16 Color Ditherer, Video Mixing Renderer 9, Video Port Manager, Video Renderer, Video Renderer, Video Source, Wave Parser, WIA Stream Snapshot Filter, Windows Media Audio Decoder, Windows Media Multiplexer, Windows Media Pad VU Data Grabber, Windows Media source filter, Windows Media Update Filter, Windows Media Video Decoder, Windows Media Video Decoder, WM ASF Reader, WM ASF Writer, WMAudio Decoder DMO, WMSpeech Decoder DMO, WMT AudioAnalyzer, WMT Black Frame Generator, WMT DirectX Transform Wrapper, WMT DV Extract, WMT Format Conversion, WMT Import Filter, WMT Interlacer, WMT Log Filter, WMT MuxDeMux Filter, WMT Sample Information Filter, WMT Screen Capture filter, WMT Switch Filter, WMT VIH2 Fix, WMT Virtual Renderer, WMT Virtual Source, WMT Volume, WMV Screen decoder DMO, WMVideo Decoder DMO, WST Decoder, XML Playlist]
Sun 3/5 11:36:01.120 MsgSend thread spawned for /127.0.0.1:42024
Sun 3/5 11:36:01.121 MsgRecv thread has spawned for /127.0.0.1:42024
Sun 3/5 11:36:01.122 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:01.127 VF thread is now running...
Sun 3/5 11:36:01.129 Loading startup runnable:net.sf.sageplugins.webserver.StartServer
Sun 3/5 11:36:01.131 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:01.134 Loaded startup runnable:net.sf.sageplugins.webserver.StartServer
Sun 3/5 11:36:01.137 SetFullScreen(true)
Sun 3/5 11:36:01.138 FullScreen set to true
Sun 3/5 11:36:01.148 sage.as@1104da7 needs an update in 21:08:14
Sun 3/5 11:36:01.149 EPG needs an update in 1268 minutes
Sun 3/5 11:36:01.149 EPG's works is done. Waiting...
Sun 3/5 11:36:01.661 Starting webserver with args: [-p, 8080, -root, webserver, -t, throttles.properties, -s, servlets.properties, -r, realms.properties, -a, aliases.properties, -nohup]
Sun 3/5 11:36:01.683 StorageDeviceDetector started...
Sun 3/5 11:36:01.869 AWTThread Hang Detected - hang time = 753
Sun 3/5 11:36:01.916 Acme httpd a2000:8080 listening.
Sun 3/5 11:36:02.618 AWTThread Hang Detected - hang time = 1502
Sun 3/5 11:36:03.368 AWTThread Hang Detected - hang time = 2252
Sun 3/5 11:36:03.480 locale = en
Sun 3/5 11:36:04.002 DatePat=EEE, MMM d orgPat=EEEE, MMMM d, yyyy
Sun 3/5 11:36:04.059 setUI(sage.bu@1aea727[Main Menu]) histIdx=0 uiHistory=[sage.bu@1aea727[Main Menu]]
Sun 3/5 11:36:04.118 AWTThread Hang Detected - hang time = 3002
Sun 3/5 11:36:05.096 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\Background.jpg
Sun 3/5 11:36:05.186 ImageUtils creating BI copy sun.awt.image.ToolkitImage@176d662
Sun 3/5 11:36:05.194 ImageUtils creating scaled masked copy width=800 height=600 mask=null BufferedImage@c2060e: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 600 height = 450 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:05.289 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\SageLogo256.png
Sun 3/5 11:36:05.319 ImageUtils creating BI copy sun.awt.image.ToolkitImage@404eb2
Sun 3/5 11:36:05.320 ImageUtils creating scaled masked copy width=135 height=41 mask=null BufferedImage@10a4a32: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 256 height = 78 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:05.322 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\ConflictResolved.png
Sun 3/5 11:36:05.340 ImageUtils creating BI copy sun.awt.image.ToolkitImage@3d4817
Sun 3/5 11:36:05.341 ImageUtils creating scaled masked copy width=30 height=27 mask=null BufferedImage@194f467: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 61 height = 55 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:05.343 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\MenuBarLong.png
Sun 3/5 11:36:05.349 ImageUtils creating BI copy sun.awt.image.ToolkitImage@ec12f3
Sun 3/5 11:36:05.351 ImageUtils creating scaled masked copy width=736 height=56 mask=null BufferedImage@1c7510d: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 586 height = 47 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:05.360 Allocated primary buffer: BufferedImage@1c7bee2: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 800 height = 600 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:06.478 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:06.521 VF processing job null nPlayin=false
Sun 3/5 11:36:06.522 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:06.593 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\ConflictUnresolved.png
Sun 3/5 11:36:06.596 ImageUtils creating BI copy sun.awt.image.ToolkitImage@56b64c
Sun 3/5 11:36:06.597 ImageUtils creating scaled masked copy width=30 height=27 mask=null BufferedImage@1e73498: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 61 height = 55 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:07.970 setUI(sage.bu@1e67280[Setup Menu]) histIdx=1 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu]]
Sun 3/5 11:36:08.512 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:09.886 setUI(sage.bu@7541f8[Detailed Setup]) histIdx=2 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup]]
Sun 3/5 11:36:10.437 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:10.573 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\MenuBarShort.png
Sun 3/5 11:36:10.576 ImageUtils creating BI copy sun.awt.image.ToolkitImage@153d79c
Sun 3/5 11:36:10.577 ImageUtils creating scaled masked copy width=213 height=46 mask=null BufferedImage@112ee4f: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 161 height = 44 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:11.190 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\down.png
Sun 3/5 11:36:11.198 ImageUtils creating BI copy sun.awt.image.ToolkitImage@8edd79
Sun 3/5 11:36:11.199 ImageUtils creating scaled masked copy width=37 height=35 mask=null BufferedImage@1f46b16: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 26 height = 25 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:11.478 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:11.671 WARNING - MissingResource: "Envy24 Family Audio (WDM)"
Sun 3/5 11:36:14.117 processOptionsMenu optionsMenu=default:null|OptionsMenu:MPEG2VideoDecoderFilterOptions context=Context[parent=Context[parent=Context[parent=Context[parent=Context[parent=Context[parent=Context[parent=Context[parent=null map={}] map={OriginalVideoDirectory=null, NumCommandRows=8, DiskspaceRule=Use All, VideoDirectory=null, DiskspaceSize=2.0E10}] map={LastCommand=}] map=null] map=null] map=null] map=null] map=null]
Sun 3/5 11:36:14.154 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:14.278 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:14.760 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:15.853 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:16.076 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:16.447 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:16.479 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:17.635 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:17.739 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:17.876 ZRoot is aborting this draw because its unnecessary
Sun 3/5 11:36:18.298 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:21.341 setUI(sage.bu@1e67280[Setup Menu]) histIdx=3 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup], sage.bu@1e67280[Setup Menu]]
Sun 3/5 11:36:21.479 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:21.805 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:24.215 setUI(sage.bu@e07e6b[Main Menu]) histIdx=4 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup], sage.bu@1e67280[Setup Menu], sage.bu@e07e6b[Main Menu]]
Sun 3/5 11:36:24.689 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:25.765 setUI(sage.bu@12d34a6[Library]) histIdx=5 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup], sage.bu@1e67280[Setup Menu], sage.bu@e07e6b[Main Menu], sage.bu@12d34a6[Library]]
Sun 3/5 11:36:26.258 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:26.479 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:27.011 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:27.013 VideoFrame.watch(FA[BAD AIRING])
Sun 3/5 11:36:27.014 watchThisFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:36:27.017 Watch airing is a file & over, do that instead dvd=true
Sun 3/5 11:36:27.019 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null])
Sun 3/5 11:36:27.034 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null] nPlayin=false
Sun 3/5 11:36:27.037 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null]
Sun 3/5 11:36:27.039 VideoFrame creating new media player for file:MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:36:27.044 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null] nPlayin=false
Sun 3/5 11:36:27.046 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null]
Sun 3/5 11:36:27.047 VF file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] targetTime = Wed 12/31 20:30:00.000
Sun 3/5 11:36:27.048 VideoFrame.timeSelected(Wed 12/31 20:30:00.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:36:27.053 DShowPlayer setVideoRendererFilter0({CD8743A1-3736-11D0-9E69-00C04FD7C15B}) called
Sun 3/5 11:36:27.582 setUI(sage.bu@57df5[MediaPlayer OSD]) histIdx=6 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup], sage.bu@1e67280[Setup Menu], sage.bu@e07e6b[Main Menu], sage.bu@12d34a6[Library], sage.bu@57df5[MediaPlayer OSD]]
Sun 3/5 11:36:27.616 DShowPlayer setAudioRendererFilter0(Default WaveOut Device) called
Sun 3/5 11:36:27.739 DShowPlayer setAudioDecoderFilter0(InterVideo Audio Decoder) called
Sun 3/5 11:36:27.884 NATIVE RenderAudio called
Sun 3/5 11:36:27.885 NATIVE RenderAudio step 1
Sun 3/5 11:36:27.885 NATIVE RenderAudio step 2
Sun 3/5 11:36:27.885 NATIVE RenderAudio step 3
Sun 3/5 11:36:27.895 NATIVE RenderVideo called
Sun 3/5 11:36:27.896 NATIVE RenderVideo step 1
Sun 3/5 11:36:27.896 NATIVE RenderVideo step 2
Sun 3/5 11:36:27.896 Video source pin information:
Sun 3/5 11:36:27.896 PIN is not connected yet!Sun 3/5 11:36:27.906 M type MEDIATYPE_DVD_ENCRYPTED_PACK  S type MEDIASUBTYPE_MPEG2_VIDEO F type FORMAT_MPEG2_VIDEO
Sun 3/5 11:36:27.906 NATIVE RenderVideo step 3
Sun 3/5 11:36:28.526 AWTThread Hang Detected - hang time = 750
Sun 3/5 11:36:28.936 Filter Graph Video Renderer Info:
Sun 3/5 11:36:28.936 Video Renderer:CLSID_OverlayMixer
Sun 3/5 11:36:28.936 M type MEDIATYPE_Video  S type Unknown GUID Name F type FORMAT_VideoInfo2
Sun 3/5 11:36:28.936 Interlaced video
Sun 3/5 11:36:28.936 Both fields per sample
Sun 3/5 11:36:28.937 Field 2 first
Sun 3/5 11:36:28.937 Stream never contains a Field 2
Sun 3/5 11:36:28.937 Either bob or weave mode.
Sun 3/5 11:36:28.963 DShowGraphFilters=Video Renderer,AudRend,VidRend,Audio Decoder,Line21 Decoder,NVIDIA Video Decoder,DVD Navigator,
Sun 3/5 11:36:28.964 DShowPlayer setVideoHWND(66118)
Sun 3/5 11:36:28.967 Got the default color key 0x100010
Sun 3/5 11:36:28.968 Got the default overlay color key of 0x100010
Sun 3/5 11:36:28.969 Setting the color key to 0x100010
Sun 3/5 11:36:28.969 Returning the color key as 0xff100010
Sun 3/5 11:36:28.970 Got Native Video Dimensions java.awt.Dimension[width=768,height=576]
Sun 3/5 11:36:28.971 VideoFrame.timeSelected2(Wed 12/31 20:30:00.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:36:28.971 Running the playback graph.
Sun 3/5 11:36:28.972 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:28.977 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:28.978 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:28.978 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:29.276 AWTThread Hang Detected - hang time = 1500
Sun 3/5 11:36:29.303 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:29.304 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:29.304 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:29.304 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:29.327 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.222 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.223 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.224 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.225 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.237 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.238 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.239 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.276 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.335 Event: 0xd
Sun 3/5 11:36:30.336 Event: 0x104
Sun 3/5 11:36:30.336 Event: 0x106
Sun 3/5 11:36:30.336 Event: 0x102
Sun 3/5 11:36:30.336 Event: 0x107
Sun 3/5 11:36:30.336 Event: 0x108
Sun 3/5 11:36:30.337 Event: 0x101
Sun 3/5 11:36:30.337 Event: 0xe
Sun 3/5 11:36:30.337 Event: 0x101
Sun 3/5 11:36:30.337 Event: 0x103
Sun 3/5 11:36:30.337 Event: 0x105
Sun 3/5 11:36:30.337 Event: 0x104
Sun 3/5 11:36:30.338 Event: 0x108
Sun 3/5 11:36:30.338 Event: 0x10b
Sun 3/5 11:36:30.338 Event: 0x10b
Sun 3/5 11:36:30.339 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.355 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.356 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.357 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.358 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.359 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.359 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.360 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.361 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.361 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.362 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.362 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.363 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.364 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.370 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.371 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.377 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.377 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.378 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.379 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.380 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.380 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.381 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.381 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.383 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.383 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.384 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.386 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.387 VF processing job null nPlayin=true
Sun 3/5 11:36:30.387 isRec=false rd=7200000 base=0
Sun 3/5 11:36:30.388 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:30.440 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\OSDChDisplay.png
Sun 3/5 11:36:30.441 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.442 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.442 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.443 Event: 0x10b
Sun 3/5 11:36:30.444 VF processing job null nPlayin=true
Sun 3/5 11:36:30.445 isRec=false rd=7200000 base=0
Sun 3/5 11:36:30.445 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:30.447 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1f12373
Sun 3/5 11:36:30.448 ImageUtils creating scaled masked copy width=53 height=31 mask=null BufferedImage@119c297: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 35 height = 26 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:30.449 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\OSDVolOn.png
Sun 3/5 11:36:30.451 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1493995
Sun 3/5 11:36:30.452 ImageUtils creating scaled masked copy width=22 height=18 mask=null BufferedImage@b07af1: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 16 height = 13 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:30.453 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\OSDVolBar.png
Sun 3/5 11:36:30.455 ImageUtils creating BI copy sun.awt.image.ToolkitImage@1883d5f
Sun 3/5 11:36:30.455 ImageUtils creating scaled masked copy width=96 height=4 mask=null BufferedImage@1630eb6: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 64 height = 4 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:30.456 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\OSDVolSlider.png
Sun 3/5 11:36:30.459 ImageUtils creating BI copy sun.awt.image.ToolkitImage@133b16a
Sun 3/5 11:36:30.460 ImageUtils creating scaled masked copy width=8 height=14 mask=null BufferedImage@5e25f3: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 9 height = 15 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:36:30.515 ImageUtils loading file C:\Program Files\Frey Technologies\SageTV\STVs\SageTV3\OSDStatusPlay.png
Sun 3/5 11:36:30.517 ImageUtils creating BI copy sun.awt.image.ToolkitImage@2df8f8
Sun 3/5 11:36:30.537 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.538 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.538 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.539 Event: 0x10b
Sun 3/5 11:36:30.540 VF processing job null nPlayin=true
Sun 3/5 11:36:30.541 isRec=false rd=7200000 base=0
Sun 3/5 11:36:30.541 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:30.742 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:30.742 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:30.743 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:30.744 Event: 0x10b
Sun 3/5 11:36:30.745 VF processing job null nPlayin=true
Sun 3/5 11:36:30.746 isRec=false rd=7200000 base=0
Sun 3/5 11:36:30.746 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:31.172 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:31.173 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:31.174 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:31.175 Event: 0x10b
Sun 3/5 11:36:31.176 VF processing job null nPlayin=true
Sun 3/5 11:36:31.176 isRec=false rd=7200000 base=0
Sun 3/5 11:36:31.176 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:31.464 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:31.677 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:31.678 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:31.678 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:31.679 Event: 0x10b
Sun 3/5 11:36:31.679 VF processing job null nPlayin=true
Sun 3/5 11:36:31.680 isRec=false rd=7200000 base=0
Sun 3/5 11:36:31.680 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:32.477 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:32.477 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:32.478 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:32.479 Event: 0x10b
Sun 3/5 11:36:32.480 VF processing job null nPlayin=true
Sun 3/5 11:36:32.481 isRec=false rd=7200000 base=0
Sun 3/5 11:36:32.481 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:32.978 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:32.978 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:32.979 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:32.979 Event: 0x10b
Sun 3/5 11:36:32.980 VF processing job null nPlayin=true
Sun 3/5 11:36:32.981 isRec=false rd=7200000 base=0
Sun 3/5 11:36:32.981 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:34.648 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:34.648 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:34.651 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:34.652 Event: 0x10b
Sun 3/5 11:36:34.653 VF processing job null nPlayin=true
Sun 3/5 11:36:34.654 isRec=false rd=7200000 base=0
Sun 3/5 11:36:34.654 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:34.991 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:34.991 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:34.994 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:34.995 Event: 0x10b
Sun 3/5 11:36:34.996 VF processing job null nPlayin=true
Sun 3/5 11:36:34.996 isRec=false rd=7200000 base=0
Sun 3/5 11:36:34.997 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:35.590 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:35.590 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:35.593 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:35.594 Event: 0x10b
Sun 3/5 11:36:35.595 VF processing job null nPlayin=true
Sun 3/5 11:36:35.599 isRec=false rd=7200000 base=0
Sun 3/5 11:36:35.601 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:35.990 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:35.995 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:35.996 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:35.997 Event: 0x10b
Sun 3/5 11:36:35.997 VF processing job null nPlayin=true
Sun 3/5 11:36:35.998 isRec=false rd=7200000 base=0
Sun 3/5 11:36:35.999 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:36.328 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:36.328 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:36.329 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:36.329 Event: 0x10b
Sun 3/5 11:36:36.331 VF processing job null nPlayin=true
Sun 3/5 11:36:36.332 isRec=false rd=7200000 base=0
Sun 3/5 11:36:36.332 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:36.465 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:36.891 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:36.892 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:36.899 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:36.900 Event: 0x10b
Sun 3/5 11:36:36.901 VF processing job null nPlayin=true
Sun 3/5 11:36:36.901 isRec=false rd=7200000 base=0
Sun 3/5 11:36:36.902 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:37.424 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:37.424 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:37.425 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:37.425 Event: 0x10b
Sun 3/5 11:36:37.426 VF processing job null nPlayin=true
Sun 3/5 11:36:37.427 isRec=false rd=7200000 base=0
Sun 3/5 11:36:37.427 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:37.830 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:37.831 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:37.831 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:37.832 Event: 0x10b
Sun 3/5 11:36:37.833 VF processing job null nPlayin=true
Sun 3/5 11:36:37.833 isRec=false rd=7200000 base=0
Sun 3/5 11:36:37.833 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:37.894 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:37.895 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:37.895 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:37.919 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:37.943 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:38.199 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:38.210 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:38.210 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:38.211 Event: 0x103
Sun 3/5 11:36:38.212 Event: 0x10b
Sun 3/5 11:36:38.213 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:38.215 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:38.216 VF processing job null nPlayin=true
Sun 3/5 11:36:38.216 isRec=false rd=7200000 base=0
Sun 3/5 11:36:38.216 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:38.559 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:38.560 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:38.560 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:38.561 Event: 0x10b
Sun 3/5 11:36:38.562 VF processing job null nPlayin=true
Sun 3/5 11:36:38.562 isRec=false rd=7200000 base=0
Sun 3/5 11:36:38.562 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:39.153 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:39.154 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:39.157 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:39.158 Event: 0x10b
Sun 3/5 11:36:39.159 VF processing job null nPlayin=true
Sun 3/5 11:36:39.159 isRec=false rd=7200000 base=0
Sun 3/5 11:36:39.160 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:39.635 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:39.635 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:39.636 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:39.652 Event: 0x10b
Sun 3/5 11:36:39.653 VF processing job null nPlayin=true
Sun 3/5 11:36:39.654 isRec=false rd=7200000 base=0
Sun 3/5 11:36:39.654 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:39.936 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:39.937 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:39.938 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:39.938 Event: 0x10b
Sun 3/5 11:36:39.939 VF processing job null nPlayin=true
Sun 3/5 11:36:39.939 isRec=false rd=7200000 base=0
Sun 3/5 11:36:39.940 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:40.469 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:40.470 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:40.471 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:40.472 Event: 0x10b
Sun 3/5 11:36:40.473 VF processing job null nPlayin=true
Sun 3/5 11:36:40.474 isRec=false rd=7200000 base=0
Sun 3/5 11:36:40.475 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:40.938 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:40.938 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:40.939 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:40.939 Event: 0x10b
Sun 3/5 11:36:40.940 VF processing job null nPlayin=true
Sun 3/5 11:36:40.941 isRec=false rd=7200000 base=0
Sun 3/5 11:36:40.941 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:41.317 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:41.318 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:41.318 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:41.319 Event: 0x10b
Sun 3/5 11:36:41.320 VF processing job null nPlayin=true
Sun 3/5 11:36:41.320 isRec=false rd=7200000 base=0
Sun 3/5 11:36:41.320 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:41.467 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:41.970 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:41.970 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:41.972 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:41.973 Event: 0x10b
Sun 3/5 11:36:41.974 VF processing job null nPlayin=true
Sun 3/5 11:36:41.974 isRec=false rd=7200000 base=0
Sun 3/5 11:36:41.974 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:42.334 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:42.339 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:42.339 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:42.341 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:42.389 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:42.810 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:42.828 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:42.828 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:42.829 Event: 0x103
Sun 3/5 11:36:42.829 Event: 0x10b
Sun 3/5 11:36:42.830 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:42.830 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:42.831 VF processing job null nPlayin=true
Sun 3/5 11:36:42.832 isRec=false rd=7200000 base=0
Sun 3/5 11:36:42.832 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:43.107 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:43.107 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:43.108 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:43.109 Event: 0x10b
Sun 3/5 11:36:43.109 VF processing job null nPlayin=true
Sun 3/5 11:36:43.110 isRec=false rd=7200000 base=0
Sun 3/5 11:36:43.110 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:43.167 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:43.167 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:43.168 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:43.169 Event: 0x10b
Sun 3/5 11:36:43.169 VF processing job null nPlayin=true
Sun 3/5 11:36:43.170 isRec=false rd=7200000 base=0
Sun 3/5 11:36:43.170 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:43.756 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:43.757 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:43.757 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:43.759 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:43.791 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:44.169 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:44.170 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:44.188 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:44.189 Event: 0x103
Sun 3/5 11:36:44.189 Event: 0x10b
Sun 3/5 11:36:44.189 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:44.190 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:44.190 VF processing job null nPlayin=true
Sun 3/5 11:36:44.191 isRec=false rd=7200000 base=0
Sun 3/5 11:36:44.191 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:44.395 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:44.396 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:44.396 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:44.439 Event: 0x10b
Sun 3/5 11:36:44.440 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:44.441 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:44.442 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:44.442 Event: 0x10b
Sun 3/5 11:36:44.443 VF processing job null nPlayin=true
Sun 3/5 11:36:44.443 isRec=false rd=7200000 base=0
Sun 3/5 11:36:44.443 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:44.788 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:44.788 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:44.791 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:44.792 Event: 0x10b
Sun 3/5 11:36:44.792 VF processing job null nPlayin=true
Sun 3/5 11:36:44.793 isRec=false rd=7200000 base=0
Sun 3/5 11:36:44.793 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:45.278 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:45.278 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:45.279 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:45.323 Event: 0x10b
Sun 3/5 11:36:45.323 VF processing job null nPlayin=true
Sun 3/5 11:36:45.324 isRec=false rd=7200000 base=0
Sun 3/5 11:36:45.324 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:45.376 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:45.377 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:45.378 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:45.379 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:45.422 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:45.644 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:45.645 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:45.646 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:45.646 Event: 0x103
Sun 3/5 11:36:45.647 Event: 0x10b
Sun 3/5 11:36:45.661 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:45.662 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:45.667 VF processing job null nPlayin=true
Sun 3/5 11:36:45.667 isRec=false rd=7200000 base=0
Sun 3/5 11:36:45.668 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:45.802 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:45.823 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:45.823 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:45.824 Event: 0x10b
Sun 3/5 11:36:45.825 VF processing job null nPlayin=true
Sun 3/5 11:36:45.825 isRec=false rd=7200000 base=0
Sun 3/5 11:36:45.826 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:45.886 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:45.887 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:45.887 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:45.888 Event: 0x10b
Sun 3/5 11:36:45.888 VF processing job null nPlayin=true
Sun 3/5 11:36:45.889 isRec=false rd=7200000 base=0
Sun 3/5 11:36:45.889 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:45.918 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:45.919 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:45.919 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:45.920 Event: 0x10b
Sun 3/5 11:36:45.920 VF processing job null nPlayin=true
Sun 3/5 11:36:45.921 isRec=false rd=7200000 base=0
Sun 3/5 11:36:45.921 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:46.186 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:46.187 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:46.188 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:46.189 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:46.212 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:46.468 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:46.593 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:46.607 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:46.611 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:46.691 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:46.692 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:46.693 Event: 0x103
Sun 3/5 11:36:46.693 Event: 0x10b
Sun 3/5 11:36:46.693 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:46.694 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:46.695 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:46.695 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:46.767 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:46.948 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:46.950 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:46.950 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:46.968 Event: 0x103
Sun 3/5 11:36:46.968 Event: 0x10b
Sun 3/5 11:36:46.969 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:46.969 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:46.970 VF processing job null nPlayin=true
Sun 3/5 11:36:46.970 isRec=false rd=7200000 base=0
Sun 3/5 11:36:46.970 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:47.126 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:47.126 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:47.127 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:47.159 Event: 0x10b
Sun 3/5 11:36:47.160 VF processing job null nPlayin=true
Sun 3/5 11:36:47.160 isRec=false rd=7200000 base=0
Sun 3/5 11:36:47.161 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:47.213 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:47.214 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:47.215 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:47.216 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:47.342 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:47.476 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:47.478 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:47.478 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:47.479 Event: 0x103
Sun 3/5 11:36:47.479 Event: 0x10b
Sun 3/5 11:36:47.480 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:47.497 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:47.498 VF processing job null nPlayin=true
Sun 3/5 11:36:47.498 isRec=false rd=7200000 base=0
Sun 3/5 11:36:47.498 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:47.689 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:47.689 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:47.690 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:47.691 Event: 0x10b
Sun 3/5 11:36:47.691 VF processing job null nPlayin=true
Sun 3/5 11:36:47.692 isRec=false rd=7200000 base=0
Sun 3/5 11:36:47.692 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:47.812 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:47.813 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:47.814 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:47.816 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:47.903 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:48.102 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:48.103 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:48.104 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:48.104 Event: 0x103
Sun 3/5 11:36:48.104 Event: 0x10b
Sun 3/5 11:36:48.105 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:48.105 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:48.106 VF processing job null nPlayin=true
Sun 3/5 11:36:48.107 isRec=false rd=7200000 base=0
Sun 3/5 11:36:48.107 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:48.265 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:48.265 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:48.266 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:48.277 Event: 0x10b
Sun 3/5 11:36:48.278 VF processing job null nPlayin=true
Sun 3/5 11:36:48.278 isRec=false rd=7200000 base=0
Sun 3/5 11:36:48.278 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:48.340 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:48.342 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:48.343 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:48.360 Event: 0x10b
Sun 3/5 11:36:48.360 VF processing job null nPlayin=true
Sun 3/5 11:36:48.361 isRec=false rd=7200000 base=0
Sun 3/5 11:36:48.361 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:48.422 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:48.422 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:48.423 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:48.425 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:48.462 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:48.797 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:48.797 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:48.798 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:48.798 Event: 0x103
Sun 3/5 11:36:48.799 Event: 0x10b
Sun 3/5 11:36:48.799 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:48.799 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:48.817 VF processing job null nPlayin=true
Sun 3/5 11:36:48.817 isRec=false rd=7200000 base=0
Sun 3/5 11:36:48.817 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:49.041 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:49.041 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:49.042 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:49.043 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:49.177 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:49.557 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:49.558 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:49.567 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:49.568 Event: 0x103
Sun 3/5 11:36:49.568 Event: 0x10b
Sun 3/5 11:36:49.569 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:49.569 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:49.570 VF processing job null nPlayin=true
Sun 3/5 11:36:49.570 isRec=false rd=7200000 base=0
Sun 3/5 11:36:49.570 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:49.639 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:49.671 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:49.671 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:49.674 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:49.828 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:50.022 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:50.075 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:50.076 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:50.111 Event: 0x103
Sun 3/5 11:36:50.111 Event: 0x10b
Sun 3/5 11:36:50.112 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:50.112 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:50.113 VF processing job null nPlayin=true
Sun 3/5 11:36:50.113 isRec=false rd=7200000 base=0
Sun 3/5 11:36:50.114 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:50.253 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:50.253 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:50.254 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:50.278 Event: 0x10b
Sun 3/5 11:36:50.279 VF processing job null nPlayin=true
Sun 3/5 11:36:50.279 isRec=false rd=7200000 base=0
Sun 3/5 11:36:50.280 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:50.436 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:50.437 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:50.437 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:50.438 Event: 0x10b
Sun 3/5 11:36:50.439 VF processing job null nPlayin=true
Sun 3/5 11:36:50.439 isRec=false rd=7200000 base=0
Sun 3/5 11:36:50.439 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:50.860 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:50.861 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:50.861 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:50.862 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:50.887 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:51.004 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:51.022 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:51.023 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:51.024 Event: 0x103
Sun 3/5 11:36:51.024 Event: 0x10b
Sun 3/5 11:36:51.024 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:51.025 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:51.026 VF processing job null nPlayin=true
Sun 3/5 11:36:51.026 isRec=false rd=7200000 base=0
Sun 3/5 11:36:51.026 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:51.264 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:51.267 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:51.275 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:51.279 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:51.397 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:51.469 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:51.725 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:51.726 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:51.727 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:51.727 Event: 0x103
Sun 3/5 11:36:51.727 Event: 0x10b
Sun 3/5 11:36:51.728 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:51.728 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:51.734 VF processing job null nPlayin=true
Sun 3/5 11:36:51.735 isRec=false rd=7200000 base=0
Sun 3/5 11:36:51.735 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:51.929 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:51.930 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:51.930 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:51.931 Event: 0x10b
Sun 3/5 11:36:51.932 VF processing job null nPlayin=true
Sun 3/5 11:36:51.932 isRec=false rd=7200000 base=0
Sun 3/5 11:36:51.933 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:52.416 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:52.417 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:52.417 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:52.418 Event: 0x10b
Sun 3/5 11:36:52.431 VF processing job null nPlayin=true
Sun 3/5 11:36:52.432 isRec=false rd=7200000 base=0
Sun 3/5 11:36:52.432 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:52.487 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:52.488 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:52.488 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:52.489 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:52.534 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:52.834 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:52.835 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:52.835 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:52.836 Event: 0x103
Sun 3/5 11:36:52.837 Event: 0x10b
Sun 3/5 11:36:52.850 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:52.851 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:52.856 VF processing job null nPlayin=true
Sun 3/5 11:36:52.857 isRec=false rd=7200000 base=0
Sun 3/5 11:36:52.857 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:53.029 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:53.030 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:53.030 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:53.031 Event: 0x10b
Sun 3/5 11:36:53.032 VF processing job null nPlayin=true
Sun 3/5 11:36:53.032 isRec=false rd=7200000 base=0
Sun 3/5 11:36:53.032 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:53.068 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:53.069 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:53.069 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:53.070 Event: 0x10b
Sun 3/5 11:36:53.070 VF processing job null nPlayin=true
Sun 3/5 11:36:53.070 isRec=false rd=7200000 base=0
Sun 3/5 11:36:53.071 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:53.510 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:53.511 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:53.512 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:53.513 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:53.565 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:53.674 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:53.693 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:53.694 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:53.695 Event: 0x103
Sun 3/5 11:36:53.695 Event: 0x10b
Sun 3/5 11:36:53.695 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:53.696 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:53.696 VF processing job null nPlayin=true
Sun 3/5 11:36:53.697 isRec=false rd=7200000 base=0
Sun 3/5 11:36:53.697 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:53.817 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:53.817 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:53.817 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:53.848 Event: 0x10b
Sun 3/5 11:36:53.849 VF processing job null nPlayin=true
Sun 3/5 11:36:53.849 isRec=false rd=7200000 base=0
Sun 3/5 11:36:53.855 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:53.862 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:53.862 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:53.863 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:53.863 Event: 0x10b
Sun 3/5 11:36:53.864 VF processing job null nPlayin=true
Sun 3/5 11:36:53.864 isRec=false rd=7200000 base=0
Sun 3/5 11:36:53.865 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:54.342 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:54.343 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:54.348 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:54.349 Event: 0x10b
Sun 3/5 11:36:54.349 VF processing job null nPlayin=true
Sun 3/5 11:36:54.350 isRec=false rd=7200000 base=0
Sun 3/5 11:36:54.350 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:54.862 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:54.862 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:54.863 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:54.863 Event: 0x10b
Sun 3/5 11:36:54.864 VF processing job null nPlayin=true
Sun 3/5 11:36:54.864 isRec=false rd=7200000 base=0
Sun 3/5 11:36:54.864 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:55.124 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:55.125 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:55.125 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:55.127 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:55.181 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:55.498 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:55.515 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:55.516 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:55.517 Event: 0x103
Sun 3/5 11:36:55.517 Event: 0x10b
Sun 3/5 11:36:55.518 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:55.518 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:55.519 VF processing job null nPlayin=true
Sun 3/5 11:36:55.519 isRec=false rd=7200000 base=0
Sun 3/5 11:36:55.520 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:55.719 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:55.720 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:55.720 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:55.721 Event: 0x10b
Sun 3/5 11:36:55.722 VF processing job null nPlayin=true
Sun 3/5 11:36:55.722 isRec=false rd=7200000 base=0
Sun 3/5 11:36:55.723 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:55.734 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:55.734 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:55.734 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:55.735 Event: 0x10b
Sun 3/5 11:36:55.736 VF processing job null nPlayin=true
Sun 3/5 11:36:55.736 isRec=false rd=7200000 base=0
Sun 3/5 11:36:55.736 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:56.143 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:56.143 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:56.144 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:56.145 Event: 0x10b
Sun 3/5 11:36:56.145 VF processing job null nPlayin=true
Sun 3/5 11:36:56.146 isRec=false rd=7200000 base=0
Sun 3/5 11:36:56.146 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:56.149 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:56.150 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:56.151 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:56.152 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:56.206 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:56.391 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:56.398 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:56.401 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:56.402 Event: 0x103
Sun 3/5 11:36:56.402 Event: 0x10b
Sun 3/5 11:36:56.403 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:56.403 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:56.404 VF processing job null nPlayin=true
Sun 3/5 11:36:56.404 isRec=false rd=7200000 base=0
Sun 3/5 11:36:56.404 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:56.484 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:36:56.565 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:56.565 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:56.566 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:56.567 Event: 0x10b
Sun 3/5 11:36:56.567 VF processing job null nPlayin=true
Sun 3/5 11:36:56.567 isRec=false rd=7200000 base=0
Sun 3/5 11:36:56.568 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:56.600 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:56.600 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:56.601 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:56.601 Event: 0x10b
Sun 3/5 11:36:56.602 VF processing job null nPlayin=true
Sun 3/5 11:36:56.602 isRec=false rd=7200000 base=0
Sun 3/5 11:36:56.603 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:56.955 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:56.956 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:56.957 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:56.958 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:56.987 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:57.084 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:57.101 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:57.103 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:57.118 Event: 0x103
Sun 3/5 11:36:57.118 Event: 0x10b
Sun 3/5 11:36:57.119 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:57.121 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:57.122 VF processing job null nPlayin=true
Sun 3/5 11:36:57.122 isRec=false rd=7200000 base=0
Sun 3/5 11:36:57.122 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:57.294 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:57.295 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:57.295 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:57.336 Event: 0x10b
Sun 3/5 11:36:57.337 VF processing job null nPlayin=true
Sun 3/5 11:36:57.338 isRec=false rd=7200000 base=0
Sun 3/5 11:36:57.338 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:57.343 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:57.344 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:57.344 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:57.345 Event: 0x10b
Sun 3/5 11:36:57.346 VF processing job null nPlayin=true
Sun 3/5 11:36:57.346 isRec=false rd=7200000 base=0
Sun 3/5 11:36:57.347 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:57.993 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:57.994 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:57.995 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:58.001 Event: 0x10b
Sun 3/5 11:36:58.002 VF processing job null nPlayin=true
Sun 3/5 11:36:58.003 isRec=false rd=7200000 base=0
Sun 3/5 11:36:58.004 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:58.497 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:58.498 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:58.498 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:58.500 Event: 0x10b
Sun 3/5 11:36:58.500 VF processing job null nPlayin=true
Sun 3/5 11:36:58.501 isRec=false rd=7200000 base=0
Sun 3/5 11:36:58.501 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:59.373 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:59.374 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:59.377 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:59.377 Event: 0x10b
Sun 3/5 11:36:59.378 VF processing job null nPlayin=true
Sun 3/5 11:36:59.379 isRec=false rd=7200000 base=0
Sun 3/5 11:36:59.379 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:59.400 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:36:59.400 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:59.401 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:59.402 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:59.455 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:59.638 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:59.640 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:59.640 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:59.641 Event: 0x103
Sun 3/5 11:36:59.641 Event: 0x10b
Sun 3/5 11:36:59.641 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:59.642 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:59.643 VF processing job null nPlayin=true
Sun 3/5 11:36:59.643 isRec=false rd=7200000 base=0
Sun 3/5 11:36:59.643 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:59.829 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:59.829 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:59.829 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:59.830 Event: 0x10b
Sun 3/5 11:36:59.831 VF processing job null nPlayin=true
Sun 3/5 11:36:59.831 isRec=false rd=7200000 base=0
Sun 3/5 11:36:59.831 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:36:59.869 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:36:59.870 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:36:59.870 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:36:59.871 Event: 0x10b
Sun 3/5 11:36:59.872 VF processing job null nPlayin=true
Sun 3/5 11:36:59.872 isRec=false rd=7200000 base=0
Sun 3/5 11:36:59.872 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:00.557 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:00.558 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:00.558 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:00.559 Event: 0x10b
Sun 3/5 11:37:00.560 VF processing job null nPlayin=true
Sun 3/5 11:37:00.561 isRec=false rd=7200000 base=0
Sun 3/5 11:37:00.561 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:01.107 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:01.108 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:01.109 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:01.109 Event: 0x10b
Sun 3/5 11:37:01.110 VF processing job null nPlayin=true
Sun 3/5 11:37:01.110 isRec=false rd=7200000 base=0
Sun 3/5 11:37:01.111 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:01.433 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:01.434 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:01.437 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:01.439 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:01.485 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:01.494 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:01.693 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:01.694 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:01.695 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:01.695 Event: 0x103
Sun 3/5 11:37:01.696 Event: 0x10b
Sun 3/5 11:37:01.696 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:01.697 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:01.697 VF processing job null nPlayin=true
Sun 3/5 11:37:01.698 isRec=false rd=7200000 base=0
Sun 3/5 11:37:01.698 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:01.886 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:01.887 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:01.887 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:01.888 Event: 0x10b
Sun 3/5 11:37:01.889 VF processing job null nPlayin=true
Sun 3/5 11:37:01.889 isRec=false rd=7200000 base=0
Sun 3/5 11:37:01.889 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:01.933 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:01.934 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:01.934 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:01.935 Event: 0x10b
Sun 3/5 11:37:01.936 VF processing job null nPlayin=true
Sun 3/5 11:37:01.936 isRec=false rd=7200000 base=0
Sun 3/5 11:37:01.937 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:02.512 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:02.512 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:02.515 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:02.516 Event: 0x10b
Sun 3/5 11:37:02.517 VF processing job null nPlayin=true
Sun 3/5 11:37:02.518 isRec=false rd=7200000 base=0
Sun 3/5 11:37:02.518 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:02.642 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:02.643 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:02.645 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:02.647 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:02.663 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:02.938 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:02.939 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:02.942 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:02.943 Event: 0x103
Sun 3/5 11:37:02.943 Event: 0x10b
Sun 3/5 11:37:02.943 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:02.944 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:02.944 VF processing job null nPlayin=true
Sun 3/5 11:37:02.945 isRec=false rd=7200000 base=0
Sun 3/5 11:37:02.945 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:02.964 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:02.965 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:02.981 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:02.981 Event: 0x10b
Sun 3/5 11:37:02.982 VF processing job null nPlayin=true
Sun 3/5 11:37:02.982 isRec=false rd=7200000 base=0
Sun 3/5 11:37:02.982 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:03.110 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:03.111 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:03.111 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:03.125 Event: 0x10b
Sun 3/5 11:37:03.126 VF processing job null nPlayin=true
Sun 3/5 11:37:03.126 isRec=false rd=7200000 base=0
Sun 3/5 11:37:03.126 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:03.568 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:03.568 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:03.571 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:03.572 Event: 0x10b
Sun 3/5 11:37:03.573 VF processing job null nPlayin=true
Sun 3/5 11:37:03.573 isRec=false rd=7200000 base=0
Sun 3/5 11:37:03.573 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:03.666 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:03.666 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:03.667 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:03.668 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:03.694 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:03.810 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:03.829 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:03.830 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:03.830 Event: 0x103
Sun 3/5 11:37:03.831 Event: 0x10b
Sun 3/5 11:37:03.831 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:03.831 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:03.832 VF processing job null nPlayin=true
Sun 3/5 11:37:03.832 isRec=false rd=7200000 base=0
Sun 3/5 11:37:03.833 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:03.938 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:03.939 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:03.939 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:03.976 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:03.977 Event: 0x10b
Sun 3/5 11:37:03.978 Event: 0x10b
Sun 3/5 11:37:03.978 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:03.980 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:03.981 VF processing job null nPlayin=true
Sun 3/5 11:37:03.981 isRec=false rd=7200000 base=0
Sun 3/5 11:37:03.981 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:04.446 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:04.447 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:04.447 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:04.448 Event: 0x10b
Sun 3/5 11:37:04.451 VF processing job null nPlayin=true
Sun 3/5 11:37:04.452 isRec=false rd=7200000 base=0
Sun 3/5 11:37:04.452 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:04.683 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:04.684 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:04.684 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:04.685 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:04.714 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:04.815 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:04.817 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:04.817 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:04.818 Event: 0x103
Sun 3/5 11:37:04.818 Event: 0x10b
Sun 3/5 11:37:04.818 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:04.819 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:04.819 VF processing job null nPlayin=true
Sun 3/5 11:37:04.820 isRec=false rd=7200000 base=0
Sun 3/5 11:37:04.820 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:05.053 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:05.054 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:05.054 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:05.055 Event: 0x10b
Sun 3/5 11:37:05.055 VF processing job null nPlayin=true
Sun 3/5 11:37:05.056 isRec=false rd=7200000 base=0
Sun 3/5 11:37:05.056 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:05.450 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:05.451 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:05.451 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:05.452 Event: 0x10b
Sun 3/5 11:37:05.453 VF processing job null nPlayin=true
Sun 3/5 11:37:05.453 isRec=false rd=7200000 base=0
Sun 3/5 11:37:05.453 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:05.891 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:05.892 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:05.893 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:05.894 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:05.946 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:06.091 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:06.109 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:06.110 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:06.110 Event: 0x103
Sun 3/5 11:37:06.111 Event: 0x10b
Sun 3/5 11:37:06.111 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:06.112 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:06.113 VF processing job null nPlayin=true
Sun 3/5 11:37:06.113 isRec=false rd=7200000 base=0
Sun 3/5 11:37:06.113 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:06.280 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:06.280 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:06.281 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:06.294 Event: 0x10b
Sun 3/5 11:37:06.294 VF processing job null nPlayin=true
Sun 3/5 11:37:06.295 isRec=false rd=7200000 base=0
Sun 3/5 11:37:06.295 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:06.379 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:06.380 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:06.381 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:06.381 Event: 0x10b
Sun 3/5 11:37:06.382 VF processing job null nPlayin=true
Sun 3/5 11:37:06.383 isRec=false rd=7200000 base=0
Sun 3/5 11:37:06.383 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:06.486 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:06.783 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:06.783 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:06.784 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:06.784 Event: 0x10b
Sun 3/5 11:37:06.785 VF processing job null nPlayin=true
Sun 3/5 11:37:06.785 isRec=false rd=7200000 base=0
Sun 3/5 11:37:06.785 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:07.110 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:07.110 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:07.111 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:07.112 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:07.160 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:07.416 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:07.432 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:07.434 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:07.435 Event: 0x103
Sun 3/5 11:37:07.435 Event: 0x10b
Sun 3/5 11:37:07.435 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:07.436 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:07.436 VF processing job null nPlayin=true
Sun 3/5 11:37:07.437 isRec=false rd=7200000 base=0
Sun 3/5 11:37:07.438 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:07.663 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:07.668 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:07.669 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:07.680 Event: 0x10b
Sun 3/5 11:37:07.681 VF processing job null nPlayin=true
Sun 3/5 11:37:07.681 isRec=false rd=7200000 base=0
Sun 3/5 11:37:07.681 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:08.337 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:08.338 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:08.339 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:08.340 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:08.361 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:08.656 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:08.674 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:08.676 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:08.676 Event: 0x103
Sun 3/5 11:37:08.676 Event: 0x10b
Sun 3/5 11:37:08.677 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:08.677 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:08.678 VF processing job null nPlayin=true
Sun 3/5 11:37:08.679 isRec=false rd=7200000 base=0
Sun 3/5 11:37:08.679 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:08.783 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:08.783 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:08.784 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:08.814 Event: 0x10b
Sun 3/5 11:37:08.815 VF processing job null nPlayin=true
Sun 3/5 11:37:08.815 isRec=false rd=7200000 base=0
Sun 3/5 11:37:08.816 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:08.830 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:08.830 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:08.831 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:08.831 Event: 0x10b
Sun 3/5 11:37:08.832 VF processing job null nPlayin=true
Sun 3/5 11:37:08.832 isRec=false rd=7200000 base=0
Sun 3/5 11:37:08.832 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:09.324 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:09.324 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:09.325 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:09.328 Event: 0x10b
Sun 3/5 11:37:09.329 VF processing job null nPlayin=true
Sun 3/5 11:37:09.329 isRec=false rd=7200000 base=0
Sun 3/5 11:37:09.330 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:09.728 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:09.731 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:09.731 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:09.733 Event: 0x10b
Sun 3/5 11:37:09.734 VF processing job null nPlayin=true
Sun 3/5 11:37:09.734 isRec=false rd=7200000 base=0
Sun 3/5 11:37:09.734 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:09.954 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:09.954 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:09.955 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:09.956 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:09.981 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:10.278 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:10.279 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:10.290 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:10.297 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:10.298 Event: 0x103
Sun 3/5 11:37:10.298 Event: 0x10b
Sun 3/5 11:37:10.299 Event: 0x10b
Sun 3/5 11:37:10.299 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:10.299 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:10.300 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:10.300 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:10.303 VF processing job null nPlayin=true
Sun 3/5 11:37:10.303 isRec=false rd=7200000 base=0
Sun 3/5 11:37:10.303 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:10.344 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:10.345 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:10.345 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:10.346 Event: 0x10b
Sun 3/5 11:37:10.347 VF processing job null nPlayin=true
Sun 3/5 11:37:10.347 isRec=false rd=7200000 base=0
Sun 3/5 11:37:10.347 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:10.403 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:10.404 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:10.404 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:10.405 Event: 0x10b
Sun 3/5 11:37:10.406 VF processing job null nPlayin=true
Sun 3/5 11:37:10.407 isRec=false rd=7200000 base=0
Sun 3/5 11:37:10.407 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:10.424 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:10.424 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:10.425 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:10.426 Event: 0x10b
Sun 3/5 11:37:10.427 VF processing job null nPlayin=true
Sun 3/5 11:37:10.427 isRec=false rd=7200000 base=0
Sun 3/5 11:37:10.427 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:10.922 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:10.922 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:10.923 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:10.923 Event: 0x10b
Sun 3/5 11:37:10.924 VF processing job null nPlayin=true
Sun 3/5 11:37:10.924 isRec=false rd=7200000 base=0
Sun 3/5 11:37:10.924 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:11.487 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:11.757 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:11.757 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:11.759 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:11.760 Event: 0x10b
Sun 3/5 11:37:11.761 VF processing job null nPlayin=true
Sun 3/5 11:37:11.761 isRec=false rd=7200000 base=0
Sun 3/5 11:37:11.762 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:12.309 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:12.309 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:12.309 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:12.318 Event: 0x10b
Sun 3/5 11:37:12.319 VF processing job null nPlayin=true
Sun 3/5 11:37:12.319 isRec=false rd=7200000 base=0
Sun 3/5 11:37:12.319 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:12.725 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:12.725 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:12.727 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:12.728 Event: 0x10b
Sun 3/5 11:37:12.729 VF processing job null nPlayin=true
Sun 3/5 11:37:12.729 isRec=false rd=7200000 base=0
Sun 3/5 11:37:12.729 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:13.224 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:13.224 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:13.225 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:13.225 Event: 0x10b
Sun 3/5 11:37:13.226 VF processing job null nPlayin=true
Sun 3/5 11:37:13.226 isRec=false rd=7200000 base=0
Sun 3/5 11:37:13.226 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:13.906 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:13.906 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:13.908 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:13.909 Event: 0x10b
Sun 3/5 11:37:13.909 VF processing job null nPlayin=true
Sun 3/5 11:37:13.910 isRec=false rd=7200000 base=0
Sun 3/5 11:37:13.910 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:14.226 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:14.226 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:14.227 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:14.228 Event: 0x10b
Sun 3/5 11:37:14.228 VF processing job null nPlayin=true
Sun 3/5 11:37:14.229 isRec=false rd=7200000 base=0
Sun 3/5 11:37:14.229 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:14.727 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:14.727 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:14.728 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:14.728 Event: 0x10b
Sun 3/5 11:37:14.729 VF processing job null nPlayin=true
Sun 3/5 11:37:14.729 isRec=false rd=7200000 base=0
Sun 3/5 11:37:14.729 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:14.835 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:14.836 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:14.837 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:14.838 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:14.877 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:15.256 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:15.259 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:15.259 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:15.262 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:15.263 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:15.264 Event: 0x103
Sun 3/5 11:37:15.264 Event: 0x10b
Sun 3/5 11:37:15.264 Event: 0x101
Sun 3/5 11:37:15.264 Event: 0x112
Sun 3/5 11:37:15.264 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:15.265 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:15.265 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:15.266 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:15.266 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:15.266 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:15.267 VF processing job null nPlayin=true
Sun 3/5 11:37:15.267 isRec=false rd=7200000 base=0
Sun 3/5 11:37:15.268 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:16.489 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:17.879 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:18.897 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:20.115 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:21.330 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:21.518 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:22.141 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:23.165 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:23.773 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:24.781 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:24.782 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:24.782 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:24.785 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:24.786 Exception from line: 1029
Sun 3/5 11:37:24.793 VF processing job null nPlayin=true
Sun 3/5 11:37:24.793 isRec=false rd=7200000 base=0
Sun 3/5 11:37:24.793 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:24.870 ImageUtils creating scaled masked copy width=71 height=71 mask=null BufferedImage@1c39aa6: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 16 height = 16 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0
Sun 3/5 11:37:25.809 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:25.809 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:25.810 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:25.811 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:25.812 Exception from line: 1029
Sun 3/5 11:37:25.819 VF processing job null nPlayin=true
Sun 3/5 11:37:25.819 isRec=false rd=7200000 base=0
Sun 3/5 11:37:25.819 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:26.519 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:28.032 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:28.033 VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:28.033 VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:28.035 VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:28.035 VF logFileWatch doneAir=FA[BAD AIRING] theTime=Wed 12/31 20:30:00.000 recTime=Wed 12/31 20:30:00.000
Sun 3/5 11:37:28.036 Stopping the playback graph.
Sun 3/5 11:37:28.039 Tearing down playback graph
Sun 3/5 11:37:28.040 NATIVE Deallocating the CPlayerData object
Sun 3/5 11:37:28.202 NATIVE Finished destroying the playback graph
Sun 3/5 11:37:28.204 VF processing job null nPlayin=false
Sun 3/5 11:37:28.205 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:28.209 setUI(sage.bu@e07e6b[Main Menu]) histIdx=7 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup], sage.bu@1e67280[Setup Menu], sage.bu@e07e6b[Main Menu], sage.bu@12d34a6[Library], sage.bu@57df5[MediaPlayer OSD], sage.bu@e07e6b[Main Menu]]
Sun 3/5 11:37:28.465 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:30.484 setUI(sage.bu@12d34a6[Library]) histIdx=8 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup], sage.bu@1e67280[Setup Menu], sage.bu@e07e6b[Main Menu], sage.bu@12d34a6[Library], sage.bu@57df5[MediaPlayer OSD], sage.bu@e07e6b[Main Menu], sage.bu@12d34a6[Library]]
Sun 3/5 11:37:30.489 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:31.529 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:31.714 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:31.715 VideoFrame.watch(FA[BAD AIRING])
Sun 3/5 11:37:31.715 watchThisFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:37:31.717 Watch airing is a file & over, do that instead dvd=true
Sun 3/5 11:37:31.717 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null])
Sun 3/5 11:37:31.718 setUI(sage.bu@57df5[MediaPlayer OSD]) histIdx=9 uiHistory=[sage.bu@1aea727[Main Menu], sage.bu@1e67280[Setup Menu], sage.bu@7541f8[Detailed Setup], sage.bu@1e67280[Setup Menu], sage.bu@e07e6b[Main Menu], sage.bu@12d34a6[Library], sage.bu@57df5[MediaPlayer OSD], sage.bu@e07e6b[Main Menu], sage.bu@12d34a6[Library], sage.bu@57df5[MediaPlayer OSD]]
Sun 3/5 11:37:31.725 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null] nPlayin=false
Sun 3/5 11:37:31.727 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null]
Sun 3/5 11:37:31.728 VideoFrame creating new media player for file:MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:37:31.728 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null] nPlayin=false
Sun 3/5 11:37:31.730 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] ifn=null]
Sun 3/5 11:37:31.731 VF file=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] targetTime = Wed 12/31 20:30:00.000
Sun 3/5 11:37:31.731 VideoFrame.timeSelected(Wed 12/31 20:30:00.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:37:31.738 DShowPlayer setVideoRendererFilter0({CD8743A1-3736-11D0-9E69-00C04FD7C15B}) called
Sun 3/5 11:37:31.743 DShowPlayer setAudioRendererFilter0(Default WaveOut Device) called
Sun 3/5 11:37:31.747 DShowPlayer setAudioDecoderFilter0(InterVideo Audio Decoder) called
Sun 3/5 11:37:31.777 NATIVE RenderAudio called
Sun 3/5 11:37:31.778 NATIVE RenderAudio step 1
Sun 3/5 11:37:31.778 NATIVE RenderAudio step 2
Sun 3/5 11:37:31.778 NATIVE RenderAudio step 3
Sun 3/5 11:37:31.784 NATIVE RenderVideo called
Sun 3/5 11:37:31.784 NATIVE RenderVideo step 1
Sun 3/5 11:37:31.784 NATIVE RenderVideo step 2
Sun 3/5 11:37:31.784 Video source pin information:
Sun 3/5 11:37:31.785 PIN is not connected yet!Sun 3/5 11:37:31.785 M type MEDIATYPE_DVD_ENCRYPTED_PACK  S type MEDIASUBTYPE_MPEG2_VIDEO F type FORMAT_MPEG2_VIDEO
Sun 3/5 11:37:31.785 NATIVE RenderVideo step 3
Sun 3/5 11:37:31.868 Filter Graph Video Renderer Info:
Sun 3/5 11:37:31.869 Video Renderer:CLSID_OverlayMixer
Sun 3/5 11:37:31.869 M type MEDIATYPE_Video  S type Unknown GUID Name F type FORMAT_VideoInfo2
Sun 3/5 11:37:31.869 Interlaced video
Sun 3/5 11:37:31.869 Both fields per sample
Sun 3/5 11:37:31.876 Field 2 first
Sun 3/5 11:37:31.876 Stream never contains a Field 2
Sun 3/5 11:37:31.876 Either bob or weave mode.
Sun 3/5 11:37:31.881 DShowGraphFilters=Video Renderer,AudRend,VidRend,Audio Decoder,Line21 Decoder,NVIDIA Video Decoder,DVD Navigator,
Sun 3/5 11:37:31.882 DShowPlayer setVideoHWND(66118)
Sun 3/5 11:37:31.885 Got the default color key 0x100010
Sun 3/5 11:37:31.885 Got the default overlay color key of 0x100010
Sun 3/5 11:37:31.886 Setting the color key to 0x100010
Sun 3/5 11:37:31.886 Returning the color key as 0xff100010
Sun 3/5 11:37:31.886 Got Native Video Dimensions java.awt.Dimension[width=768,height=576]
Sun 3/5 11:37:31.887 VideoFrame.timeSelected2(Wed 12/31 20:30:00.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:37:31.887 Running the playback graph.
Sun 3/5 11:37:31.888 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:31.897 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:31.897 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:31.898 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.123 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.123 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.123 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.124 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.126 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.126 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.127 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.127 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:32.157 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.201 AWTThread Hang Detected - hang time = 750
Sun 3/5 11:37:33.315 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:33.315 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:33.319 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:33.321 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.322 Event: 0xd
Sun 3/5 11:37:33.322 Event: 0x104
Sun 3/5 11:37:33.322 Event: 0x106
Sun 3/5 11:37:33.322 Event: 0x102
Sun 3/5 11:37:33.322 Event: 0x107
Sun 3/5 11:37:33.324 Event: 0x108
Sun 3/5 11:37:33.324 Event: 0x101
Sun 3/5 11:37:33.324 Event: 0xe
Sun 3/5 11:37:33.325 Event: 0x101
Sun 3/5 11:37:33.325 Event: 0x103
Sun 3/5 11:37:33.325 Event: 0x105
Sun 3/5 11:37:33.325 Event: 0x104
Sun 3/5 11:37:33.325 Event: 0x108
Sun 3/5 11:37:33.325 Event: 0x10b
Sun 3/5 11:37:33.325 Event: 0x10b
Sun 3/5 11:37:33.326 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.326 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.327 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.327 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.328 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.328 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.329 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.329 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.330 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.330 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.332 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.346 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.355 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.356 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.356 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.357 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.358 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.359 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.359 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.360 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.361 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.361 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.362 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.362 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.363 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.363 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.364 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.364 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.365 VF processing job null nPlayin=true
Sun 3/5 11:37:33.365 isRec=false rd=7200000 base=0
Sun 3/5 11:37:33.365 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:33.515 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:33.516 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.517 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.517 Event: 0x10b
Sun 3/5 11:37:33.518 VF processing job null nPlayin=true
Sun 3/5 11:37:33.518 isRec=false rd=7200000 base=0
Sun 3/5 11:37:33.518 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:33.603 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:33.604 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.604 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.605 Event: 0x10b
Sun 3/5 11:37:33.605 VF processing job null nPlayin=true
Sun 3/5 11:37:33.606 isRec=false rd=7200000 base=0
Sun 3/5 11:37:33.606 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:33.818 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:33.818 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:33.819 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:33.819 Event: 0x10b
Sun 3/5 11:37:33.820 VF processing job null nPlayin=true
Sun 3/5 11:37:33.820 isRec=false rd=7200000 base=0
Sun 3/5 11:37:33.821 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:34.112 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:34.113 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:34.113 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:34.117 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:34.195 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:34.387 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:34.389 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:34.389 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:34.390 Event: 0x103
Sun 3/5 11:37:34.390 Event: 0x10b
Sun 3/5 11:37:34.390 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:34.391 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:34.391 VF processing job null nPlayin=true
Sun 3/5 11:37:34.392 isRec=false rd=7200000 base=0
Sun 3/5 11:37:34.392 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:34.673 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:34.673 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:34.674 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:34.674 Event: 0x10b
Sun 3/5 11:37:34.675 VF processing job null nPlayin=true
Sun 3/5 11:37:34.675 isRec=false rd=7200000 base=0
Sun 3/5 11:37:34.675 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:34.936 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:34.937 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:34.938 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:34.939 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:34.991 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:35.447 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:35.461 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:35.465 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:35.466 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:35.466 Event: 0x103
Sun 3/5 11:37:35.467 Event: 0x10b
Sun 3/5 11:37:35.467 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:35.467 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:35.468 VF processing job null nPlayin=true
Sun 3/5 11:37:35.472 isRec=false rd=7200000 base=0
Sun 3/5 11:37:35.472 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:35.739 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:35.740 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:35.740 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:35.741 Event: 0x10b
Sun 3/5 11:37:35.742 VF processing job null nPlayin=true
Sun 3/5 11:37:35.742 isRec=false rd=7200000 base=0
Sun 3/5 11:37:35.742 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:35.747 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:35.748 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:35.748 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:35.750 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:35.950 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.317 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.318 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.337 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:36.340 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.340 Event: 0x103
Sun 3/5 11:37:36.341 Event: 0x10b
Sun 3/5 11:37:36.341 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.341 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.342 VF processing job null nPlayin=true
Sun 3/5 11:37:36.342 isRec=false rd=7200000 base=0
Sun 3/5 11:37:36.342 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:36.359 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:36.370 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.371 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.373 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.504 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.514 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:36.668 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.704 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.705 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.705 Event: 0x103
Sun 3/5 11:37:36.706 Event: 0x10b
Sun 3/5 11:37:36.711 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.711 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.712 VF processing job null nPlayin=true
Sun 3/5 11:37:36.713 isRec=false rd=7200000 base=0
Sun 3/5 11:37:36.713 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:36.779 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:36.797 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.798 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.808 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.953 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.956 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.971 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:36.974 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.975 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.975 Event: 0x103
Sun 3/5 11:37:36.975 Event: 0x10b
Sun 3/5 11:37:36.976 Event: 0x10b
Sun 3/5 11:37:36.990 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.991 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.992 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:36.993 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:36.994 VF processing job null nPlayin=true
Sun 3/5 11:37:36.994 isRec=false rd=7200000 base=0
Sun 3/5 11:37:36.994 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:37.338 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:37.338 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:37.339 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:37.339 Event: 0x10b
Sun 3/5 11:37:37.340 VF processing job null nPlayin=true
Sun 3/5 11:37:37.340 isRec=false rd=7200000 base=0
Sun 3/5 11:37:37.341 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:37.415 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:37.416 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:37.416 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:37.417 Event: 0x10b
Sun 3/5 11:37:37.418 VF processing job null nPlayin=true
Sun 3/5 11:37:37.419 isRec=false rd=7200000 base=0
Sun 3/5 11:37:37.419 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:37.940 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:37.941 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:37.944 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:37.945 Event: 0x10b
Sun 3/5 11:37:37.946 VF processing job null nPlayin=true
Sun 3/5 11:37:37.947 isRec=false rd=7200000 base=0
Sun 3/5 11:37:37.947 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:38.188 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:38.188 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:38.189 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:38.190 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:38.208 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:38.434 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:38.450 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:38.451 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:38.451 Event: 0x103
Sun 3/5 11:37:38.451 Event: 0x10b
Sun 3/5 11:37:38.455 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:38.455 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:38.457 VF processing job null nPlayin=true
Sun 3/5 11:37:38.457 isRec=false rd=7200000 base=0
Sun 3/5 11:37:38.458 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:38.582 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:38.616 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:38.616 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:38.619 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:38.768 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:38.899 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:38.938 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:38.939 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:38.939 Event: 0x103
Sun 3/5 11:37:38.939 Event: 0x10b
Sun 3/5 11:37:38.940 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:38.940 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:38.941 VF processing job null nPlayin=true
Sun 3/5 11:37:38.941 isRec=false rd=7200000 base=0
Sun 3/5 11:37:38.941 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:39.103 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:39.103 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:39.104 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:39.104 Event: 0x10b
Sun 3/5 11:37:39.105 VF processing job null nPlayin=true
Sun 3/5 11:37:39.106 isRec=false rd=7200000 base=0
Sun 3/5 11:37:39.106 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:39.599 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:39.600 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:39.601 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:39.602 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:39.685 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:39.838 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:39.855 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:39.858 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:39.859 Event: 0x103
Sun 3/5 11:37:39.859 Event: 0x10b
Sun 3/5 11:37:39.859 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:39.860 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:39.860 VF processing job null nPlayin=true
Sun 3/5 11:37:39.861 isRec=false rd=7200000 base=0
Sun 3/5 11:37:39.861 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:40.115 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.116 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.116 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.117 Event: 0x10b
Sun 3/5 11:37:40.118 VF processing job null nPlayin=true
Sun 3/5 11:37:40.118 isRec=false rd=7200000 base=0
Sun 3/5 11:37:40.118 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:40.121 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.121 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.124 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.125 Event: 0x10b
Sun 3/5 11:37:40.126 VF processing job null nPlayin=true
Sun 3/5 11:37:40.126 isRec=false rd=7200000 base=0
Sun 3/5 11:37:40.126 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:40.207 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.208 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.209 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.210 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:40.348 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.459 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.473 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.477 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.477 Event: 0x103
Sun 3/5 11:37:40.477 Event: 0x10b
Sun 3/5 11:37:40.478 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.478 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.479 VF processing job null nPlayin=true
Sun 3/5 11:37:40.479 isRec=false rd=7200000 base=0
Sun 3/5 11:37:40.479 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:40.670 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.671 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.671 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.715 Event: 0x10b
Sun 3/5 11:37:40.715 VF processing job null nPlayin=true
Sun 3/5 11:37:40.716 isRec=false rd=7200000 base=0
Sun 3/5 11:37:40.716 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:40.762 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.763 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.763 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.764 Event: 0x10b
Sun 3/5 11:37:40.764 VF processing job null nPlayin=true
Sun 3/5 11:37:40.765 isRec=false rd=7200000 base=0
Sun 3/5 11:37:40.765 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:40.826 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.827 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.827 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:40.829 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.878 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.880 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:40.881 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.881 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.882 Event: 0x103
Sun 3/5 11:37:40.882 Event: 0x10b
Sun 3/5 11:37:40.882 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:40.883 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:40.883 VF processing job null nPlayin=true
Sun 3/5 11:37:40.884 isRec=false rd=7200000 base=0
Sun 3/5 11:37:40.884 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:41.070 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:41.071 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:41.071 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:41.082 Event: 0x10b
Sun 3/5 11:37:41.083 VF processing job null nPlayin=true
Sun 3/5 11:37:41.083 isRec=false rd=7200000 base=0
Sun 3/5 11:37:41.083 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:41.112 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:41.113 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:41.114 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:41.114 Event: 0x10b
Sun 3/5 11:37:41.115 VF processing job null nPlayin=true
Sun 3/5 11:37:41.115 isRec=false rd=7200000 base=0
Sun 3/5 11:37:41.115 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:41.407 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:41.417 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:41.418 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:41.418 Event: 0x10b
Sun 3/5 11:37:41.419 VF processing job null nPlayin=true
Sun 3/5 11:37:41.419 isRec=false rd=7200000 base=0
Sun 3/5 11:37:41.420 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:41.428 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:41.453 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:41.454 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:41.457 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:41.491 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:41.515 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:41.695 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:41.696 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:41.696 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:41.697 Event: 0x103
Sun 3/5 11:37:41.697 Event: 0x10b
Sun 3/5 11:37:41.715 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:41.716 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:41.717 VF processing job null nPlayin=true
Sun 3/5 11:37:41.717 isRec=false rd=7200000 base=0
Sun 3/5 11:37:41.717 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:41.913 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:41.914 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:41.914 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:41.915 Event: 0x10b
Sun 3/5 11:37:41.916 VF processing job null nPlayin=true
Sun 3/5 11:37:41.916 isRec=false rd=7200000 base=0
Sun 3/5 11:37:41.916 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:42.043 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:42.044 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:42.044 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:42.045 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:42.135 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:42.239 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:42.240 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:42.240 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:42.241 Event: 0x103
Sun 3/5 11:37:42.241 Event: 0x10b
Sun 3/5 11:37:42.242 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:42.242 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:42.243 VF processing job null nPlayin=true
Sun 3/5 11:37:42.243 isRec=false rd=7200000 base=0
Sun 3/5 11:37:42.243 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:42.461 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:42.462 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:42.500 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:42.502 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:42.502 Event: 0x10b
Sun 3/5 11:37:42.503 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:42.503 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:42.504 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:42.612 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:42.949 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:42.950 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:42.951 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:42.951 Event: 0x103
Sun 3/5 11:37:42.951 Event: 0x10b
Sun 3/5 11:37:42.952 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:42.952 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:42.953 VF processing job null nPlayin=true
Sun 3/5 11:37:42.953 isRec=false rd=7200000 base=0
Sun 3/5 11:37:42.953 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:43.060 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:43.098 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:43.098 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:43.100 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:43.163 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:43.444 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:43.451 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:43.451 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:43.452 Event: 0x103
Sun 3/5 11:37:43.452 Event: 0x10b
Sun 3/5 11:37:43.452 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:43.453 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:43.456 VF processing job null nPlayin=true
Sun 3/5 11:37:43.456 isRec=false rd=7200000 base=0
Sun 3/5 11:37:43.457 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:43.614 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:43.615 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:43.616 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:43.616 Event: 0x10b
Sun 3/5 11:37:43.617 VF processing job null nPlayin=true
Sun 3/5 11:37:43.617 isRec=false rd=7200000 base=0
Sun 3/5 11:37:43.617 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:43.633 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:43.635 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:43.635 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:43.636 Event: 0x10b
Sun 3/5 11:37:43.637 VF processing job null nPlayin=true
Sun 3/5 11:37:43.637 isRec=false rd=7200000 base=0
Sun 3/5 11:37:43.637 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:44.279 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:44.279 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:44.280 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:44.281 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:44.310 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:44.422 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:44.437 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:44.437 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:44.441 Event: 0x103
Sun 3/5 11:37:44.441 Event: 0x10b
Sun 3/5 11:37:44.441 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:44.442 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:44.442 VF processing job null nPlayin=true
Sun 3/5 11:37:44.443 isRec=false rd=7200000 base=0
Sun 3/5 11:37:44.457 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:44.564 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:44.565 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:44.565 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:44.566 Event: 0x10b
Sun 3/5 11:37:44.567 VF processing job null nPlayin=true
Sun 3/5 11:37:44.567 isRec=false rd=7200000 base=0
Sun 3/5 11:37:44.568 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:44.647 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:44.648 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:44.648 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:44.686 Event: 0x10b
Sun 3/5 11:37:44.687 VF processing job null nPlayin=true
Sun 3/5 11:37:44.688 isRec=false rd=7200000 base=0
Sun 3/5 11:37:44.688 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:44.891 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:44.891 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:44.892 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:44.894 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:44.921 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.270 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.271 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.272 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.272 Event: 0x103
Sun 3/5 11:37:45.272 Event: 0x10b
Sun 3/5 11:37:45.273 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.273 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.274 VF processing job null nPlayin=true
Sun 3/5 11:37:45.274 isRec=false rd=7200000 base=0
Sun 3/5 11:37:45.275 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:45.483 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.484 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.484 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.504 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.520 Event: 0x10b
Sun 3/5 11:37:45.520 Event: 0x10b
Sun 3/5 11:37:45.521 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.522 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:45.523 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.524 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.525 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.526 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.690 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.813 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.814 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.814 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.815 Event: 0x103
Sun 3/5 11:37:45.815 Event: 0x10b
Sun 3/5 11:37:45.815 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.816 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.817 VF processing job null nPlayin=true
Sun 3/5 11:37:45.817 isRec=false rd=7200000 base=0
Sun 3/5 11:37:45.817 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:45.948 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:45.949 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:45.949 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:45.957 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:46.004 Event: 0x10b
Sun 3/5 11:37:46.004 Event: 0x10b
Sun 3/5 11:37:46.005 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:46.005 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:46.006 VF processing job null nPlayin=true
Sun 3/5 11:37:46.006 isRec=false rd=7200000 base=0
Sun 3/5 11:37:46.007 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:46.310 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:46.311 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:46.311 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:46.312 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:46.379 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:46.495 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:46.510 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:46.511 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:46.511 Event: 0x103
Sun 3/5 11:37:46.511 Event: 0x10b
Sun 3/5 11:37:46.512 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:46.517 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:46.518 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:46.519 VF processing job null nPlayin=true
Sun 3/5 11:37:46.520 isRec=false rd=7200000 base=0
Sun 3/5 11:37:46.520 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:46.619 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:46.620 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:46.620 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:46.640 Event: 0x10b
Sun 3/5 11:37:46.641 VF processing job null nPlayin=true
Sun 3/5 11:37:46.641 isRec=false rd=7200000 base=0
Sun 3/5 11:37:46.641 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:46.667 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:46.668 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:46.668 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:46.669 Event: 0x10b
Sun 3/5 11:37:46.670 VF processing job null nPlayin=true
Sun 3/5 11:37:46.670 isRec=false rd=7200000 base=0
Sun 3/5 11:37:46.670 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:47.274 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:47.275 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:47.275 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:47.276 Event: 0x10b
Sun 3/5 11:37:47.276 VF processing job null nPlayin=true
Sun 3/5 11:37:47.277 isRec=false rd=7200000 base=0
Sun 3/5 11:37:47.277 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:47.324 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:47.325 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:47.326 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:47.327 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:47.359 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:47.472 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:47.474 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:47.474 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:47.489 Event: 0x103
Sun 3/5 11:37:47.489 Event: 0x10b
Sun 3/5 11:37:47.490 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:47.490 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:47.491 VF processing job null nPlayin=true
Sun 3/5 11:37:47.492 isRec=false rd=7200000 base=0
Sun 3/5 11:37:47.492 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:47.988 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:47.989 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:47.989 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:47.990 Event: 0x10b
Sun 3/5 11:37:47.990 VF processing job null nPlayin=true
Sun 3/5 11:37:47.991 isRec=false rd=7200000 base=0
Sun 3/5 11:37:47.991 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:48.028 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:48.029 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:48.029 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:48.030 Event: 0x10b
Sun 3/5 11:37:48.030 VF processing job null nPlayin=true
Sun 3/5 11:37:48.031 isRec=false rd=7200000 base=0
Sun 3/5 11:37:48.031 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:48.128 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:48.129 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:48.129 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:48.131 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:48.186 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:48.440 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:48.441 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:48.442 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:48.458 Event: 0x103
Sun 3/5 11:37:48.459 Event: 0x10b
Sun 3/5 11:37:48.459 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:48.459 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:48.460 VF processing job null nPlayin=true
Sun 3/5 11:37:48.460 isRec=false rd=7200000 base=0
Sun 3/5 11:37:48.461 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:48.573 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:48.574 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:48.574 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:48.575 Event: 0x10b
Sun 3/5 11:37:48.576 VF processing job null nPlayin=true
Sun 3/5 11:37:48.577 isRec=false rd=7200000 base=0
Sun 3/5 11:37:48.577 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:48.616 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:48.617 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:48.617 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:48.618 Event: 0x10b
Sun 3/5 11:37:48.618 VF processing job null nPlayin=true
Sun 3/5 11:37:48.619 isRec=false rd=7200000 base=0
Sun 3/5 11:37:48.619 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:48.951 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:48.952 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:48.953 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:48.954 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:48.989 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.084 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.085 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.109 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.121 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.122 Event: 0x103
Sun 3/5 11:37:49.122 Event: 0x10b
Sun 3/5 11:37:49.122 Event: 0x10b
Sun 3/5 11:37:49.124 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.124 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.125 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.125 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.126 VF processing job null nPlayin=true
Sun 3/5 11:37:49.126 isRec=false rd=7200000 base=0
Sun 3/5 11:37:49.126 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:49.243 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.243 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.244 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.274 Event: 0x10b
Sun 3/5 11:37:49.275 VF processing job null nPlayin=true
Sun 3/5 11:37:49.275 isRec=false rd=7200000 base=0
Sun 3/5 11:37:49.275 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:49.710 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.711 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.711 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.712 Event: 0x10b
Sun 3/5 11:37:49.712 VF processing job null nPlayin=true
Sun 3/5 11:37:49.713 isRec=false rd=7200000 base=0
Sun 3/5 11:37:49.713 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:49.757 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:49.758 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.758 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.765 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.795 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.919 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:49.934 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.938 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.939 Event: 0x103
Sun 3/5 11:37:49.939 Event: 0x10b
Sun 3/5 11:37:49.939 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:49.940 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:49.940 VF processing job null nPlayin=true
Sun 3/5 11:37:49.941 isRec=false rd=7200000 base=0
Sun 3/5 11:37:49.941 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:50.034 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:50.035 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:50.035 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:50.036 Event: 0x10b
Sun 3/5 11:37:50.037 VF processing job null nPlayin=true
Sun 3/5 11:37:50.037 isRec=false rd=7200000 base=0
Sun 3/5 11:37:50.038 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:50.072 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:50.072 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:50.073 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:50.108 Event: 0x10b
Sun 3/5 11:37:50.109 VF processing job null nPlayin=true
Sun 3/5 11:37:50.109 isRec=false rd=7200000 base=0
Sun 3/5 11:37:50.110 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:50.536 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:50.537 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:50.537 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:50.538 Event: 0x10b
Sun 3/5 11:37:50.542 VF processing job null nPlayin=true
Sun 3/5 11:37:50.542 isRec=false rd=7200000 base=0
Sun 3/5 11:37:50.542 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:50.576 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:50.576 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:50.578 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:50.581 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:50.630 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:50.749 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:50.750 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:50.750 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:50.751 Event: 0x103
Sun 3/5 11:37:50.751 Event: 0x10b
Sun 3/5 11:37:50.751 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:50.752 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:50.753 VF processing job null nPlayin=true
Sun 3/5 11:37:50.753 isRec=false rd=7200000 base=0
Sun 3/5 11:37:50.753 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:50.999 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:51.000 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:51.000 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:51.001 Event: 0x10b
Sun 3/5 11:37:51.001 VF processing job null nPlayin=true
Sun 3/5 11:37:51.002 isRec=false rd=7200000 base=0
Sun 3/5 11:37:51.002 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:51.400 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:51.400 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:51.401 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:51.401 Event: 0x10b
Sun 3/5 11:37:51.402 VF processing job null nPlayin=true
Sun 3/5 11:37:51.403 isRec=false rd=7200000 base=0
Sun 3/5 11:37:51.403 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:51.518 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:51.792 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:51.793 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:51.794 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:51.795 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:51.848 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:51.962 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:51.980 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:51.981 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:51.982 Event: 0x103
Sun 3/5 11:37:51.982 Event: 0x10b
Sun 3/5 11:37:51.982 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:51.983 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:51.983 VF processing job null nPlayin=true
Sun 3/5 11:37:51.984 isRec=false rd=7200000 base=0
Sun 3/5 11:37:51.984 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:52.148 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:52.148 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:52.149 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:52.149 Event: 0x10b
Sun 3/5 11:37:52.151 VF processing job null nPlayin=true
Sun 3/5 11:37:52.152 isRec=false rd=7200000 base=0
Sun 3/5 11:37:52.152 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:52.178 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:52.179 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:52.179 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:52.180 Event: 0x10b
Sun 3/5 11:37:52.180 VF processing job null nPlayin=true
Sun 3/5 11:37:52.181 isRec=false rd=7200000 base=0
Sun 3/5 11:37:52.181 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:52.665 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:52.665 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:52.666 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:52.666 Event: 0x10b
Sun 3/5 11:37:52.667 VF processing job null nPlayin=true
Sun 3/5 11:37:52.668 isRec=false rd=7200000 base=0
Sun 3/5 11:37:52.668 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:53.056 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:53.056 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:53.059 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:53.060 Event: 0x10b
Sun 3/5 11:37:53.061 VF processing job null nPlayin=true
Sun 3/5 11:37:53.061 isRec=false rd=7200000 base=0
Sun 3/5 11:37:53.061 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:53.208 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:53.208 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:53.209 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:53.212 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:53.252 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:53.536 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:53.542 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:53.546 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:53.546 Event: 0x103
Sun 3/5 11:37:53.547 Event: 0x10b
Sun 3/5 11:37:53.547 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:53.547 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:53.548 VF processing job null nPlayin=true
Sun 3/5 11:37:53.548 isRec=false rd=7200000 base=0
Sun 3/5 11:37:53.549 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:53.886 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:53.886 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:53.887 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:53.887 Event: 0x10b
Sun 3/5 11:37:53.888 VF processing job null nPlayin=true
Sun 3/5 11:37:53.888 isRec=false rd=7200000 base=0
Sun 3/5 11:37:53.889 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:54.630 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:54.630 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:54.631 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:54.636 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:54.645 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:54.646 Event: 0x10b
Sun 3/5 11:37:54.671 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:54.765 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:54.766 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:54.766 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:54.784 Event: 0x103
Sun 3/5 11:37:54.784 Event: 0x10b
Sun 3/5 11:37:54.784 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:54.785 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:54.785 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:54.786 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:54.786 VF processing job null nPlayin=true
Sun 3/5 11:37:54.787 isRec=false rd=7200000 base=0
Sun 3/5 11:37:54.787 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:54.886 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:54.886 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:54.886 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:54.909 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:54.930 Event: 0x10b
Sun 3/5 11:37:54.930 Event: 0x10b
Sun 3/5 11:37:54.931 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:54.931 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:54.932 VF processing job null nPlayin=true
Sun 3/5 11:37:54.932 isRec=false rd=7200000 base=0
Sun 3/5 11:37:54.932 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:55.367 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:55.368 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:55.371 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:55.372 Event: 0x10b
Sun 3/5 11:37:55.372 VF processing job null nPlayin=true
Sun 3/5 11:37:55.373 isRec=false rd=7200000 base=0
Sun 3/5 11:37:55.373 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:55.824 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:55.825 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:55.825 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:55.826 Event: 0x10b
Sun 3/5 11:37:55.826 VF processing job null nPlayin=true
Sun 3/5 11:37:55.826 isRec=false rd=7200000 base=0
Sun 3/5 11:37:55.827 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:55.848 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:55.849 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:55.849 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:55.851 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:55.870 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:55.988 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:55.989 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:56.003 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:56.005 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:56.006 Event: 0x103
Sun 3/5 11:37:56.006 Event: 0x10b
Sun 3/5 11:37:56.006 Event: 0x10b
Sun 3/5 11:37:56.008 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:56.009 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:56.009 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:56.010 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:56.012 VF processing job null nPlayin=true
Sun 3/5 11:37:56.012 isRec=false rd=7200000 base=0
Sun 3/5 11:37:56.013 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:56.065 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:56.066 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:56.066 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:56.067 Event: 0x10b
Sun 3/5 11:37:56.068 VF processing job null nPlayin=true
Sun 3/5 11:37:56.068 isRec=false rd=7200000 base=0
Sun 3/5 11:37:56.068 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:56.112 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:56.112 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:56.113 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:56.134 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:56.148 Event: 0x10b
Sun 3/5 11:37:56.148 Event: 0x10b
Sun 3/5 11:37:56.149 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:56.149 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:56.150 VF processing job null nPlayin=true
Sun 3/5 11:37:56.150 isRec=false rd=7200000 base=0
Sun 3/5 11:37:56.151 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:56.519 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:37:56.634 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:56.634 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:56.635 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:56.635 Event: 0x10b
Sun 3/5 11:37:56.636 VF processing job null nPlayin=true
Sun 3/5 11:37:56.636 isRec=false rd=7200000 base=0
Sun 3/5 11:37:56.636 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:57.469 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:57.469 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:57.474 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:57.516 Event: 0x10b
Sun 3/5 11:37:57.517 VF processing job null nPlayin=true
Sun 3/5 11:37:57.517 isRec=false rd=7200000 base=0
Sun 3/5 11:37:57.517 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:58.020 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:58.020 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:58.020 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:58.022 Event: 0x10b
Sun 3/5 11:37:58.022 VF processing job null nPlayin=true
Sun 3/5 11:37:58.022 isRec=false rd=7200000 base=0
Sun 3/5 11:37:58.023 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:58.439 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:58.439 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:58.439 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:58.440 Event: 0x10b
Sun 3/5 11:37:58.441 VF processing job null nPlayin=true
Sun 3/5 11:37:58.441 isRec=false rd=7200000 base=0
Sun 3/5 11:37:58.441 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:58.936 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:58.936 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:58.937 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:37:58.953 Event: 0x10b
Sun 3/5 11:37:58.953 VF processing job null nPlayin=true
Sun 3/5 11:37:58.954 isRec=false rd=7200000 base=0
Sun 3/5 11:37:58.954 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:37:59.313 VF.submitJob(VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null])
Sun 3/5 11:37:59.313 VF processing job VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null] nPlayin=true
Sun 3/5 11:37:59.313 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:37:59.314 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=10000 file=null ifn=null]
Sun 3/5 11:37:59.315 VideoFrame.timeSelected(Wed 12/31 21:58:53.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:37:59.316 VideoFrame.timeSelected2(Wed 12/31 21:58:53.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:37:59.316 Doing DVD Media Time Select Millis 5333000
Sun 3/5 11:37:59.578 Did DVD Media Time Select Millis 1
Sun 3/5 11:37:59.696 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:59.821 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:59.851 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:37:59.862 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:00.277 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:00.278 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:00.283 Event: 0x10b
Sun 3/5 11:38:00.284 Event: 0x10b
Sun 3/5 11:38:00.284 Event: 0x10b
Sun 3/5 11:38:00.284 Event: 0x10b
Sun 3/5 11:38:00.285 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:00.285 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:00.286 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:00.287 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:00.288 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:00.289 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:00.290 VF processing job null nPlayin=true
Sun 3/5 11:38:00.290 isRec=false rd=7200000 base=0
Sun 3/5 11:38:00.290 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:00.531 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:00.532 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:00.533 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:00.534 Event: 0x10b
Sun 3/5 11:38:00.534 VF processing job null nPlayin=true
Sun 3/5 11:38:00.535 isRec=false rd=7200000 base=0
Sun 3/5 11:38:00.535 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:00.732 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:38:00.733 VF.submitJob(VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null])
Sun 3/5 11:38:00.734 VF processing job VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:00.735 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null]
Sun 3/5 11:38:00.735 VideoFrame.timeSelected(Wed 12/31 22:01:24.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:38:00.736 VideoFrame.timeSelected2(Wed 12/31 22:01:24.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:38:00.736 Doing DVD Media Time Select Millis 5484000
Sun 3/5 11:38:00.849 Did DVD Media Time Select Millis 1
Sun 3/5 11:38:00.956 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:01.011 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:01.022 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:01.040 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:01.520 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:38:01.557 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:01.643 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:01.644 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:01.647 Event: 0x10b
Sun 3/5 11:38:01.647 Event: 0x10b
Sun 3/5 11:38:01.647 Event: 0x10b
Sun 3/5 11:38:01.648 Event: 0x10b
Sun 3/5 11:38:01.648 Event: 0x10b
Sun 3/5 11:38:01.648 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:01.649 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:01.650 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:01.651 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:01.652 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:01.652 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:01.653 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:01.654 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:01.655 VF processing job null nPlayin=true
Sun 3/5 11:38:01.655 isRec=false rd=7200000 base=0
Sun 3/5 11:38:01.656 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:01.952 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:38:01.953 VF.submitJob(VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null])
Sun 3/5 11:38:01.953 VF processing job VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:01.954 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null]
Sun 3/5 11:38:01.955 VideoFrame.timeSelected(Wed 12/31 22:03:55.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:38:01.955 VideoFrame.timeSelected2(Wed 12/31 22:03:55.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:38:01.955 Doing DVD Media Time Select Millis 5635000
Sun 3/5 11:38:02.180 Did DVD Media Time Select Millis 1
Sun 3/5 11:38:02.244 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:02.405 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:02.477 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:02.512 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:03.024 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:03.025 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:03.028 Event: 0x10b
Sun 3/5 11:38:03.029 Event: 0x10b
Sun 3/5 11:38:03.029 Event: 0x10b
Sun 3/5 11:38:03.029 Event: 0x10b
Sun 3/5 11:38:03.030 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:03.030 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:03.031 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:03.031 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:03.032 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:03.033 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:03.034 VF processing job null nPlayin=true
Sun 3/5 11:38:03.034 isRec=false rd=7200000 base=0
Sun 3/5 11:38:03.034 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:03.140 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:03.141 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:03.141 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:03.145 Event: 0x10b
Sun 3/5 11:38:03.146 VF processing job null nPlayin=true
Sun 3/5 11:38:03.146 isRec=false rd=7200000 base=0
Sun 3/5 11:38:03.146 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:03.566 VF.submitJob(VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null])
Sun 3/5 11:38:03.567 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:38:03.568 VF processing job VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:03.569 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null]
Sun 3/5 11:38:03.570 VideoFrame.timeSelected(Wed 12/31 22:06:26.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:38:03.581 VideoFrame.timeSelected2(Wed 12/31 22:06:26.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:38:03.581 Doing DVD Media Time Select Millis 5786000
Sun 3/5 11:38:03.885 Did DVD Media Time Select Millis 1
Sun 3/5 11:38:03.986 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:04.101 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:04.141 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:04.309 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:04.525 AWTThread Hang Detected - hang time = 750
Sun 3/5 11:38:04.836 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:04.836 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:04.839 Event: 0x10b
Sun 3/5 11:38:04.840 Event: 0x10b
Sun 3/5 11:38:04.840 Event: 0x10b
Sun 3/5 11:38:04.840 Event: 0x10b
Sun 3/5 11:38:04.840 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:04.841 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:04.846 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:04.846 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:04.847 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:04.847 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:04.848 VF processing job null nPlayin=true
Sun 3/5 11:38:04.848 isRec=false rd=7200000 base=0
Sun 3/5 11:38:04.848 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:04.998 VF.submitJob(VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null])
Sun 3/5 11:38:04.999 VF processing job VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:05.003 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null]
Sun 3/5 11:38:05.004 VideoFrame.timeSelected(Wed 12/31 22:08:57.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:38:05.004 VideoFrame.timeSelected2(Wed 12/31 22:08:57.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:38:05.005 Doing DVD Media Time Select Millis 5937000
Sun 3/5 11:38:05.039 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:38:05.079 Did DVD Media Time Select Millis 1
Sun 3/5 11:38:05.186 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:05.262 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:05.297 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:05.711 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:05.712 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:05.717 Event: 0x10b
Sun 3/5 11:38:05.717 Event: 0x10b
Sun 3/5 11:38:05.717 Event: 0x10b
Sun 3/5 11:38:05.718 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:05.718 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:05.720 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:05.720 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:05.721 VF processing job null nPlayin=true
Sun 3/5 11:38:05.721 isRec=false rd=7200000 base=0
Sun 3/5 11:38:05.722 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:06.011 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:06.011 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:06.012 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:06.017 Event: 0x10b
Sun 3/5 11:38:06.018 VF processing job null nPlayin=true
Sun 3/5 11:38:06.019 isRec=false rd=7200000 base=0
Sun 3/5 11:38:06.019 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:06.521 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:38:06.582 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:06.582 VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:06.583 VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 3/5 11:38:06.583 Event: 0x10b
Sun 3/5 11:38:06.584 VF processing job null nPlayin=true
Sun 3/5 11:38:06.584 isRec=false rd=7200000 base=0
Sun 3/5 11:38:06.584 VF thread is now waiting for 0:00:00.000
Sun 3/5 11:38:07.019 PM currState=3 wakeupTime=Wed 12/31/1969 20:30:00.000
Sun 3/5 11:38:07.020 VF.submitJob(VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null])
Sun 3/5 11:38:07.021 VF processing job VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null] nPlayin=true
Sun 3/5 11:38:07.022 VF processing on UI Thread VFJob[TimeAdjust r=0.0 t=150000 file=null ifn=null]
Sun 3/5 11:38:07.022 VideoFrame.timeSelected(Wed 12/31 22:11:28.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=]
Sun 3/5 11:38:07.023 VideoFrame.timeSelected2(Wed 12/31 22:10:44.000, true) currFile=MediaFile[id=112589 FA[BAD AIRING] host=a2000 encodedBy=] realDur=7200000
Sun 3/5 11:38:07.023 Doing DVD Media Time Select Millis 6044000
Sun 3/5 11:38:07.975 AWTThread Hang Detected - hang time = 750
Sun 3/5 11:38:07.981 Did DVD Media Time Select Millis 1
Sun 3/5 11:38:08.169 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:08.192 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:08.193 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:08.193 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:08.194 VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 3/5 11:38:08.725 AWTThread Hang Detected - hang time = 1500
Sun 3/5 11:38:09.475 AWTThread Hang Detected - hang time = 2250
Sun 3/5 11:38:10.225 AWTThread Hang Detected - hang time = 3000
Sun 3/5 11:38:10.975 AWTThread Hang Detected - hang time = 3750
Sun 3/5 11:38:11.522 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:38:11.725 AWTThread Hang Detected - hang time = 4500
Sun 3/5 11:38:12.475 AWTThread Hang Detected - hang time = 5250
Sun 3/5 11:38:13.225 AWTThread Hang Detected - hang time = 6000
Sun 3/5 11:38:13.975 AWTThread Hang Detected - hang time = 6750
Sun 3/5 11:38:14.728 AWTThread Hang Detected - hang time = 7503
Sun 3/5 11:38:15.477 AWTThread Hang Detected - hang time = 8252
Sun 3/5 11:38:16.227 AWTThread Hang Detected - hang time = 9002
Sun 3/5 11:38:16.524 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:38:16.977 AWTThread Hang Detected - hang time = 9752
Sun 3/5 11:38:17.728 AWTThread Hang Detected - hang time = 10503
Sun 3/5 11:38:18.478 AWTThread Hang Detected - hang time = 11253
Sun 3/5 11:38:19.229 AWTThread Hang Detected - hang time = 12004
Sun 3/5 11:38:25.720 AWTThread Hang Detected - hang time = 18495
Sun 3/5 11:38:26.470 AWTThread Hang Detected - hang time = 19245
Sun 3/5 11:38:26.721 Received command of:NOOP from Socket[addr=/127.0.0.1,port=42024,localport=1058]
Sun 3/5 11:38:27.233 AWTThread Hang Detected - hang time = 20008
Sun 3/5 11:38:27.983 AWTThread Hang Detected - hang time = 20758
Sun 3/5 11:38:28.733 AWTThread Hang Detected - hang time = 21508
Sun 3/5 11:38:29.483 AWTThread Hang Detected - hang time = 22258
Sun 3/5 11:38:30.233 AWTThread Hang Detected - hang time = 23008
Sun 3/5 11:38:30.983 AWTThread Hang Detected - hang time = 23758
Sun 3/5 11:38:31.733 AWTThread Hang Detected - hang time = 24508